{"level":"warn","ts":"2026-10-15T14:08:56.148037Z","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-15T14:08:56.148146Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-15T14:08:56.148242Z","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-15T14:08:56.14826Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-10-15T14:08:56.148293Z","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-15T14:08:56.149071Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-10-15T14:08:56.149163Z","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":8,"max-cpu-available":8,"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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-15T14:08:56.154471Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.921608ms"} {"level":"info","ts":"2026-10-15T14:08:56.165054Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-10-15T14:08:56.165142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:08:56.165165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-10-15T14:08:56.165173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:08:56.165182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-10-15T14:08:56.165221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-10-15T14:08:56.172769Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:08:56.173997Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:08:56.175378Z","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-15T14:08:56.177532Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:08:56.177719Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:08:56.178002Z","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-15T14:08:56.180137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-10-15T14:08:56.18213Z","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-15T14:08:56.182173Z","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-15T14:08:56.182423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-10-15T14:08:56.183829Z","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-15T14:08:56.184049Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:08:56.184076Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:08:56.184165Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-10-15T14:08:56.184179Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-10-15T14:08:56.866104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:08:56.866176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:08:56.866229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-10-15T14:08:56.866252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:08:56.86627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-10-15T14:08:56.866287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-10-15T14:08:56.866307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-10-15T14:08:56.867666Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:08:56.868756Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:08:56.868756Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:08:56.868793Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:08:56.869201Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:08:56.869346Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:08:56.870128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:08:56.870257Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:08:56.870296Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:08:56.870574Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-10-15T14:08:56.871595Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:09:07.522063Z","caller":"traceutil/trace.go:171","msg":"trace[2043036928] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"127.600794ms","start":"2026-10-15T14:09:07.394424Z","end":"2026-10-15T14:09:07.522024Z","steps":["trace[2043036928] 'process raft request' (duration: 58.723114ms)","trace[2043036928] 'compare' (duration: 68.618646ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:09:07.522196Z","caller":"traceutil/trace.go:171","msg":"trace[1425764298] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"127.245147ms","start":"2026-10-15T14:09:07.39492Z","end":"2026-10-15T14:09:07.522166Z","steps":["trace[1425764298] 'process raft request' (duration: 126.993294ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:07.522206Z","caller":"traceutil/trace.go:171","msg":"trace[710918699] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"126.940993ms","start":"2026-10-15T14:09:07.395246Z","end":"2026-10-15T14:09:07.522187Z","steps":["trace[710918699] 'process raft request' (duration: 126.725359ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:07.522339Z","caller":"traceutil/trace.go:171","msg":"trace[9933673] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:371; }","duration":"124.911788ms","start":"2026-10-15T14:09:07.397407Z","end":"2026-10-15T14:09:07.522318Z","steps":["trace[9933673] 'read index received' (duration: 2.999152ms)","trace[9933673] 'applied index is now lower than readState.Index' (duration: 121.910836ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:09:07.522561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.065771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-15T14:09:07.522654Z","caller":"traceutil/trace.go:171","msg":"trace[2016667674] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:366; }","duration":"125.260265ms","start":"2026-10-15T14:09:07.397361Z","end":"2026-10-15T14:09:07.522622Z","steps":["trace[2016667674] 'agreement among raft nodes before linearized reading' (duration: 125.064811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:07.522776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.660163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-10-15T14:09:07.522841Z","caller":"traceutil/trace.go:171","msg":"trace[956421471] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:367; }","duration":"106.776625ms","start":"2026-10-15T14:09:07.416048Z","end":"2026-10-15T14:09:07.522824Z","steps":["trace[956421471] 'agreement among raft nodes before linearized reading' (duration: 106.622672ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:07.522823Z","caller":"traceutil/trace.go:171","msg":"trace[2102770630] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"118.891985ms","start":"2026-10-15T14:09:07.403864Z","end":"2026-10-15T14:09:07.522756Z","steps":["trace[2102770630] 'process raft request' (duration: 118.176682ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:28.045269Z","caller":"traceutil/trace.go:171","msg":"trace[1519827679] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"117.586065ms","start":"2026-10-15T14:09:27.927647Z","end":"2026-10-15T14:09:28.045233Z","steps":["trace[1519827679] 'process raft request' (duration: 117.393292ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:48.040174Z","caller":"traceutil/trace.go:171","msg":"trace[697130487] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"150.091694ms","start":"2026-10-15T14:09:47.890055Z","end":"2026-10-15T14:09:48.040147Z","steps":["trace[697130487] 'process raft request' (duration: 144.987743ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:48.044585Z","caller":"traceutil/trace.go:171","msg":"trace[1324763085] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"120.841469ms","start":"2026-10-15T14:09:47.923712Z","end":"2026-10-15T14:09:48.044553Z","steps":["trace[1324763085] 'process raft request' (duration: 120.758167ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:09:48.044762Z","caller":"traceutil/trace.go:171","msg":"trace[820830948] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"137.388891ms","start":"2026-10-15T14:09:47.907349Z","end":"2026-10-15T14:09:48.044738Z","steps":["trace[820830948] 'process raft request' (duration: 136.915534ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:09:53.834454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.752469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:09:53.834536Z","caller":"traceutil/trace.go:171","msg":"trace[402963012] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"147.701725ms","start":"2026-10-15T14:09:53.686821Z","end":"2026-10-15T14:09:53.834522Z","steps":["trace[402963012] 'process raft request' (duration: 35.54695ms)","trace[402963012] 'compare' (duration: 111.643047ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:10:10.700469Z","caller":"traceutil/trace.go:171","msg":"trace[1772732298] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:858; }","duration":"188.948671ms","start":"2026-10-15T14:10:10.511498Z","end":"2026-10-15T14:10:10.700447Z","steps":["trace[1772732298] 'read index received' (duration: 188.711587ms)","trace[1772732298] 'applied index is now lower than readState.Index' (duration: 235.764µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:10:10.700664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.726295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:10:10.700756Z","caller":"traceutil/trace.go:171","msg":"trace[700204761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"106.871367ms","start":"2026-10-15T14:10:10.593873Z","end":"2026-10-15T14:10:10.700745Z","steps":["trace[700204761] 'agreement among raft nodes before linearized reading' (duration: 106.706634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:10.700873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.381107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-15T14:10:10.700929Z","caller":"traceutil/trace.go:171","msg":"trace[309355583] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:834; }","duration":"189.478779ms","start":"2026-10-15T14:10:10.511438Z","end":"2026-10-15T14:10:10.700917Z","steps":["trace[309355583] 'agreement among raft nodes before linearized reading' (duration: 189.136064ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:10:10.701115Z","caller":"traceutil/trace.go:171","msg":"trace[1164343823] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"196.919964ms","start":"2026-10-15T14:10:10.504184Z","end":"2026-10-15T14:10:10.701104Z","steps":["trace[1164343823] 'process raft request' (duration: 196.094582ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:10:10.828059Z","caller":"traceutil/trace.go:171","msg":"trace[1122945048] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"121.088955ms","start":"2026-10-15T14:10:10.706938Z","end":"2026-10-15T14:10:10.828027Z","steps":["trace[1122945048] 'process raft request' (duration: 120.939833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:10:10.828232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.025905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:10:10.828056Z","caller":"traceutil/trace.go:171","msg":"trace[2111072189] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"124.832223ms","start":"2026-10-15T14:10:10.703183Z","end":"2026-10-15T14:10:10.828015Z","steps":["trace[2111072189] 'read index received' (duration: 97.109266ms)","trace[2111072189] 'applied index is now lower than readState.Index' (duration: 27.719107ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:10:10.828291Z","caller":"traceutil/trace.go:171","msg":"trace[1589725090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"125.103807ms","start":"2026-10-15T14:10:10.70317Z","end":"2026-10-15T14:10:10.828274Z","steps":["trace[1589725090] 'agreement among raft nodes before linearized reading' (duration: 124.960885ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:33.423407Z","caller":"traceutil/trace.go:171","msg":"trace[1811205779] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"126.189872ms","start":"2026-10-15T14:11:33.297187Z","end":"2026-10-15T14:11:33.423377Z","steps":["trace[1811205779] 'process raft request' (duration: 64.072481ms)","trace[1811205779] 'compare' (duration: 62.001657ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:11:33.9846Z","caller":"traceutil/trace.go:171","msg":"trace[1094325015] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"392.148877ms","start":"2026-10-15T14:11:33.592419Z","end":"2026-10-15T14:11:33.984568Z","steps":["trace[1094325015] 'read index received' (duration: 351.755268ms)","trace[1094325015] 'applied index is now lower than readState.Index' (duration: 40.392089ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:11:33.984751Z","caller":"traceutil/trace.go:171","msg":"trace[381234705] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"518.389791ms","start":"2026-10-15T14:11:33.466329Z","end":"2026-10-15T14:11:33.984719Z","steps":["trace[381234705] 'process raft request' (duration: 477.834137ms)","trace[381234705] 'compare' (duration: 40.222394ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:11:33.984778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.345293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:11:33.984892Z","caller":"traceutil/trace.go:171","msg":"trace[1788124704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1918; }","duration":"392.513968ms","start":"2026-10-15T14:11:33.592361Z","end":"2026-10-15T14:11:33.984875Z","steps":["trace[1788124704] 'agreement among raft nodes before linearized reading' (duration: 392.356033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:11:33.984958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:11:33.592337Z","time spent":"392.60632ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T14:11:33.985157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:11:33.466309Z","time spent":"518.524465ms","remote":"127.0.0.1:41390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:11:34.017098Z","caller":"traceutil/trace.go:171","msg":"trace[1923007817] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"300.590854ms","start":"2026-10-15T14:11:33.716478Z","end":"2026-10-15T14:11:34.017069Z","steps":["trace[1923007817] 'process raft request' (duration: 300.334266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:11:34.01718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.111843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-15T14:11:34.017234Z","caller":"traceutil/trace.go:171","msg":"trace[1024685853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1919; }","duration":"284.236749ms","start":"2026-10-15T14:11:33.732982Z","end":"2026-10-15T14:11:34.017219Z","steps":["trace[1024685853] 'agreement among raft nodes before linearized reading' (duration: 284.024072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:11:34.017256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:11:33.716446Z","time spent":"300.724418ms","remote":"127.0.0.1:41390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:11:34.017365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.699693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-15T14:11:34.017389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.090376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f021b9e7-38e4-41f5-a346-afb765bf73c7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-15T14:11:34.017469Z","caller":"traceutil/trace.go:171","msg":"trace[821613201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1919; }","duration":"340.850919ms","start":"2026-10-15T14:11:33.67658Z","end":"2026-10-15T14:11:34.017431Z","steps":["trace[821613201] 'agreement among raft nodes before linearized reading' (duration: 340.543309ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:34.017499Z","caller":"traceutil/trace.go:171","msg":"trace[1878087827] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f021b9e7-38e4-41f5-a346-afb765bf73c7; range_end:; response_count:1; response_revision:1919; }","duration":"298.213071ms","start":"2026-10-15T14:11:33.719244Z","end":"2026-10-15T14:11:34.017457Z","steps":["trace[1878087827] 'agreement among raft nodes before linearized reading' (duration: 297.994844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:11:34.017515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:11:33.676519Z","time spent":"340.989404ms","remote":"127.0.0.1:41390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T14:11:34.017725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.906495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f021b9e7-38e4-41f5-a346-afb765bf73c7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-15T14:11:34.018054Z","caller":"traceutil/trace.go:171","msg":"trace[514321553] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f021b9e7-38e4-41f5-a346-afb765bf73c7; range_end:; response_count:1; response_revision:1919; }","duration":"107.41933ms","start":"2026-10-15T14:11:33.910609Z","end":"2026-10-15T14:11:34.018028Z","steps":["trace[514321553] 'agreement among raft nodes before linearized reading' (duration: 106.595435ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:34.182505Z","caller":"traceutil/trace.go:171","msg":"trace[997731250] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"160.308639ms","start":"2026-10-15T14:11:34.022162Z","end":"2026-10-15T14:11:34.182471Z","steps":["trace[997731250] 'process raft request' (duration: 160.078832ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:34.182621Z","caller":"traceutil/trace.go:171","msg":"trace[929595138] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"159.484283ms","start":"2026-10-15T14:11:34.023114Z","end":"2026-10-15T14:11:34.182598Z","steps":["trace[929595138] 'process raft request' (duration: 159.266267ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:11:34.182809Z","caller":"traceutil/trace.go:171","msg":"trace[901479469] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1990; }","duration":"127.953241ms","start":"2026-10-15T14:11:34.05484Z","end":"2026-10-15T14:11:34.182793Z","steps":["trace[901479469] 'read index received' (duration: 127.328022ms)","trace[901479469] 'applied index is now lower than readState.Index' (duration: 623.899µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:11:34.182933Z","caller":"traceutil/trace.go:171","msg":"trace[898133897] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"156.020757ms","start":"2026-10-15T14:11:34.026891Z","end":"2026-10-15T14:11:34.182912Z","steps":["trace[898133897] 'process raft request' (duration: 155.539252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:11:34.183049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.338816ms","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-15T14:11:34.183105Z","caller":"traceutil/trace.go:171","msg":"trace[957715499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1923; }","duration":"131.467221ms","start":"2026-10-15T14:11:34.051622Z","end":"2026-10-15T14:11:34.183089Z","steps":["trace[957715499] 'agreement among raft nodes before linearized reading' (duration: 131.261384ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:12:08.361543Z","caller":"traceutil/trace.go:171","msg":"trace[622015531] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"133.986877ms","start":"2026-10-15T14:12:08.22747Z","end":"2026-10-15T14:12:08.361457Z","steps":["trace[622015531] 'process raft request' (duration: 133.686249ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:12:08.39508Z","caller":"traceutil/trace.go:171","msg":"trace[1046785124] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"140.011202ms","start":"2026-10-15T14:12:08.255037Z","end":"2026-10-15T14:12:08.395049Z","steps":["trace[1046785124] 'process raft request' (duration: 112.874057ms)","trace[1046785124] 'compare' (duration: 26.919729ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:13:14.987456Z","caller":"traceutil/trace.go:171","msg":"trace[254397699] transaction","detail":"{read_only:false; response_revision:2626; number_of_response:1; }","duration":"169.860865ms","start":"2026-10-15T14:13:14.817555Z","end":"2026-10-15T14:13:14.987416Z","steps":["trace[254397699] 'process raft request' (duration: 169.65625ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:13:14.989744Z","caller":"traceutil/trace.go:171","msg":"trace[1661990193] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"169.18493ms","start":"2026-10-15T14:13:14.820506Z","end":"2026-10-15T14:13:14.989691Z","steps":["trace[1661990193] 'process raft request' (duration: 169.019796ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:13:46.429675Z","caller":"traceutil/trace.go:171","msg":"trace[1219339102] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"221.08256ms","start":"2026-10-15T14:13:46.208551Z","end":"2026-10-15T14:13:46.429634Z","steps":["trace[1219339102] 'process raft request' (duration: 148.835134ms)","trace[1219339102] 'compare' (duration: 72.094782ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:13:51.788927Z","caller":"traceutil/trace.go:171","msg":"trace[636526656] linearizableReadLoop","detail":"{readStateIndex:2930; appliedIndex:2929; }","duration":"137.194497ms","start":"2026-10-15T14:13:51.651696Z","end":"2026-10-15T14:13:51.788891Z","steps":["trace[636526656] 'read index received' (duration: 136.991693ms)","trace[636526656] 'applied index is now lower than readState.Index' (duration: 201.504µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:13:51.789035Z","caller":"traceutil/trace.go:171","msg":"trace[157386773] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"178.040699ms","start":"2026-10-15T14:13:51.610943Z","end":"2026-10-15T14:13:51.788983Z","steps":["trace[157386773] 'process raft request' (duration: 177.744083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:13:51.789429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.702958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T14:13:51.789499Z","caller":"traceutil/trace.go:171","msg":"trace[1808005707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2829; }","duration":"137.856711ms","start":"2026-10-15T14:13:51.651622Z","end":"2026-10-15T14:13:51.789478Z","steps":["trace[1808005707] 'agreement among raft nodes before linearized reading' (duration: 137.404462ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:46.663854Z","caller":"traceutil/trace.go:171","msg":"trace[1309927264] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"240.404225ms","start":"2026-10-15T14:18:46.423415Z","end":"2026-10-15T14:18:46.663819Z","steps":["trace[1309927264] 'process raft request' (duration: 240.086979ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:47.141134Z","caller":"traceutil/trace.go:171","msg":"trace[1667375841] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"146.134801ms","start":"2026-10-15T14:18:46.994965Z","end":"2026-10-15T14:18:47.141099Z","steps":["trace[1667375841] 'process raft request' (duration: 145.869986ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:47.170696Z","caller":"traceutil/trace.go:171","msg":"trace[670555735] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5317; }","duration":"121.212269ms","start":"2026-10-15T14:18:47.049453Z","end":"2026-10-15T14:18:47.170665Z","steps":["trace[670555735] 'read index received' (duration: 91.401955ms)","trace[670555735] 'applied index is now lower than readState.Index' (duration: 29.808904ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:18:47.170937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.465103ms","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-15T14:18:47.171099Z","caller":"traceutil/trace.go:171","msg":"trace[682688603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5133; }","duration":"121.684338ms","start":"2026-10-15T14:18:47.049401Z","end":"2026-10-15T14:18:47.171085Z","steps":["trace[682688603] 'agreement among raft nodes before linearized reading' (duration: 121.372212ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:47.171065Z","caller":"traceutil/trace.go:171","msg":"trace[1861555868] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"131.122627ms","start":"2026-10-15T14:18:47.039915Z","end":"2026-10-15T14:18:47.171038Z","steps":["trace[1861555868] 'process raft request' (duration: 130.589278ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:56.906786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-10-15T14:18:56.973574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"65.600127ms","hash":1531909866,"current-db-size-bytes":50958336,"current-db-size":"51 MB","current-db-size-in-use-bytes":27160576,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T14:18:56.973645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1531909866,"revision":2855,"compact-revision":-1} {"level":"info","ts":"2026-10-15T14:18:59.469689Z","caller":"traceutil/trace.go:171","msg":"trace[1104454862] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"196.186508ms","start":"2026-10-15T14:18:59.273475Z","end":"2026-10-15T14:18:59.469662Z","steps":["trace[1104454862] 'process raft request' (duration: 196.040436ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:59.495186Z","caller":"traceutil/trace.go:171","msg":"trace[1464188326] linearizableReadLoop","detail":"{readStateIndex:5483; appliedIndex:5482; }","duration":"153.441516ms","start":"2026-10-15T14:18:59.341723Z","end":"2026-10-15T14:18:59.495165Z","steps":["trace[1464188326] 'read index received' (duration: 128.561837ms)","trace[1464188326] 'applied index is now lower than readState.Index' (duration: 24.879029ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:59.495369Z","caller":"traceutil/trace.go:171","msg":"trace[855492263] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"193.511913ms","start":"2026-10-15T14:18:59.301847Z","end":"2026-10-15T14:18:59.495359Z","steps":["trace[855492263] 'process raft request' (duration: 183.625287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:59.495561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.835753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T14:18:59.495581Z","caller":"traceutil/trace.go:171","msg":"trace[721003261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5290; }","duration":"153.915715ms","start":"2026-10-15T14:18:59.341659Z","end":"2026-10-15T14:18:59.495575Z","steps":["trace[721003261] 'agreement among raft nodes before linearized reading' (duration: 153.819394ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:59.627559Z","caller":"traceutil/trace.go:171","msg":"trace[318951635] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"121.360617ms","start":"2026-10-15T14:18:59.506143Z","end":"2026-10-15T14:18:59.627503Z","steps":["trace[318951635] 'process raft request' (duration: 101.587224ms)","trace[318951635] 'compare' (duration: 19.420786ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:59.749116Z","caller":"traceutil/trace.go:171","msg":"trace[2004654847] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"111.849106ms","start":"2026-10-15T14:18:59.637238Z","end":"2026-10-15T14:18:59.749087Z","steps":["trace[2004654847] 'process raft request' (duration: 107.98392ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:59.777699Z","caller":"traceutil/trace.go:171","msg":"trace[302428413] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"139.919129ms","start":"2026-10-15T14:18:59.637748Z","end":"2026-10-15T14:18:59.777668Z","steps":["trace[302428413] 'process raft request' (duration: 139.726086ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:59.778228Z","caller":"traceutil/trace.go:171","msg":"trace[687146036] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"140.635531ms","start":"2026-10-15T14:18:59.637565Z","end":"2026-10-15T14:18:59.778201Z","steps":["trace[687146036] 'process raft request' (duration: 140.065391ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:59.77839Z","caller":"traceutil/trace.go:171","msg":"trace[924793471] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"140.478078ms","start":"2026-10-15T14:18:59.637902Z","end":"2026-10-15T14:18:59.77838Z","steps":["trace[924793471] 'process raft request' (duration: 140.040831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:10.673199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.577201ms","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-15T14:23:10.673256Z","caller":"traceutil/trace.go:171","msg":"trace[950166094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7122; }","duration":"192.645363ms","start":"2026-10-15T14:23:10.480599Z","end":"2026-10-15T14:23:10.673244Z","steps":["trace[950166094] 'range keys from in-memory index tree' (duration: 192.450358ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:10.673466Z","caller":"traceutil/trace.go:171","msg":"trace[1452489476] linearizableReadLoop","detail":"{readStateIndex:7377; appliedIndex:7376; }","duration":"118.509748ms","start":"2026-10-15T14:23:10.554946Z","end":"2026-10-15T14:23:10.673456Z","steps":["trace[1452489476] 'read index received' (duration: 38.012853ms)","trace[1452489476] 'applied index is now lower than readState.Index' (duration: 80.496235ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:10.6737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.750614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-15T14:23:10.673747Z","caller":"traceutil/trace.go:171","msg":"trace[849937397] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:7123; }","duration":"118.822505ms","start":"2026-10-15T14:23:10.554917Z","end":"2026-10-15T14:23:10.673739Z","steps":["trace[849937397] 'agreement among raft nodes before linearized reading' (duration: 118.709933ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:10.673867Z","caller":"traceutil/trace.go:171","msg":"trace[762985772] transaction","detail":"{read_only:false; response_revision:7123; number_of_response:1; }","duration":"127.054988ms","start":"2026-10-15T14:23:10.546786Z","end":"2026-10-15T14:23:10.673841Z","steps":["trace[762985772] 'process raft request' (duration: 46.244755ms)","trace[762985772] 'compare' (duration: 80.077146ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:56.91709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5275} {"level":"info","ts":"2026-10-15T14:23:57.001685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5275,"took":"82.583421ms","hash":4221335951,"current-db-size-bytes":50958336,"current-db-size":"51 MB","current-db-size-in-use-bytes":18804736,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-15T14:23:57.001785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4221335951,"revision":5275,"compact-revision":2855}