{"level":"warn","ts":"2026-03-07T21:40:15.80627Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-07T21:40:15.806389Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-03-07T21:40:15.806458Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-07T21:40:15.806472Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-07T21:40:15.806489Z","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-03-07T21:40:15.807196Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-03-07T21:40:15.807291Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-03-07T21:40:15.816554Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.019485ms"} {"level":"info","ts":"2026-03-07T21:40:15.824903Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-03-07T21:40:15.825018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-07T21:40:15.825048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-03-07T21:40:15.825062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-07T21:40:15.825081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-03-07T21:40:15.825135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-03-07T21:40:15.834009Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-07T21:40:15.836415Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-07T21:40:15.839081Z","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-03-07T21:40:15.841357Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-07T21:40:15.841544Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-07T21:40:15.841687Z","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-03-07T21:40:15.841787Z","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-03-07T21:40:15.841806Z","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-03-07T21:40:15.842411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-03-07T21:40:15.845781Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-07T21:40:15.849089Z","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-03-07T21:40:15.849439Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-07T21:40:15.849552Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-07T21:40:15.84915Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-07T21:40:15.849647Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-07T21:40:15.926179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-07T21:40:15.926305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-07T21:40:15.926353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-03-07T21:40:15.926393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-03-07T21:40:15.926439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-07T21:40:15.926483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-03-07T21:40:15.926523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-07T21:40:15.927509Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T21:40:15.928856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-07T21:40:15.928859Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-07T21:40:15.928931Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-07T21:40:15.92919Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-07T21:40:15.929257Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-07T21:40:15.929371Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T21:40:15.929536Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T21:40:15.929612Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T21:40:15.932196Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-07T21:40:15.939673Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"warn","ts":"2026-03-07T21:40:32.094541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.765721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4379"} {"level":"info","ts":"2026-03-07T21:40:32.094671Z","caller":"traceutil/trace.go:171","msg":"trace[1176965549] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:369; }","duration":"137.917236ms","start":"2026-03-07T21:40:31.956732Z","end":"2026-03-07T21:40:32.094649Z","steps":["trace[1176965549] 'range keys from in-memory index tree' (duration: 137.625877ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:40:51.964559Z","caller":"traceutil/trace.go:171","msg":"trace[858115634] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"147.220068ms","start":"2026-03-07T21:40:51.817317Z","end":"2026-03-07T21:40:51.964537Z","steps":["trace[858115634] 'process raft request' (duration: 147.094634ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:40:52.732975Z","caller":"traceutil/trace.go:171","msg":"trace[1707852138] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:523; }","duration":"197.174085ms","start":"2026-03-07T21:40:52.535774Z","end":"2026-03-07T21:40:52.732948Z","steps":["trace[1707852138] 'read index received' (duration: 27.446975ms)","trace[1707852138] 'applied index is now lower than readState.Index' (duration: 169.72603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:40:52.73311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.308719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T21:40:52.73317Z","caller":"traceutil/trace.go:171","msg":"trace[422461226] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:509; }","duration":"197.433172ms","start":"2026-03-07T21:40:52.535722Z","end":"2026-03-07T21:40:52.733155Z","steps":["trace[422461226] 'agreement among raft nodes before linearized reading' (duration: 197.3287ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:40:52.733127Z","caller":"traceutil/trace.go:171","msg":"trace[1452886023] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"257.710928ms","start":"2026-03-07T21:40:52.475359Z","end":"2026-03-07T21:40:52.73307Z","steps":["trace[1452886023] 'process raft request' (duration: 257.42834ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:41:11.823887Z","caller":"traceutil/trace.go:171","msg":"trace[1722363244] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"278.718758ms","start":"2026-03-07T21:41:11.545139Z","end":"2026-03-07T21:41:11.823858Z","steps":["trace[1722363244] 'process raft request' (duration: 278.527313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:42:04.209797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.094027ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T21:42:04.209929Z","caller":"traceutil/trace.go:171","msg":"trace[358012137] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1561; }","duration":"113.240941ms","start":"2026-03-07T21:42:04.096669Z","end":"2026-03-07T21:42:04.20991Z","steps":["trace[358012137] 'range keys from in-memory index tree' (duration: 113.076296ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:42:04.21524Z","caller":"traceutil/trace.go:171","msg":"trace[21987482] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"209.767681ms","start":"2026-03-07T21:42:04.005424Z","end":"2026-03-07T21:42:04.215192Z","steps":["trace[21987482] 'process raft request' (duration: 209.519235ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:42:04.498913Z","caller":"traceutil/trace.go:171","msg":"trace[274164515] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"139.614341ms","start":"2026-03-07T21:42:04.359267Z","end":"2026-03-07T21:42:04.498882Z","steps":["trace[274164515] 'process raft request' (duration: 139.320313ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:42:04.630521Z","caller":"traceutil/trace.go:171","msg":"trace[679235983] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"215.388413ms","start":"2026-03-07T21:42:04.415081Z","end":"2026-03-07T21:42:04.630469Z","steps":["trace[679235983] 'process raft request' (duration: 140.452024ms)","trace[679235983] 'compare' (duration: 74.6213ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:42:04.703234Z","caller":"traceutil/trace.go:171","msg":"trace[835735558] linearizableReadLoop","detail":"{readStateIndex:1622; appliedIndex:1620; }","duration":"268.529024ms","start":"2026-03-07T21:42:04.434672Z","end":"2026-03-07T21:42:04.703201Z","steps":["trace[835735558] 'read index received' (duration: 63.909032ms)","trace[835735558] 'applied index is now lower than readState.Index' (duration: 204.618222ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:42:04.703589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.879784ms","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-03-07T21:42:04.703698Z","caller":"traceutil/trace.go:171","msg":"trace[1936863142] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1564; }","duration":"269.045868ms","start":"2026-03-07T21:42:04.434629Z","end":"2026-03-07T21:42:04.703675Z","steps":["trace[1936863142] 'agreement among raft nodes before linearized reading' (duration: 268.75325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:42:04.703588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.873413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-07T21:42:04.703725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.384119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-07T21:42:04.703802Z","caller":"traceutil/trace.go:171","msg":"trace[1182431549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1564; }","duration":"230.173331ms","start":"2026-03-07T21:42:04.473607Z","end":"2026-03-07T21:42:04.703781Z","steps":["trace[1182431549] 'agreement among raft nodes before linearized reading' (duration: 229.819181ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:42:04.703829Z","caller":"traceutil/trace.go:171","msg":"trace[1953724768] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1564; }","duration":"200.534563ms","start":"2026-03-07T21:42:04.503265Z","end":"2026-03-07T21:42:04.703799Z","steps":["trace[1953724768] 'agreement among raft nodes before linearized reading' (duration: 200.212985ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:42:18.323277Z","caller":"traceutil/trace.go:171","msg":"trace[1032462152] linearizableReadLoop","detail":"{readStateIndex:1728; appliedIndex:1727; }","duration":"114.799902ms","start":"2026-03-07T21:42:18.208455Z","end":"2026-03-07T21:42:18.323255Z","steps":["trace[1032462152] 'read index received' (duration: 114.679189ms)","trace[1032462152] 'applied index is now lower than readState.Index' (duration: 119.713µs)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:42:18.323394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.931316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T21:42:18.323425Z","caller":"traceutil/trace.go:171","msg":"trace[1232122227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1667; }","duration":"115.013449ms","start":"2026-03-07T21:42:18.208402Z","end":"2026-03-07T21:42:18.323415Z","steps":["trace[1232122227] 'agreement among raft nodes before linearized reading' (duration: 114.948067ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:42:18.323587Z","caller":"traceutil/trace.go:171","msg":"trace[556160918] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"157.579206ms","start":"2026-03-07T21:42:18.16599Z","end":"2026-03-07T21:42:18.32357Z","steps":["trace[556160918] 'process raft request' (duration: 157.126123ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:42:55.467099Z","caller":"traceutil/trace.go:171","msg":"trace[1810003179] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"116.08903ms","start":"2026-03-07T21:42:55.350976Z","end":"2026-03-07T21:42:55.467065Z","steps":["trace[1810003179] 'read index received' (duration: 100.849736ms)","trace[1810003179] 'applied index is now lower than readState.Index' (duration: 15.235914ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:42:55.467112Z","caller":"traceutil/trace.go:171","msg":"trace[617081658] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"163.523166ms","start":"2026-03-07T21:42:55.303532Z","end":"2026-03-07T21:42:55.467055Z","steps":["trace[617081658] 'process raft request' (duration: 148.299903ms)","trace[617081658] 'compare' (duration: 15.080909ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:42:55.467248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.270095ms","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-03-07T21:42:55.467277Z","caller":"traceutil/trace.go:171","msg":"trace[180221244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1867; }","duration":"116.346877ms","start":"2026-03-07T21:42:55.350922Z","end":"2026-03-07T21:42:55.467269Z","steps":["trace[180221244] 'agreement among raft nodes before linearized reading' (duration: 116.221524ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:43:00.956877Z","caller":"traceutil/trace.go:171","msg":"trace[1852073872] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"145.181119ms","start":"2026-03-07T21:43:00.811667Z","end":"2026-03-07T21:43:00.956849Z","steps":["trace[1852073872] 'process raft request' (duration: 144.987964ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:43:51.807886Z","caller":"traceutil/trace.go:171","msg":"trace[1561276949] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"210.749744ms","start":"2026-03-07T21:43:51.597111Z","end":"2026-03-07T21:43:51.807861Z","steps":["trace[1561276949] 'process raft request' (duration: 210.61504ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:43:51.813657Z","caller":"traceutil/trace.go:171","msg":"trace[1023359996] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"213.880058ms","start":"2026-03-07T21:43:51.59975Z","end":"2026-03-07T21:43:51.81363Z","steps":["trace[1023359996] 'process raft request' (duration: 213.677662ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:43:51.813809Z","caller":"traceutil/trace.go:171","msg":"trace[729617143] linearizableReadLoop","detail":"{readStateIndex:2398; appliedIndex:2396; }","duration":"197.600808ms","start":"2026-03-07T21:43:51.616181Z","end":"2026-03-07T21:43:51.813781Z","steps":["trace[729617143] 'read index received' (duration: 191.643207ms)","trace[729617143] 'applied index is now lower than readState.Index' (duration: 5.955651ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:43:51.81384Z","caller":"traceutil/trace.go:171","msg":"trace[135097080] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"154.382361ms","start":"2026-03-07T21:43:51.659421Z","end":"2026-03-07T21:43:51.813804Z","steps":["trace[135097080] 'process raft request' (duration: 154.158455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:43:51.814072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.872097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-07T21:43:51.814108Z","caller":"traceutil/trace.go:171","msg":"trace[920064052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2311; }","duration":"197.952978ms","start":"2026-03-07T21:43:51.616144Z","end":"2026-03-07T21:43:51.814097Z","steps":["trace[920064052] 'agreement among raft nodes before linearized reading' (duration: 197.788003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:44:24.244968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.925617ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-07T21:44:24.245057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.546304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T21:44:24.245103Z","caller":"traceutil/trace.go:171","msg":"trace[131394050] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2493; }","duration":"148.075031ms","start":"2026-03-07T21:44:24.09701Z","end":"2026-03-07T21:44:24.245085Z","steps":["trace[131394050] 'range keys from in-memory index tree' (duration: 147.909517ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:44:24.245155Z","caller":"traceutil/trace.go:171","msg":"trace[483042295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2493; }","duration":"211.685908ms","start":"2026-03-07T21:44:24.033446Z","end":"2026-03-07T21:44:24.245132Z","steps":["trace[483042295] 'range keys from in-memory index tree' (duration: 211.422452ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:46:50.329933Z","caller":"traceutil/trace.go:171","msg":"trace[1656056639] linearizableReadLoop","detail":"{readStateIndex:3521; appliedIndex:3520; }","duration":"123.874566ms","start":"2026-03-07T21:46:50.20602Z","end":"2026-03-07T21:46:50.329894Z","steps":["trace[1656056639] 'read index received' (duration: 123.544957ms)","trace[1656056639] 'applied index is now lower than readState.Index' (duration: 327.969µs)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:46:50.330331Z","caller":"traceutil/trace.go:171","msg":"trace[382582251] transaction","detail":"{read_only:false; response_revision:3389; number_of_response:1; }","duration":"129.874869ms","start":"2026-03-07T21:46:50.20044Z","end":"2026-03-07T21:46:50.330315Z","steps":["trace[382582251] 'process raft request' (duration: 129.238072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:46:50.330644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.605105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T21:46:50.33069Z","caller":"traceutil/trace.go:171","msg":"trace[1371930336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3389; }","duration":"124.713998ms","start":"2026-03-07T21:46:50.205965Z","end":"2026-03-07T21:46:50.330679Z","steps":["trace[1371930336] 'agreement among raft nodes before linearized reading' (duration: 124.615485ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:48:23.469272Z","caller":"traceutil/trace.go:171","msg":"trace[1779357611] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"108.118229ms","start":"2026-03-07T21:48:23.361119Z","end":"2026-03-07T21:48:23.469238Z","steps":["trace[1779357611] 'process raft request' (duration: 57.963804ms)","trace[1779357611] 'compare' (duration: 49.513058ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:48:32.025687Z","caller":"traceutil/trace.go:171","msg":"trace[1514916710] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"134.92698ms","start":"2026-03-07T21:48:31.890728Z","end":"2026-03-07T21:48:32.025655Z","steps":["trace[1514916710] 'process raft request' (duration: 111.406448ms)","trace[1514916710] 'compare' (duration: 23.337247ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:48:33.389597Z","caller":"traceutil/trace.go:171","msg":"trace[101381795] linearizableReadLoop","detail":"{readStateIndex:5079; appliedIndex:5078; }","duration":"102.662482ms","start":"2026-03-07T21:48:33.286912Z","end":"2026-03-07T21:48:33.389574Z","steps":["trace[101381795] 'read index received' (duration: 102.390425ms)","trace[101381795] 'applied index is now lower than readState.Index' (duration: 270.907µs)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:48:33.38968Z","caller":"traceutil/trace.go:171","msg":"trace[494943791] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"111.556672ms","start":"2026-03-07T21:48:33.278093Z","end":"2026-03-07T21:48:33.38965Z","steps":["trace[494943791] 'process raft request' (duration: 111.300014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:48:33.389942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.006553ms","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-03-07T21:48:33.390061Z","caller":"traceutil/trace.go:171","msg":"trace[2008467611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4896; }","duration":"103.194137ms","start":"2026-03-07T21:48:33.286839Z","end":"2026-03-07T21:48:33.390033Z","steps":["trace[2008467611] 'agreement among raft nodes before linearized reading' (duration: 102.870409ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:48:45.03457Z","caller":"traceutil/trace.go:171","msg":"trace[590987624] linearizableReadLoop","detail":"{readStateIndex:5241; appliedIndex:5239; }","duration":"110.553285ms","start":"2026-03-07T21:48:44.923961Z","end":"2026-03-07T21:48:45.034514Z","steps":["trace[590987624] 'read index received' (duration: 11.788556ms)","trace[590987624] 'applied index is now lower than readState.Index' (duration: 98.763149ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:48:45.03487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.878583ms","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-03-07T21:48:45.034969Z","caller":"traceutil/trace.go:171","msg":"trace[1028337173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5053; }","duration":"111.022287ms","start":"2026-03-07T21:48:44.923926Z","end":"2026-03-07T21:48:45.034948Z","steps":["trace[1028337173] 'agreement among raft nodes before linearized reading' (duration: 110.718269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:49:24.141211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810824623587730,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-07T21:49:24.391622Z","caller":"traceutil/trace.go:171","msg":"trace[1787004107] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"918.354711ms","start":"2026-03-07T21:49:23.473235Z","end":"2026-03-07T21:49:24.391589Z","steps":["trace[1787004107] 'process raft request' (duration: 918.135325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:49:24.392307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:23.473195Z","time spent":"918.519196ms","remote":"127.0.0.1:52598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T21:49:24.392738Z","caller":"traceutil/trace.go:171","msg":"trace[973499285] linearizableReadLoop","detail":"{readStateIndex:5574; appliedIndex:5572; }","duration":"752.108397ms","start":"2026-03-07T21:49:23.640605Z","end":"2026-03-07T21:49:24.392713Z","steps":["trace[973499285] 'read index received' (duration: 750.7671ms)","trace[973499285] 'applied index is now lower than readState.Index' (duration: 1.339867ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:49:24.392786Z","caller":"traceutil/trace.go:171","msg":"trace[794010340] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"335.968705ms","start":"2026-03-07T21:49:24.0568Z","end":"2026-03-07T21:49:24.392769Z","steps":["trace[794010340] 'process raft request' (duration: 335.750049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:49:24.392848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:24.05678Z","time spent":"336.036626ms","remote":"127.0.0.1:54024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T21:49:24.392941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.388546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-07T21:49:24.39293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.757227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-07T21:49:24.39299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.426416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T21:49:24.393004Z","caller":"traceutil/trace.go:171","msg":"trace[1666644735] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5376; }","duration":"340.480868ms","start":"2026-03-07T21:49:24.052519Z","end":"2026-03-07T21:49:24.393Z","steps":["trace[1666644735] 'agreement among raft nodes before linearized reading' (duration: 340.454307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:49:24.392988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.365474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-07T21:49:24.393029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:24.052508Z","time spent":"340.513728ms","remote":"127.0.0.1:53988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-03-07T21:49:24.393025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.020041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-03-07T21:49:24.393058Z","caller":"traceutil/trace.go:171","msg":"trace[1585069238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5376; }","duration":"752.501047ms","start":"2026-03-07T21:49:23.64054Z","end":"2026-03-07T21:49:24.393041Z","steps":["trace[1585069238] 'agreement among raft nodes before linearized reading' (duration: 752.270001ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:49:24.393075Z","caller":"traceutil/trace.go:171","msg":"trace[1959108354] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5376; }","duration":"338.171514ms","start":"2026-03-07T21:49:24.054893Z","end":"2026-03-07T21:49:24.393065Z","steps":["trace[1959108354] 'agreement among raft nodes before linearized reading' (duration: 338.01411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:49:24.393094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:23.640517Z","time spent":"752.570119ms","remote":"127.0.0.1:54024","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":"warn","ts":"2026-03-07T21:49:24.393104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:24.054859Z","time spent":"338.239146ms","remote":"127.0.0.1:53988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6180,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"info","ts":"2026-03-07T21:49:24.392999Z","caller":"traceutil/trace.go:171","msg":"trace[1484050613] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5376; }","duration":"339.842929ms","start":"2026-03-07T21:49:24.053135Z","end":"2026-03-07T21:49:24.392978Z","steps":["trace[1484050613] 'agreement among raft nodes before linearized reading' (duration: 339.712156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:49:24.393154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:24.053127Z","time spent":"340.013764ms","remote":"127.0.0.1:53988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-03-07T21:49:24.393163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.666484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lcczk\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-07T21:49:24.393271Z","caller":"traceutil/trace.go:171","msg":"trace[406348912] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lcczk; range_end:; response_count:1; response_revision:5376; }","duration":"603.821578ms","start":"2026-03-07T21:49:23.789423Z","end":"2026-03-07T21:49:24.393244Z","steps":["trace[406348912] 'agreement among raft nodes before linearized reading' (duration: 603.52321ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:49:24.392961Z","caller":"traceutil/trace.go:171","msg":"trace[1931851765] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5376; }","duration":"431.446367ms","start":"2026-03-07T21:49:23.961507Z","end":"2026-03-07T21:49:24.392953Z","steps":["trace[1931851765] 'agreement among raft nodes before linearized reading' (duration: 431.397186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:49:24.39332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:23.961453Z","time spent":"431.860139ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-03-07T21:49:24.393319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:23.789403Z","time spent":"603.905681ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-lcczk\" "} {"level":"warn","ts":"2026-03-07T21:49:24.392989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.151806ms","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-03-07T21:49:24.393381Z","caller":"traceutil/trace.go:171","msg":"trace[1245584812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5376; }","duration":"749.610988ms","start":"2026-03-07T21:49:23.643755Z","end":"2026-03-07T21:49:24.393366Z","steps":["trace[1245584812] 'agreement among raft nodes before linearized reading' (duration: 749.088914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:49:24.393434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:49:23.643734Z","time spent":"749.68572ms","remote":"127.0.0.1:54024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-07T21:49:24.393528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.981905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T21:49:24.393569Z","caller":"traceutil/trace.go:171","msg":"trace[1107212319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5376; }","duration":"118.082717ms","start":"2026-03-07T21:49:24.275476Z","end":"2026-03-07T21:49:24.393558Z","steps":["trace[1107212319] 'agreement among raft nodes before linearized reading' (duration: 117.423519ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:50:16.27893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2750} {"level":"info","ts":"2026-03-07T21:50:16.331315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2750,"took":"51.095715ms","hash":3941959076,"current-db-size-bytes":41521152,"current-db-size":"42 MB","current-db-size-in-use-bytes":31293440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-07T21:50:16.33139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3941959076,"revision":2750,"compact-revision":-1} {"level":"warn","ts":"2026-03-07T21:54:35.034395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.828707ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-07T21:54:35.034533Z","caller":"traceutil/trace.go:171","msg":"trace[1245082616] linearizableReadLoop","detail":"{readStateIndex:7808; appliedIndex:7807; }","duration":"179.523116ms","start":"2026-03-07T21:54:34.854988Z","end":"2026-03-07T21:54:35.034512Z","steps":["trace[1245082616] 'read index received' (duration: 41.461002ms)","trace[1245082616] 'applied index is now lower than readState.Index' (duration: 138.060384ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:54:35.034675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.681181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-07T21:54:35.034723Z","caller":"traceutil/trace.go:171","msg":"trace[1610452085] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:7535; }","duration":"179.780023ms","start":"2026-03-07T21:54:34.854929Z","end":"2026-03-07T21:54:35.034709Z","steps":["trace[1610452085] 'agreement among raft nodes before linearized reading' (duration: 179.66004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:54:35.03479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.920511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-07T21:54:35.034831Z","caller":"traceutil/trace.go:171","msg":"trace[175692161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7535; }","duration":"165.962102ms","start":"2026-03-07T21:54:34.868856Z","end":"2026-03-07T21:54:35.034818Z","steps":["trace[175692161] 'agreement among raft nodes before linearized reading' (duration: 165.773337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:54:35.034999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.016821ms","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-03-07T21:54:35.035084Z","caller":"traceutil/trace.go:171","msg":"trace[1393962316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7535; }","duration":"154.160635ms","start":"2026-03-07T21:54:34.880905Z","end":"2026-03-07T21:54:35.035066Z","steps":["trace[1393962316] 'agreement among raft nodes before linearized reading' (duration: 153.920909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:54:35.035087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.12996ms","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-03-07T21:54:35.035183Z","caller":"traceutil/trace.go:171","msg":"trace[1651334878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7535; }","duration":"165.277133ms","start":"2026-03-07T21:54:34.869883Z","end":"2026-03-07T21:54:35.03516Z","steps":["trace[1651334878] 'agreement among raft nodes before linearized reading' (duration: 164.960315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:54:46.705392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.214906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-07T21:54:46.705598Z","caller":"traceutil/trace.go:171","msg":"trace[415769171] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:7593; }","duration":"277.476113ms","start":"2026-03-07T21:54:46.428069Z","end":"2026-03-07T21:54:46.705545Z","steps":["trace[415769171] 'count revisions from in-memory index tree' (duration: 277.091393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:54:47.494108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.147303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-07T21:54:47.494204Z","caller":"traceutil/trace.go:171","msg":"trace[891979619] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:7596; }","duration":"186.328248ms","start":"2026-03-07T21:54:47.307855Z","end":"2026-03-07T21:54:47.494183Z","steps":["trace[891979619] 'count revisions from in-memory index tree' (duration: 186.029049ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:16.287851Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5754} {"level":"info","ts":"2026-03-07T21:55:16.383858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5754,"took":"93.92566ms","hash":922114128,"current-db-size-bytes":43991040,"current-db-size":"44 MB","current-db-size-in-use-bytes":23093248,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-07T21:55:16.384004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":922114128,"revision":5754,"compact-revision":2750} {"level":"info","ts":"2026-03-07T21:55:19.566939Z","caller":"traceutil/trace.go:171","msg":"trace[1042150221] transaction","detail":"{read_only:false; response_revision:7803; number_of_response:1; }","duration":"182.206347ms","start":"2026-03-07T21:55:19.384705Z","end":"2026-03-07T21:55:19.566911Z","steps":["trace[1042150221] 'process raft request' (duration: 182.072983ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:19.757526Z","caller":"traceutil/trace.go:171","msg":"trace[1386416589] linearizableReadLoop","detail":"{readStateIndex:8089; appliedIndex:8087; }","duration":"306.08113ms","start":"2026-03-07T21:55:19.451393Z","end":"2026-03-07T21:55:19.757474Z","steps":["trace[1386416589] 'read index received' (duration: 115.323934ms)","trace[1386416589] 'applied index is now lower than readState.Index' (duration: 190.756246ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:55:19.757533Z","caller":"traceutil/trace.go:171","msg":"trace[1510584431] transaction","detail":"{read_only:false; response_revision:7804; number_of_response:1; }","duration":"372.681006ms","start":"2026-03-07T21:55:19.384795Z","end":"2026-03-07T21:55:19.757476Z","steps":["trace[1510584431] 'process raft request' (duration: 308.964907ms)","trace[1510584431] 'compare' (duration: 63.329239ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:55:19.75774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:55:19.384777Z","time spent":"372.886932ms","remote":"127.0.0.1:54024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T21:55:19.757775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.609509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T21:55:19.757828Z","caller":"traceutil/trace.go:171","msg":"trace[564296070] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:7805; }","duration":"147.735902ms","start":"2026-03-07T21:55:19.610075Z","end":"2026-03-07T21:55:19.757811Z","steps":["trace[564296070] 'agreement among raft nodes before linearized reading' (duration: 147.626389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:55:19.757818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.417409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-07T21:55:19.757882Z","caller":"traceutil/trace.go:171","msg":"trace[2042667014] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:7805; }","duration":"306.513522ms","start":"2026-03-07T21:55:19.451352Z","end":"2026-03-07T21:55:19.757866Z","steps":["trace[2042667014] 'agreement among raft nodes before linearized reading' (duration: 306.177843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:55:19.75793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:55:19.451333Z","time spent":"306.588953ms","remote":"127.0.0.1:53784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"info","ts":"2026-03-07T21:55:19.959489Z","caller":"traceutil/trace.go:171","msg":"trace[163943492] linearizableReadLoop","detail":"{readStateIndex:8093; appliedIndex:8092; }","duration":"144.483304ms","start":"2026-03-07T21:55:19.814983Z","end":"2026-03-07T21:55:19.959466Z","steps":["trace[163943492] 'read index received' (duration: 107.856622ms)","trace[163943492] 'applied index is now lower than readState.Index' (duration: 36.625712ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:55:19.959561Z","caller":"traceutil/trace.go:171","msg":"trace[1474019275] transaction","detail":"{read_only:false; response_revision:7807; number_of_response:1; }","duration":"169.219708ms","start":"2026-03-07T21:55:19.790288Z","end":"2026-03-07T21:55:19.959508Z","steps":["trace[1474019275] 'process raft request' (duration: 132.770501ms)","trace[1474019275] 'compare' (duration: 36.217981ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:55:19.959688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.68581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-07T21:55:19.959722Z","caller":"traceutil/trace.go:171","msg":"trace[1486229161] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:7807; }","duration":"144.759992ms","start":"2026-03-07T21:55:19.814951Z","end":"2026-03-07T21:55:19.959711Z","steps":["trace[1486229161] 'agreement among raft nodes before linearized reading' (duration: 144.624048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:55:19.974719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.485552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-07T21:55:19.974774Z","caller":"traceutil/trace.go:171","msg":"trace[1071718411] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7808; }","duration":"101.598806ms","start":"2026-03-07T21:55:19.873163Z","end":"2026-03-07T21:55:19.974762Z","steps":["trace[1071718411] 'agreement among raft nodes before linearized reading' (duration: 101.449272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:55:27.583739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.582633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xsw75\" ","response":"range_response_count:1 size:4648"} {"level":"warn","ts":"2026-03-07T21:55:27.583745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.662165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xsw75\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-03-07T21:55:27.583841Z","caller":"traceutil/trace.go:171","msg":"trace[1759892356] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xsw75; range_end:; response_count:1; response_revision:7883; }","duration":"240.747638ms","start":"2026-03-07T21:55:27.343072Z","end":"2026-03-07T21:55:27.583819Z","steps":["trace[1759892356] 'range keys from in-memory index tree' (duration: 240.490731ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:27.583856Z","caller":"traceutil/trace.go:171","msg":"trace[146523513] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xsw75; range_end:; response_count:1; response_revision:7883; }","duration":"278.833039ms","start":"2026-03-07T21:55:27.305Z","end":"2026-03-07T21:55:27.583833Z","steps":["trace[146523513] 'range keys from in-memory index tree' (duration: 278.519181ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:27.583828Z","caller":"traceutil/trace.go:171","msg":"trace[68710200] linearizableReadLoop","detail":"{readStateIndex:8174; appliedIndex:8173; }","duration":"156.930059ms","start":"2026-03-07T21:55:27.426858Z","end":"2026-03-07T21:55:27.583788Z","steps":["trace[68710200] 'read index received' (duration: 101.695717ms)","trace[68710200] 'applied index is now lower than readState.Index' (duration: 55.231792ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:55:27.583928Z","caller":"traceutil/trace.go:171","msg":"trace[1167356263] transaction","detail":"{read_only:false; response_revision:7884; number_of_response:1; }","duration":"240.181453ms","start":"2026-03-07T21:55:27.343708Z","end":"2026-03-07T21:55:27.583889Z","steps":["trace[1167356263] 'process raft request' (duration: 184.734145ms)","trace[1167356263] 'compare' (duration: 55.047647ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:55:27.584163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.279539ms","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-03-07T21:55:27.584216Z","caller":"traceutil/trace.go:171","msg":"trace[1234572178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7884; }","duration":"157.403952ms","start":"2026-03-07T21:55:27.426798Z","end":"2026-03-07T21:55:27.584202Z","steps":["trace[1234572178] 'agreement among raft nodes before linearized reading' (duration: 157.110924ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:27.747732Z","caller":"traceutil/trace.go:171","msg":"trace[25576909] transaction","detail":"{read_only:false; response_revision:7885; number_of_response:1; }","duration":"158.443669ms","start":"2026-03-07T21:55:27.589256Z","end":"2026-03-07T21:55:27.747699Z","steps":["trace[25576909] 'process raft request' (duration: 134.474637ms)","trace[25576909] 'compare' (duration: 23.836489ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T21:55:27.766292Z","caller":"traceutil/trace.go:171","msg":"trace[1269947126] linearizableReadLoop","detail":"{readStateIndex:8176; appliedIndex:8174; }","duration":"167.543425ms","start":"2026-03-07T21:55:27.598725Z","end":"2026-03-07T21:55:27.766268Z","steps":["trace[1269947126] 'read index received' (duration: 125.025884ms)","trace[1269947126] 'applied index is now lower than readState.Index' (duration: 42.516131ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:55:27.766508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.77006ms","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-03-07T21:55:27.766551Z","caller":"traceutil/trace.go:171","msg":"trace[674317931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7886; }","duration":"167.863473ms","start":"2026-03-07T21:55:27.598676Z","end":"2026-03-07T21:55:27.766539Z","steps":["trace[674317931] 'agreement among raft nodes before linearized reading' (duration: 167.673868ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:27.76676Z","caller":"traceutil/trace.go:171","msg":"trace[551192031] transaction","detail":"{read_only:false; response_revision:7886; number_of_response:1; }","duration":"175.699593ms","start":"2026-03-07T21:55:27.591018Z","end":"2026-03-07T21:55:27.766717Z","steps":["trace[551192031] 'process raft request' (duration: 175.146809ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:28.630072Z","caller":"traceutil/trace.go:171","msg":"trace[1778057005] linearizableReadLoop","detail":"{readStateIndex:8185; appliedIndex:8183; }","duration":"121.414056ms","start":"2026-03-07T21:55:28.508639Z","end":"2026-03-07T21:55:28.630053Z","steps":["trace[1778057005] 'read index received' (duration: 63.116623ms)","trace[1778057005] 'applied index is now lower than readState.Index' (duration: 58.296583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:55:28.630296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.642252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-03-07T21:55:28.630332Z","caller":"traceutil/trace.go:171","msg":"trace[60420976] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:7895; }","duration":"121.695964ms","start":"2026-03-07T21:55:28.508625Z","end":"2026-03-07T21:55:28.630321Z","steps":["trace[60420976] 'agreement among raft nodes before linearized reading' (duration: 121.513919ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:28.630516Z","caller":"traceutil/trace.go:171","msg":"trace[720011791] transaction","detail":"{read_only:false; response_revision:7895; number_of_response:1; }","duration":"124.092269ms","start":"2026-03-07T21:55:28.506415Z","end":"2026-03-07T21:55:28.630507Z","steps":["trace[720011791] 'process raft request' (duration: 123.485412ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:29.109043Z","caller":"traceutil/trace.go:171","msg":"trace[247652751] linearizableReadLoop","detail":"{readStateIndex:8188; appliedIndex:8187; }","duration":"377.207298ms","start":"2026-03-07T21:55:28.731808Z","end":"2026-03-07T21:55:29.109016Z","steps":["trace[247652751] 'read index received' (duration: 376.973371ms)","trace[247652751] 'applied index is now lower than readState.Index' (duration: 232.406µs)"],"step_count":2} {"level":"warn","ts":"2026-03-07T21:55:29.109182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.355951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-07T21:55:29.109214Z","caller":"traceutil/trace.go:171","msg":"trace[1313141204] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:7898; }","duration":"377.455194ms","start":"2026-03-07T21:55:28.73175Z","end":"2026-03-07T21:55:29.109206Z","steps":["trace[1313141204] 'agreement among raft nodes before linearized reading' (duration: 377.362931ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T21:55:29.109162Z","caller":"traceutil/trace.go:171","msg":"trace[1976696184] transaction","detail":"{read_only:false; response_revision:7898; number_of_response:1; }","duration":"396.929046ms","start":"2026-03-07T21:55:28.712159Z","end":"2026-03-07T21:55:29.109088Z","steps":["trace[1976696184] 'process raft request' (duration: 396.70145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T21:55:29.109242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:55:28.731696Z","time spent":"377.539136ms","remote":"127.0.0.1:53896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":7,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-07T21:55:29.109382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T21:55:28.712148Z","time spent":"397.129151ms","remote":"127.0.0.1:52598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T21:59:12.975634Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd37ed2859641e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-07T21:59:12.982045Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-07T21:59:12.982179Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-07T22:00:16.2949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7789} {"level":"info","ts":"2026-03-07T22:00:16.361064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7789,"took":"63.268947ms","hash":2423609264,"current-db-size-bytes":43991040,"current-db-size":"44 MB","current-db-size-in-use-bytes":26103808,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-07T22:00:16.361144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2423609264,"revision":7789,"compact-revision":5754} {"level":"info","ts":"2026-03-07T22:05:16.303491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9982} {"level":"info","ts":"2026-03-07T22:05:16.377122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9982,"took":"71.5498ms","hash":3169455840,"current-db-size-bytes":43991040,"current-db-size":"44 MB","current-db-size-in-use-bytes":16830464,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-07T22:05:16.377184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3169455840,"revision":9982,"compact-revision":7789}