{"level":"warn","ts":"2026-04-02T22:31:22.283485Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T22:31:22.283566Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-04-02T22:31:22.283634Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T22:31:22.283648Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-02T22:31:22.283678Z","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-04-02T22:31:22.284309Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-04-02T22:31:22.284384Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-04-02T22:31:22.287768Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.189712ms"} {"level":"info","ts":"2026-04-02T22:31:22.295918Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-04-02T22:31:22.296077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T22:31:22.296126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-04-02T22:31:22.296154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T22:31:22.296178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-04-02T22:31:22.296258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-04-02T22:31:22.304996Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T22:31:22.306504Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T22:31:22.308087Z","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-04-02T22:31:22.310556Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T22:31:22.310712Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T22:31:22.310938Z","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-04-02T22:31:22.310996Z","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-04-02T22:31:22.311006Z","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-04-02T22:31:22.311723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-04-02T22:31:22.311829Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-02T22:31:22.318112Z","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-04-02T22:31:22.318296Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-02T22:31:22.318358Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-02T22:31:22.31867Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T22:31:22.318729Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T22:31:22.797339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T22:31:22.797422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T22:31:22.797472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-04-02T22:31:22.797494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-04-02T22:31:22.797514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-02T22:31:22.797543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-04-02T22:31:22.797557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-02T22:31:22.798583Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:31:22.799374Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:31:22.799368Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T22:31:22.799449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:31:22.799762Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T22:31:22.799812Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T22:31:22.800126Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:31:22.800344Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:31:22.800392Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:31:22.803054Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T22:31:22.803353Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"warn","ts":"2026-04-02T22:31:42.241439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.582465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:31:42.241586Z","caller":"traceutil/trace.go:171","msg":"trace[257218058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:450; }","duration":"163.78391ms","start":"2026-04-02T22:31:42.077781Z","end":"2026-04-02T22:31:42.241565Z","steps":["trace[257218058] 'range keys from in-memory index tree' (duration: 163.495892ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:42.54845Z","caller":"traceutil/trace.go:171","msg":"trace[588119613] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"234.680462ms","start":"2026-04-02T22:31:42.313747Z","end":"2026-04-02T22:31:42.548427Z","steps":["trace[588119613] 'process raft request' (duration: 165.955572ms)","trace[588119613] 'compare' (duration: 68.644038ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:31:42.554792Z","caller":"traceutil/trace.go:171","msg":"trace[1326549902] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"239.271676ms","start":"2026-04-02T22:31:42.315367Z","end":"2026-04-02T22:31:42.554639Z","steps":["trace[1326549902] 'process raft request' (duration: 239.174483ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:00.500541Z","caller":"traceutil/trace.go:171","msg":"trace[1048436816] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"232.342094ms","start":"2026-04-02T22:32:00.26817Z","end":"2026-04-02T22:32:00.500512Z","steps":["trace[1048436816] 'process raft request' (duration: 230.523222ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:00.677906Z","caller":"traceutil/trace.go:171","msg":"trace[1712370240] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"160.617846ms","start":"2026-04-02T22:32:00.51726Z","end":"2026-04-02T22:32:00.677878Z","steps":["trace[1712370240] 'process raft request' (duration: 114.417772ms)","trace[1712370240] 'compare' (duration: 46.080041ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:00.821673Z","caller":"traceutil/trace.go:171","msg":"trace[1883843048] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"152.075912ms","start":"2026-04-02T22:32:00.669564Z","end":"2026-04-02T22:32:00.82164Z","steps":["trace[1883843048] 'read index received' (duration: 142.335456ms)","trace[1883843048] 'applied index is now lower than readState.Index' (duration: 9.739586ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:00.821769Z","caller":"traceutil/trace.go:171","msg":"trace[1799295706] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"258.850108ms","start":"2026-04-02T22:32:00.562885Z","end":"2026-04-02T22:32:00.821735Z","steps":["trace[1799295706] 'process raft request' (duration: 249.134582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:00.821928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.349531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T22:32:00.821961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.33787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T22:32:00.821991Z","caller":"traceutil/trace.go:171","msg":"trace[687918808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"152.459774ms","start":"2026-04-02T22:32:00.669515Z","end":"2026-04-02T22:32:00.821974Z","steps":["trace[687918808] 'agreement among raft nodes before linearized reading' (duration: 152.210167ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:00.822014Z","caller":"traceutil/trace.go:171","msg":"trace[1585494733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"152.448993ms","start":"2026-04-02T22:32:00.669535Z","end":"2026-04-02T22:32:00.821984Z","steps":["trace[1585494733] 'agreement among raft nodes before linearized reading' (duration: 152.237247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:01.373485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.448077ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T22:32:01.373571Z","caller":"traceutil/trace.go:171","msg":"trace[928063338] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"296.044435ms","start":"2026-04-02T22:32:01.077512Z","end":"2026-04-02T22:32:01.373557Z","steps":["trace[928063338] 'read index received' (duration: 94.369461ms)","trace[928063338] 'applied index is now lower than readState.Index' (duration: 201.673674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:01.37363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.109897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:32:01.373655Z","caller":"traceutil/trace.go:171","msg":"trace[1703063687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"296.167399ms","start":"2026-04-02T22:32:01.07748Z","end":"2026-04-02T22:32:01.373647Z","steps":["trace[1703063687] 'agreement among raft nodes before linearized reading' (duration: 296.112657ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:01.37691Z","caller":"traceutil/trace.go:171","msg":"trace[1265886582] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"152.157075ms","start":"2026-04-02T22:32:01.224715Z","end":"2026-04-02T22:32:01.376872Z","steps":["trace[1265886582] 'process raft request' (duration: 151.594849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:15.970327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.735182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T22:32:15.970424Z","caller":"traceutil/trace.go:171","msg":"trace[1514820785] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:782; }","duration":"235.878385ms","start":"2026-04-02T22:32:15.734528Z","end":"2026-04-02T22:32:15.970406Z","steps":["trace[1514820785] 'range keys from in-memory index tree' (duration: 235.546635ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:42.932892Z","caller":"traceutil/trace.go:171","msg":"trace[1422360564] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"118.362805ms","start":"2026-04-02T22:32:42.814495Z","end":"2026-04-02T22:32:42.932858Z","steps":["trace[1422360564] 'process raft request' (duration: 118.089747ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:00.412453Z","caller":"traceutil/trace.go:171","msg":"trace[258420338] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"112.446791ms","start":"2026-04-02T22:33:00.299966Z","end":"2026-04-02T22:33:00.412413Z","steps":["trace[258420338] 'process raft request' (duration: 57.283257ms)","trace[258420338] 'compare' (duration: 54.977869ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:28.22436Z","caller":"traceutil/trace.go:171","msg":"trace[996428617] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1897; }","duration":"322.11539ms","start":"2026-04-02T22:33:27.902209Z","end":"2026-04-02T22:33:28.224324Z","steps":["trace[996428617] 'read index received' (duration: 280.834962ms)","trace[996428617] 'applied index is now lower than readState.Index' (duration: 41.278148ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:28.224398Z","caller":"traceutil/trace.go:171","msg":"trace[1779722432] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"344.785191ms","start":"2026-04-02T22:33:27.879573Z","end":"2026-04-02T22:33:28.224358Z","steps":["trace[1779722432] 'process raft request' (duration: 303.523223ms)","trace[1779722432] 'compare' (duration: 40.937649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:33:28.224647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.416667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3e2015-2b31-4ead-84ef-82e5d72c802a\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-02T22:33:28.224712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.073547ms","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":"warn","ts":"2026-04-02T22:33:28.224728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.017765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:28.224787Z","caller":"traceutil/trace.go:171","msg":"trace[26833984] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1833; }","duration":"277.196ms","start":"2026-04-02T22:33:27.947574Z","end":"2026-04-02T22:33:28.22477Z","steps":["trace[26833984] 'agreement among raft nodes before linearized reading' (duration: 276.960594ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:28.22473Z","caller":"traceutil/trace.go:171","msg":"trace[926934239] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8f3e2015-2b31-4ead-84ef-82e5d72c802a; range_end:; response_count:1; response_revision:1833; }","duration":"322.53035ms","start":"2026-04-02T22:33:27.902175Z","end":"2026-04-02T22:33:28.224705Z","steps":["trace[926934239] 'agreement among raft nodes before linearized reading' (duration: 322.329435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:28.225171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:27.879535Z","time spent":"344.973125ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:33:28.224964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:27.902158Z","time spent":"322.766837ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f3e2015-2b31-4ead-84ef-82e5d72c802a\" "} {"level":"info","ts":"2026-04-02T22:33:28.225586Z","caller":"traceutil/trace.go:171","msg":"trace[1830337689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1833; }","duration":"147.902838ms","start":"2026-04-02T22:33:28.077666Z","end":"2026-04-02T22:33:28.225569Z","steps":["trace[1830337689] 'agreement among raft nodes before linearized reading' (duration: 146.998374ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:11.861418Z","caller":"traceutil/trace.go:171","msg":"trace[1079994142] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"136.835965ms","start":"2026-04-02T22:34:11.724561Z","end":"2026-04-02T22:34:11.861397Z","steps":["trace[1079994142] 'process raft request' (duration: 136.698921ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:18.657537Z","caller":"traceutil/trace.go:171","msg":"trace[1629909147] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"164.597828ms","start":"2026-04-02T22:34:18.49291Z","end":"2026-04-02T22:34:18.657508Z","steps":["trace[1629909147] 'process raft request' (duration: 164.345131ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:38:26.931215Z","caller":"traceutil/trace.go:171","msg":"trace[1149906361] linearizableReadLoop","detail":"{readStateIndex:3851; appliedIndex:3850; }","duration":"113.993179ms","start":"2026-04-02T22:38:26.817132Z","end":"2026-04-02T22:38:26.931125Z","steps":["trace[1149906361] 'read index received' (duration: 113.871516ms)","trace[1149906361] 'applied index is now lower than readState.Index' (duration: 120.523µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:38:26.931367Z","caller":"traceutil/trace.go:171","msg":"trace[410591452] transaction","detail":"{read_only:false; response_revision:3717; number_of_response:1; }","duration":"116.890931ms","start":"2026-04-02T22:38:26.814465Z","end":"2026-04-02T22:38:26.931356Z","steps":["trace[410591452] 'process raft request' (duration: 116.535721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:38:26.93156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.417691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:6 size:46075"} {"level":"info","ts":"2026-04-02T22:38:26.931633Z","caller":"traceutil/trace.go:171","msg":"trace[1459075972] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:6; response_revision:3717; }","duration":"114.538164ms","start":"2026-04-02T22:38:26.81708Z","end":"2026-04-02T22:38:26.931618Z","steps":["trace[1459075972] 'agreement among raft nodes before linearized reading' (duration: 114.323798ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:38:27.086212Z","caller":"traceutil/trace.go:171","msg":"trace[104565549] transaction","detail":"{read_only:false; response_revision:3719; number_of_response:1; }","duration":"149.045043ms","start":"2026-04-02T22:38:26.93714Z","end":"2026-04-02T22:38:27.086185Z","steps":["trace[104565549] 'process raft request' (duration: 148.890828ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:38:27.086352Z","caller":"traceutil/trace.go:171","msg":"trace[991600547] transaction","detail":"{read_only:false; response_revision:3718; number_of_response:1; }","duration":"150.276678ms","start":"2026-04-02T22:38:26.936048Z","end":"2026-04-02T22:38:27.086324Z","steps":["trace[991600547] 'process raft request' (duration: 96.564966ms)","trace[991600547] 'compare' (duration: 52.687532ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:38:27.086512Z","caller":"traceutil/trace.go:171","msg":"trace[1939699416] transaction","detail":"{read_only:false; response_revision:3720; number_of_response:1; }","duration":"149.173037ms","start":"2026-04-02T22:38:26.937299Z","end":"2026-04-02T22:38:27.086472Z","steps":["trace[1939699416] 'process raft request' (duration: 148.841628ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:38:27.086671Z","caller":"traceutil/trace.go:171","msg":"trace[618382867] transaction","detail":"{read_only:false; response_revision:3721; number_of_response:1; }","duration":"149.148735ms","start":"2026-04-02T22:38:26.937476Z","end":"2026-04-02T22:38:27.086625Z","steps":["trace[618382867] 'process raft request' (duration: 148.854127ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:38:27.086709Z","caller":"traceutil/trace.go:171","msg":"trace[1252729750] transaction","detail":"{read_only:false; response_revision:3722; number_of_response:1; }","duration":"119.041733ms","start":"2026-04-02T22:38:26.967608Z","end":"2026-04-02T22:38:27.08665Z","steps":["trace[1252729750] 'process raft request' (duration: 118.860028ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:39:54.277415Z","caller":"traceutil/trace.go:171","msg":"trace[452507245] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"221.670986ms","start":"2026-04-02T22:39:54.055713Z","end":"2026-04-02T22:39:54.277384Z","steps":["trace[452507245] 'process raft request' (duration: 221.45482ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:04.970421Z","caller":"traceutil/trace.go:171","msg":"trace[2018236358] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"138.613842ms","start":"2026-04-02T22:40:04.831762Z","end":"2026-04-02T22:40:04.970376Z","steps":["trace[2018236358] 'process raft request' (duration: 138.238571ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:05.19889Z","caller":"traceutil/trace.go:171","msg":"trace[1726954670] linearizableReadLoop","detail":"{readStateIndex:5293; appliedIndex:5292; }","duration":"122.680755ms","start":"2026-04-02T22:40:05.07618Z","end":"2026-04-02T22:40:05.198861Z","steps":["trace[1726954670] 'read index received' (duration: 122.508699ms)","trace[1726954670] 'applied index is now lower than readState.Index' (duration: 170.176µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:40:05.199013Z","caller":"traceutil/trace.go:171","msg":"trace[1168383450] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"216.524783ms","start":"2026-04-02T22:40:04.982444Z","end":"2026-04-02T22:40:05.198969Z","steps":["trace[1168383450] 'process raft request' (duration: 216.200243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:05.199055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.854159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:40:05.199236Z","caller":"traceutil/trace.go:171","msg":"trace[801975711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5109; }","duration":"123.102347ms","start":"2026-04-02T22:40:05.076112Z","end":"2026-04-02T22:40:05.199215Z","steps":["trace[801975711] 'agreement among raft nodes before linearized reading' (duration: 122.86752ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:13.225768Z","caller":"traceutil/trace.go:171","msg":"trace[1065705543] linearizableReadLoop","detail":"{readStateIndex:5436; appliedIndex:5435; }","duration":"148.429486ms","start":"2026-04-02T22:40:13.077304Z","end":"2026-04-02T22:40:13.225734Z","steps":["trace[1065705543] 'read index received' (duration: 133.24083ms)","trace[1065705543] 'applied index is now lower than readState.Index' (duration: 15.186855ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:40:13.226114Z","caller":"traceutil/trace.go:171","msg":"trace[1684956720] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"175.934086ms","start":"2026-04-02T22:40:13.050132Z","end":"2026-04-02T22:40:13.226066Z","steps":["trace[1684956720] 'process raft request' (duration: 160.537074ms)","trace[1684956720] 'compare' (duration: 14.676362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:40:13.226166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.651131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:40:13.226226Z","caller":"traceutil/trace.go:171","msg":"trace[1825723647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5250; }","duration":"148.95701ms","start":"2026-04-02T22:40:13.077253Z","end":"2026-04-02T22:40:13.22621Z","steps":["trace[1825723647] 'agreement among raft nodes before linearized reading' (duration: 148.6141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:28.036527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.730372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:40:28.036682Z","caller":"traceutil/trace.go:171","msg":"trace[384957502] linearizableReadLoop","detail":"{readStateIndex:5646; appliedIndex:5645; }","duration":"341.209552ms","start":"2026-04-02T22:40:27.695453Z","end":"2026-04-02T22:40:28.036662Z","steps":["trace[384957502] 'read index received' (duration: 151.161862ms)","trace[384957502] 'applied index is now lower than readState.Index' (duration: 190.04544ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:40:28.036733Z","caller":"traceutil/trace.go:171","msg":"trace[437231346] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"342.046386ms","start":"2026-04-02T22:40:27.694633Z","end":"2026-04-02T22:40:28.03668Z","steps":["trace[437231346] 'process raft request' (duration: 152.065687ms)","trace[437231346] 'compare' (duration: 189.423073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:40:28.036928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:27.6946Z","time spent":"342.238961ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:40:28.036946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.48885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:64702"} {"level":"info","ts":"2026-04-02T22:40:28.036971Z","caller":"traceutil/trace.go:171","msg":"trace[29122917] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5455; }","duration":"341.544422ms","start":"2026-04-02T22:40:27.695419Z","end":"2026-04-02T22:40:28.036964Z","steps":["trace[29122917] 'agreement among raft nodes before linearized reading' (duration: 341.329876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:28.037007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:27.695411Z","time spent":"341.589764ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":64725,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-04-02T22:40:28.037181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.730465ms","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-04-02T22:40:28.03722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.771554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T22:40:28.037278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.752406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-04-02T22:40:28.03732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.730745ms","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-04-02T22:40:28.037399Z","caller":"traceutil/trace.go:171","msg":"trace[1450689426] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5455; }","duration":"287.903721ms","start":"2026-04-02T22:40:27.749461Z","end":"2026-04-02T22:40:28.037365Z","steps":["trace[1450689426] 'agreement among raft nodes before linearized reading' (duration: 287.539321ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:28.037502Z","caller":"traceutil/trace.go:171","msg":"trace[761435720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5455; }","duration":"292.90844ms","start":"2026-04-02T22:40:27.744507Z","end":"2026-04-02T22:40:28.037415Z","steps":["trace[761435720] 'agreement among raft nodes before linearized reading' (duration: 292.516619ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:28.037354Z","caller":"traceutil/trace.go:171","msg":"trace[405442070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5455; }","duration":"313.988601ms","start":"2026-04-02T22:40:27.723338Z","end":"2026-04-02T22:40:28.037326Z","steps":["trace[405442070] 'agreement among raft nodes before linearized reading' (duration: 313.657401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:40:28.037635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:40:27.723312Z","time spent":"314.301039ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T22:40:28.037271Z","caller":"traceutil/trace.go:171","msg":"trace[1796002324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5455; }","duration":"287.849529ms","start":"2026-04-02T22:40:27.749392Z","end":"2026-04-02T22:40:28.037242Z","steps":["trace[1796002324] 'agreement among raft nodes before linearized reading' (duration: 287.596782ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:40:28.199006Z","caller":"traceutil/trace.go:171","msg":"trace[1757485628] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"102.743057ms","start":"2026-04-02T22:40:28.096231Z","end":"2026-04-02T22:40:28.198974Z","steps":["trace[1757485628] 'process raft request' (duration: 46.255135ms)","trace[1757485628] 'compare' (duration: 56.305247ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:41:22.831047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2880} {"level":"info","ts":"2026-04-02T22:41:22.898484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2880,"took":"65.79342ms","hash":1394288462,"current-db-size-bytes":56672256,"current-db-size":"57 MB","current-db-size-in-use-bytes":32612352,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-02T22:41:22.898549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1394288462,"revision":2880,"compact-revision":-1} {"level":"info","ts":"2026-04-02T22:46:12.12775Z","caller":"traceutil/trace.go:171","msg":"trace[194199719] transaction","detail":"{read_only:false; response_revision:8083; number_of_response:1; }","duration":"331.961418ms","start":"2026-04-02T22:46:11.79577Z","end":"2026-04-02T22:46:12.127731Z","steps":["trace[194199719] 'process raft request' (duration: 331.844865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:46:12.127913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:46:11.795726Z","time spent":"332.081131ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:46:12.202535Z","caller":"traceutil/trace.go:171","msg":"trace[113697388] linearizableReadLoop","detail":"{readStateIndex:8356; appliedIndex:8355; }","duration":"338.995846ms","start":"2026-04-02T22:46:11.86351Z","end":"2026-04-02T22:46:12.202506Z","steps":["trace[113697388] 'read index received' (duration: 264.215279ms)","trace[113697388] 'applied index is now lower than readState.Index' (duration: 74.779417ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:46:12.202693Z","caller":"traceutil/trace.go:171","msg":"trace[467490224] transaction","detail":"{read_only:false; response_revision:8084; number_of_response:1; }","duration":"406.085857ms","start":"2026-04-02T22:46:11.796551Z","end":"2026-04-02T22:46:12.202636Z","steps":["trace[467490224] 'process raft request' (duration: 405.188691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:46:12.202819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.286314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T22:46:12.20287Z","caller":"traceutil/trace.go:171","msg":"trace[1191447916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8084; }","duration":"339.396137ms","start":"2026-04-02T22:46:11.863461Z","end":"2026-04-02T22:46:12.202857Z","steps":["trace[1191447916] 'agreement among raft nodes before linearized reading' (duration: 339.207251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:46:12.202914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:46:11.863439Z","time spent":"339.467249ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T22:46:12.202989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:46:11.796527Z","time spent":"406.306923ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:46:12.202993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.222319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:46:12.203016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.14689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-02T22:46:12.203092Z","caller":"traceutil/trace.go:171","msg":"trace[312694665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8084; }","duration":"125.356453ms","start":"2026-04-02T22:46:12.077712Z","end":"2026-04-02T22:46:12.203068Z","steps":["trace[312694665] 'agreement among raft nodes before linearized reading' (duration: 125.215269ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:46:12.203128Z","caller":"traceutil/trace.go:171","msg":"trace[520683551] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8084; }","duration":"339.296154ms","start":"2026-04-02T22:46:11.863804Z","end":"2026-04-02T22:46:12.2031Z","steps":["trace[520683551] 'agreement among raft nodes before linearized reading' (duration: 338.930344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:46:12.203202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:46:11.863797Z","time spent":"339.371967ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T22:46:12.20322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.244009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r546g\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-02T22:46:12.203258Z","caller":"traceutil/trace.go:171","msg":"trace[70167455] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r546g; range_end:; response_count:1; response_revision:8084; }","duration":"319.323072ms","start":"2026-04-02T22:46:11.883926Z","end":"2026-04-02T22:46:12.203249Z","steps":["trace[70167455] 'agreement among raft nodes before linearized reading' (duration: 319.217299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:46:12.203285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:46:11.8839Z","time spent":"319.378992ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-r546g\" "} {"level":"warn","ts":"2026-04-02T22:46:12.203268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.726367ms","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-04-02T22:46:12.203365Z","caller":"traceutil/trace.go:171","msg":"trace[1178126576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8084; }","duration":"339.879871ms","start":"2026-04-02T22:46:11.863461Z","end":"2026-04-02T22:46:12.203341Z","steps":["trace[1178126576] 'agreement among raft nodes before linearized reading' (duration: 339.573662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:46:12.20341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:46:11.863442Z","time spent":"339.959204ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T22:46:22.857691Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5917} {"level":"warn","ts":"2026-04-02T22:46:23.313334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.98759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:46:23.313485Z","caller":"traceutil/trace.go:171","msg":"trace[946384542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8143; }","duration":"237.245018ms","start":"2026-04-02T22:46:23.076208Z","end":"2026-04-02T22:46:23.313453Z","steps":["trace[946384542] 'range keys from in-memory index tree' (duration: 236.855347ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:46:23.53067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5917,"took":"670.365127ms","hash":1631104161,"current-db-size-bytes":56672256,"current-db-size":"57 MB","current-db-size-in-use-bytes":26255360,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-02T22:46:23.530783Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1631104161,"revision":5917,"compact-revision":2880} {"level":"warn","ts":"2026-04-02T22:46:24.503816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.746231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:46:24.504138Z","caller":"traceutil/trace.go:171","msg":"trace[1709914985] transaction","detail":"{read_only:false; response_revision:8151; number_of_response:1; }","duration":"160.666009ms","start":"2026-04-02T22:46:24.343431Z","end":"2026-04-02T22:46:24.504097Z","steps":["trace[1709914985] 'process raft request' (duration: 38.483235ms)","trace[1709914985] 'compare' (duration: 121.544926ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:46:25.298643Z","caller":"traceutil/trace.go:171","msg":"trace[243546852] transaction","detail":"{read_only:false; response_revision:8153; number_of_response:1; }","duration":"134.794789ms","start":"2026-04-02T22:46:25.163807Z","end":"2026-04-02T22:46:25.298602Z","steps":["trace[243546852] 'process raft request' (duration: 134.552963ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:46:52.312254Z","caller":"traceutil/trace.go:171","msg":"trace[1384215885] transaction","detail":"{read_only:false; response_revision:8349; number_of_response:1; }","duration":"101.985794ms","start":"2026-04-02T22:46:52.210238Z","end":"2026-04-02T22:46:52.312224Z","steps":["trace[1384215885] 'process raft request' (duration: 101.81904ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:46:52.500661Z","caller":"traceutil/trace.go:171","msg":"trace[1971550049] transaction","detail":"{read_only:false; response_revision:8351; number_of_response:1; }","duration":"140.289264ms","start":"2026-04-02T22:46:52.36035Z","end":"2026-04-02T22:46:52.500639Z","steps":["trace[1971550049] 'process raft request' (duration: 138.093373ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:06.663701Z","caller":"traceutil/trace.go:171","msg":"trace[792640394] linearizableReadLoop","detail":"{readStateIndex:8756; appliedIndex:8755; }","duration":"195.001537ms","start":"2026-04-02T22:47:06.468676Z","end":"2026-04-02T22:47:06.663677Z","steps":["trace[792640394] 'read index received' (duration: 194.690818ms)","trace[792640394] 'applied index is now lower than readState.Index' (duration: 309.589µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:47:06.663823Z","caller":"traceutil/trace.go:171","msg":"trace[316016869] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"270.231806ms","start":"2026-04-02T22:47:06.393581Z","end":"2026-04-02T22:47:06.663813Z","steps":["trace[316016869] 'process raft request' (duration: 269.896337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:47:06.664242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.304388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-02T22:47:06.664304Z","caller":"traceutil/trace.go:171","msg":"trace[1288542977] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8467; }","duration":"249.39138ms","start":"2026-04-02T22:47:06.414894Z","end":"2026-04-02T22:47:06.664285Z","steps":["trace[1288542977] 'agreement among raft nodes before linearized reading' (duration: 249.210165ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:06.879521Z","caller":"traceutil/trace.go:171","msg":"trace[381935048] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"208.680942ms","start":"2026-04-02T22:47:06.670561Z","end":"2026-04-02T22:47:06.879242Z","steps":["trace[381935048] 'process raft request' (duration: 200.210144ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:06.895721Z","caller":"traceutil/trace.go:171","msg":"trace[1622725070] linearizableReadLoop","detail":"{readStateIndex:8762; appliedIndex:8756; }","duration":"220.503946ms","start":"2026-04-02T22:47:06.675199Z","end":"2026-04-02T22:47:06.895703Z","steps":["trace[1622725070] 'read index received' (duration: 195.670685ms)","trace[1622725070] 'applied index is now lower than readState.Index' (duration: 24.832191ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:47:06.895888Z","caller":"traceutil/trace.go:171","msg":"trace[1032027404] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"225.089564ms","start":"2026-04-02T22:47:06.670786Z","end":"2026-04-02T22:47:06.895876Z","steps":["trace[1032027404] 'process raft request' (duration: 224.814997ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:06.896063Z","caller":"traceutil/trace.go:171","msg":"trace[89121323] transaction","detail":"{read_only:false; response_revision:8469; number_of_response:1; }","duration":"225.435874ms","start":"2026-04-02T22:47:06.670619Z","end":"2026-04-02T22:47:06.896055Z","steps":["trace[89121323] 'process raft request' (duration: 224.757025ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:06.896089Z","caller":"traceutil/trace.go:171","msg":"trace[1552061660] transaction","detail":"{read_only:false; response_revision:8470; number_of_response:1; }","duration":"225.378273ms","start":"2026-04-02T22:47:06.670706Z","end":"2026-04-02T22:47:06.896084Z","steps":["trace[1552061660] 'process raft request' (duration: 224.758126ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:06.896105Z","caller":"traceutil/trace.go:171","msg":"trace[1101279295] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"225.354322ms","start":"2026-04-02T22:47:06.670746Z","end":"2026-04-02T22:47:06.8961Z","steps":["trace[1101279295] 'process raft request' (duration: 224.767635ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:06.896252Z","caller":"traceutil/trace.go:171","msg":"trace[22901490] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"222.7962ms","start":"2026-04-02T22:47:06.673449Z","end":"2026-04-02T22:47:06.896246Z","steps":["trace[22901490] 'process raft request' (duration: 222.184413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:47:06.896324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.109243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:211645"} {"level":"info","ts":"2026-04-02T22:47:06.901751Z","caller":"traceutil/trace.go:171","msg":"trace[260236875] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8474; }","duration":"226.564276ms","start":"2026-04-02T22:47:06.675166Z","end":"2026-04-02T22:47:06.90173Z","steps":["trace[260236875] 'agreement among raft nodes before linearized reading' (duration: 220.786534ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:06.896372Z","caller":"traceutil/trace.go:171","msg":"trace[1800775391] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"128.120142ms","start":"2026-04-02T22:47:06.76824Z","end":"2026-04-02T22:47:06.89636Z","steps":["trace[1800775391] 'process raft request' (duration: 127.428363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:47:06.898276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.376828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T22:47:06.902767Z","caller":"traceutil/trace.go:171","msg":"trace[874500228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8474; }","duration":"188.904705ms","start":"2026-04-02T22:47:06.713848Z","end":"2026-04-02T22:47:06.902752Z","steps":["trace[874500228] 'agreement among raft nodes before linearized reading' (duration: 184.339437ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:07.790638Z","caller":"traceutil/trace.go:171","msg":"trace[198017143] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"213.372545ms","start":"2026-04-02T22:47:07.577233Z","end":"2026-04-02T22:47:07.790605Z","steps":["trace[198017143] 'process raft request' (duration: 213.172729ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:07.8427Z","caller":"traceutil/trace.go:171","msg":"trace[485399068] linearizableReadLoop","detail":"{readStateIndex:8770; appliedIndex:8768; }","duration":"145.45145ms","start":"2026-04-02T22:47:07.697223Z","end":"2026-04-02T22:47:07.842674Z","steps":["trace[485399068] 'read index received' (duration: 93.165566ms)","trace[485399068] 'applied index is now lower than readState.Index' (duration: 52.284924ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:47:07.842726Z","caller":"traceutil/trace.go:171","msg":"trace[1911003115] transaction","detail":"{read_only:false; response_revision:8481; number_of_response:1; }","duration":"147.845378ms","start":"2026-04-02T22:47:07.694863Z","end":"2026-04-02T22:47:07.842709Z","steps":["trace[1911003115] 'process raft request' (duration: 147.663503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:47:07.842819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.583583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:47:07.842848Z","caller":"traceutil/trace.go:171","msg":"trace[1987918447] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8481; }","duration":"145.661846ms","start":"2026-04-02T22:47:07.697177Z","end":"2026-04-02T22:47:07.842839Z","steps":["trace[1987918447] 'agreement among raft nodes before linearized reading' (duration: 145.583603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:47:07.843022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.843789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-02T22:47:07.843098Z","caller":"traceutil/trace.go:171","msg":"trace[1218719024] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8481; }","duration":"141.947191ms","start":"2026-04-02T22:47:07.701128Z","end":"2026-04-02T22:47:07.843075Z","steps":["trace[1218719024] 'agreement among raft nodes before linearized reading' (duration: 141.707374ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:08.347155Z","caller":"traceutil/trace.go:171","msg":"trace[866394455] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"117.195744ms","start":"2026-04-02T22:47:08.229932Z","end":"2026-04-02T22:47:08.347128Z","steps":["trace[866394455] 'process raft request' (duration: 116.814454ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:47:56.345028Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244d.wal"} {"level":"warn","ts":"2026-04-02T22:48:13.489882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.631942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:48:13.490062Z","caller":"traceutil/trace.go:171","msg":"trace[1752747499] transaction","detail":"{read_only:false; response_revision:9103; number_of_response:1; }","duration":"177.320037ms","start":"2026-04-02T22:48:13.312711Z","end":"2026-04-02T22:48:13.490031Z","steps":["trace[1752747499] 'process raft request' (duration: 49.408337ms)","trace[1752747499] 'compare' (duration: 127.424036ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:48:13.490043Z","caller":"traceutil/trace.go:171","msg":"trace[1867325579] linearizableReadLoop","detail":"{readStateIndex:9411; appliedIndex:9410; }","duration":"168.840187ms","start":"2026-04-02T22:48:13.321182Z","end":"2026-04-02T22:48:13.490022Z","steps":["trace[1867325579] 'read index received' (duration: 40.947308ms)","trace[1867325579] 'applied index is now lower than readState.Index' (duration: 127.890839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:48:13.4908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.599939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-02T22:48:13.490842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.505762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r546g\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-02T22:48:13.490934Z","caller":"traceutil/trace.go:171","msg":"trace[848943461] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r546g; range_end:; response_count:1; response_revision:9103; }","duration":"140.652627ms","start":"2026-04-02T22:48:13.350257Z","end":"2026-04-02T22:48:13.49091Z","steps":["trace[848943461] 'agreement among raft nodes before linearized reading' (duration: 140.365369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:48:13.490883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.479877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-02T22:48:13.490945Z","caller":"traceutil/trace.go:171","msg":"trace[2044966578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9103; }","duration":"169.756434ms","start":"2026-04-02T22:48:13.321118Z","end":"2026-04-02T22:48:13.490874Z","steps":["trace[2044966578] 'agreement among raft nodes before linearized reading' (duration: 169.375733ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:48:13.491054Z","caller":"traceutil/trace.go:171","msg":"trace[1055784951] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9103; }","duration":"153.671012ms","start":"2026-04-02T22:48:13.337316Z","end":"2026-04-02T22:48:13.490987Z","steps":["trace[1055784951] 'agreement among raft nodes before linearized reading' (duration: 153.316722ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:48:13.656321Z","caller":"traceutil/trace.go:171","msg":"trace[1348414249] transaction","detail":"{read_only:false; response_revision:9105; number_of_response:1; }","duration":"159.503805ms","start":"2026-04-02T22:48:13.496786Z","end":"2026-04-02T22:48:13.65629Z","steps":["trace[1348414249] 'process raft request' (duration: 159.425273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:48:13.656746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.791521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:244599"} {"level":"info","ts":"2026-04-02T22:48:13.656807Z","caller":"traceutil/trace.go:171","msg":"trace[255881796] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:9105; }","duration":"160.903835ms","start":"2026-04-02T22:48:13.495894Z","end":"2026-04-02T22:48:13.656798Z","steps":["trace[255881796] 'agreement among raft nodes before linearized reading' (duration: 160.483993ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:48:13.656977Z","caller":"traceutil/trace.go:171","msg":"trace[1180479357] transaction","detail":"{read_only:false; response_revision:9104; number_of_response:1; }","duration":"161.740618ms","start":"2026-04-02T22:48:13.495215Z","end":"2026-04-02T22:48:13.656956Z","steps":["trace[1180479357] 'process raft request' (duration: 108.387861ms)","trace[1180479357] 'compare' (duration: 52.439432ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:48:13.656329Z","caller":"traceutil/trace.go:171","msg":"trace[1587692992] linearizableReadLoop","detail":"{readStateIndex:9412; appliedIndex:9411; }","duration":"160.36653ms","start":"2026-04-02T22:48:13.495938Z","end":"2026-04-02T22:48:13.656305Z","steps":["trace[1587692992] 'read index received' (duration: 107.732223ms)","trace[1587692992] 'applied index is now lower than readState.Index' (duration: 52.621767ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:49:21.992851Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T22:49:21.998051Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T22:49:21.99812Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T22:51:22.863944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8142} {"level":"info","ts":"2026-04-02T22:51:22.940805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8142,"took":"74.468939ms","hash":32931385,"current-db-size-bytes":56672256,"current-db-size":"57 MB","current-db-size-in-use-bytes":24915968,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-02T22:51:22.940907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":32931385,"revision":8142,"compact-revision":5917} {"level":"info","ts":"2026-04-02T22:56:22.869489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10576} {"level":"info","ts":"2026-04-02T22:56:22.955967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10576,"took":"84.54907ms","hash":1552821156,"current-db-size-bytes":56672256,"current-db-size":"57 MB","current-db-size-in-use-bytes":20033536,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-02T22:56:22.95607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1552821156,"revision":10576,"compact-revision":8142}