{"level":"warn","ts":"2026-10-15T11:15:38.009454Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:15:38.009573Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.139: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.139:2380","--initial-cluster=instance=https://199.204.45.139:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.139:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.139:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T11:15:38.009665Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T11:15:38.009694Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.139:2380"]} {"level":"info","ts":"2026-10-15T11:15:38.009739Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T11:15:38.010734Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.139:2379"]} {"level":"info","ts":"2026-10-15T11:15:38.010839Z","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.139:2380"],"listen-peer-urls":["https://199.204.45.139:2380"],"advertise-client-urls":["https://199.204.45.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.139:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T11:15:38.029262Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"18.124136ms"} {"level":"info","ts":"2026-10-15T11:15:38.061689Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5739a4baaf9cb6ee","cluster-id":"f3dfd8b01c2185e2"} {"level":"info","ts":"2026-10-15T11:15:38.06186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T11:15:38.061914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became follower at term 0"} {"level":"info","ts":"2026-10-15T11:15:38.06194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5739a4baaf9cb6ee [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T11:15:38.061957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became follower at term 1"} {"level":"info","ts":"2026-10-15T11:15:38.062034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee switched to configuration voters=(6285235876689393390)"} {"level":"warn","ts":"2026-10-15T11:15:38.070311Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T11:15:38.07241Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T11:15:38.073988Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-15T11:15:38.077164Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5739a4baaf9cb6ee","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T11:15:38.078088Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5739a4baaf9cb6ee","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T11:15:38.078312Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:15:38.078471Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:15:38.07849Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T11:15:38.080626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee switched to configuration voters=(6285235876689393390)"} {"level":"info","ts":"2026-10-15T11:15:38.082266Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f3dfd8b01c2185e2","local-member-id":"5739a4baaf9cb6ee","added-peer-id":"5739a4baaf9cb6ee","added-peer-peer-urls":["https://199.204.45.139:2380"]} {"level":"info","ts":"2026-10-15T11:15:38.085303Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T11:15:38.085475Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.139:2380"} {"level":"info","ts":"2026-10-15T11:15:38.085506Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.139:2380"} {"level":"info","ts":"2026-10-15T11:15:38.085923Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5739a4baaf9cb6ee","initial-advertise-peer-urls":["https://199.204.45.139:2380"],"listen-peer-urls":["https://199.204.45.139:2380"],"advertise-client-urls":["https://199.204.45.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T11:15:38.08599Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T11:15:38.162985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T11:15:38.163087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T11:15:38.163152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee received MsgPreVoteResp from 5739a4baaf9cb6ee at term 1"} {"level":"info","ts":"2026-10-15T11:15:38.163243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became candidate at term 2"} {"level":"info","ts":"2026-10-15T11:15:38.1633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee received MsgVoteResp from 5739a4baaf9cb6ee at term 2"} {"level":"info","ts":"2026-10-15T11:15:38.163359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5739a4baaf9cb6ee became leader at term 2"} {"level":"info","ts":"2026-10-15T11:15:38.163417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5739a4baaf9cb6ee elected leader 5739a4baaf9cb6ee at term 2"} {"level":"info","ts":"2026-10-15T11:15:38.165189Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:15:38.166256Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:15:38.166319Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:15:38.166255Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5739a4baaf9cb6ee","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.139:2379]}","request-path":"/0/members/5739a4baaf9cb6ee/attributes","cluster-id":"f3dfd8b01c2185e2","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T11:15:38.166542Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T11:15:38.166656Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T11:15:38.167055Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f3dfd8b01c2185e2","local-member-id":"5739a4baaf9cb6ee","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:15:38.167333Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:15:38.167493Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:15:38.171425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.139:2379"} {"level":"info","ts":"2026-10-15T11:15:38.171657Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T11:15:48.528523Z","caller":"traceutil/trace.go:171","msg":"trace[200250586] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"127.95253ms","start":"2026-10-15T11:15:48.400524Z","end":"2026-10-15T11:15:48.528477Z","steps":["trace[200250586] 'read index received' (duration: 127.501761ms)","trace[200250586] 'applied index is now lower than readState.Index' (duration: 448.869µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:15:48.528621Z","caller":"traceutil/trace.go:171","msg":"trace[1147153828] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"137.421651ms","start":"2026-10-15T11:15:48.391162Z","end":"2026-10-15T11:15:48.528584Z","steps":["trace[1147153828] 'process raft request' (duration: 137.044874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:15:48.528838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.278886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T11:15:48.529Z","caller":"traceutil/trace.go:171","msg":"trace[2087178571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:303; }","duration":"128.503591ms","start":"2026-10-15T11:15:48.400474Z","end":"2026-10-15T11:15:48.528978Z","steps":["trace[2087178571] 'agreement among raft nodes before linearized reading' (duration: 128.168554ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:16:30.451225Z","caller":"traceutil/trace.go:171","msg":"trace[1339176005] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"243.39279ms","start":"2026-10-15T11:16:30.207762Z","end":"2026-10-15T11:16:30.451155Z","steps":["trace[1339176005] 'process raft request' (duration: 238.109568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:31.127672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.55953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T11:16:31.127777Z","caller":"traceutil/trace.go:171","msg":"trace[498528026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"346.733864ms","start":"2026-10-15T11:16:30.781024Z","end":"2026-10-15T11:16:31.127757Z","steps":["trace[498528026] 'range keys from in-memory index tree' (duration: 346.283525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:31.127817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.995012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-15T11:16:31.127823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:16:30.780995Z","time spent":"346.817635ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T11:16:31.127895Z","caller":"traceutil/trace.go:171","msg":"trace[1185366896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"342.131655ms","start":"2026-10-15T11:16:30.785745Z","end":"2026-10-15T11:16:31.127876Z","steps":["trace[1185366896] 'range keys from in-memory index tree' (duration: 341.754617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:16:31.127935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:16:30.785719Z","time spent":"342.206806ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T11:16:31.144963Z","caller":"traceutil/trace.go:171","msg":"trace[424908995] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"122.799081ms","start":"2026-10-15T11:16:31.022133Z","end":"2026-10-15T11:16:31.144932Z","steps":["trace[424908995] 'process raft request' (duration: 121.946664ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:16:36.251108Z","caller":"traceutil/trace.go:171","msg":"trace[1191915077] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"118.562049ms","start":"2026-10-15T11:16:36.13248Z","end":"2026-10-15T11:16:36.251042Z","steps":["trace[1191915077] 'process raft request' (duration: 80.528551ms)","trace[1191915077] 'compare' (duration: 37.13307ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:16:47.459524Z","caller":"traceutil/trace.go:171","msg":"trace[2002168468] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"213.850577ms","start":"2026-10-15T11:16:47.245643Z","end":"2026-10-15T11:16:47.459494Z","steps":["trace[2002168468] 'read index received' (duration: 132.741205ms)","trace[2002168468] 'applied index is now lower than readState.Index' (duration: 81.107932ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:16:47.459821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.32412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-15T11:16:47.459825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.159453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T11:16:47.459909Z","caller":"traceutil/trace.go:171","msg":"trace[402218341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"214.307736ms","start":"2026-10-15T11:16:47.245581Z","end":"2026-10-15T11:16:47.459889Z","steps":["trace[402218341] 'agreement among raft nodes before linearized reading' (duration: 214.029101ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:16:47.459858Z","caller":"traceutil/trace.go:171","msg":"trace[1931293003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"207.424632ms","start":"2026-10-15T11:16:47.252424Z","end":"2026-10-15T11:16:47.459849Z","steps":["trace[1931293003] 'agreement among raft nodes before linearized reading' (duration: 207.218558ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:04.262741Z","caller":"traceutil/trace.go:171","msg":"trace[923498285] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"263.096396ms","start":"2026-10-15T11:18:03.999607Z","end":"2026-10-15T11:18:04.262703Z","steps":["trace[923498285] 'process raft request' (duration: 230.813208ms)","trace[923498285] 'compare' (duration: 31.932442ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:18:04.273536Z","caller":"traceutil/trace.go:171","msg":"trace[548285333] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"273.543966ms","start":"2026-10-15T11:18:03.999966Z","end":"2026-10-15T11:18:04.27351Z","steps":["trace[548285333] 'process raft request' (duration: 273.092638ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:19.554427Z","caller":"traceutil/trace.go:171","msg":"trace[1808454765] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"135.114387ms","start":"2026-10-15T11:18:19.419287Z","end":"2026-10-15T11:18:19.554401Z","steps":["trace[1808454765] 'process raft request' (duration: 134.969394ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:55.254683Z","caller":"traceutil/trace.go:171","msg":"trace[1439850818] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"252.420071ms","start":"2026-10-15T11:18:55.002188Z","end":"2026-10-15T11:18:55.254608Z","steps":["trace[1439850818] 'process raft request' (duration: 252.041414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:55.54004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.725814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:18:55.540175Z","caller":"traceutil/trace.go:171","msg":"trace[1276756764] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2257; }","duration":"256.714634ms","start":"2026-10-15T11:18:55.28344Z","end":"2026-10-15T11:18:55.540155Z","steps":["trace[1276756764] 'read index received' (duration: 95.168835ms)","trace[1276756764] 'applied index is now lower than readState.Index' (duration: 161.544449ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:18:55.540258Z","caller":"traceutil/trace.go:171","msg":"trace[79817496] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"374.433417ms","start":"2026-10-15T11:18:55.165782Z","end":"2026-10-15T11:18:55.540215Z","steps":["trace[79817496] 'process raft request' (duration: 212.786076ms)","trace[79817496] 'compare' (duration: 160.51785ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:18:55.54038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.949578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T11:18:55.540425Z","caller":"traceutil/trace.go:171","msg":"trace[73168274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2177; }","duration":"257.110261ms","start":"2026-10-15T11:18:55.283298Z","end":"2026-10-15T11:18:55.540409Z","steps":["trace[73168274] 'agreement among raft nodes before linearized reading' (duration: 256.956018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:55.540475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:55.165749Z","time spent":"374.59892ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:19:41.864849Z","caller":"traceutil/trace.go:171","msg":"trace[318528360] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"174.265375ms","start":"2026-10-15T11:19:41.690501Z","end":"2026-10-15T11:19:41.864766Z","steps":["trace[318528360] 'process raft request' (duration: 152.174468ms)","trace[318528360] 'compare' (duration: 21.71032ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:19:41.864867Z","caller":"traceutil/trace.go:171","msg":"trace[1743605257] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"170.867861ms","start":"2026-10-15T11:19:41.69397Z","end":"2026-10-15T11:19:41.864838Z","steps":["trace[1743605257] 'process raft request' (duration: 170.608896ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:41.865214Z","caller":"traceutil/trace.go:171","msg":"trace[331244926] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"127.145264ms","start":"2026-10-15T11:19:41.738014Z","end":"2026-10-15T11:19:41.865159Z","steps":["trace[331244926] 'process raft request' (duration: 126.715406ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:20:18.844895Z","caller":"traceutil/trace.go:171","msg":"trace[1184930649] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"128.904287ms","start":"2026-10-15T11:20:18.715969Z","end":"2026-10-15T11:20:18.844873Z","steps":["trace[1184930649] 'process raft request' (duration: 128.776725ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:20:24.154862Z","caller":"traceutil/trace.go:171","msg":"trace[1882561609] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"116.561153ms","start":"2026-10-15T11:20:24.038276Z","end":"2026-10-15T11:20:24.154837Z","steps":["trace[1882561609] 'process raft request' (duration: 115.89362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:41.416625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.826794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:23:41.416737Z","caller":"traceutil/trace.go:171","msg":"trace[1417808262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4086; }","duration":"101.007358ms","start":"2026-10-15T11:23:41.315705Z","end":"2026-10-15T11:23:41.416712Z","steps":["trace[1417808262] 'range keys from in-memory index tree' (duration: 100.670211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:23:41.41665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.944106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:23:41.416828Z","caller":"traceutil/trace.go:171","msg":"trace[288456189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4086; }","duration":"101.195641ms","start":"2026-10-15T11:23:41.315611Z","end":"2026-10-15T11:23:41.416807Z","steps":["trace[288456189] 'range keys from in-memory index tree' (duration: 100.691071ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:16.151268Z","caller":"traceutil/trace.go:171","msg":"trace[557735423] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"129.231468ms","start":"2026-10-15T11:25:16.022002Z","end":"2026-10-15T11:25:16.151234Z","steps":["trace[557735423] 'process raft request' (duration: 122.840938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:25:16.151492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.362985ms","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-10-15T11:25:16.151539Z","caller":"traceutil/trace.go:171","msg":"trace[210505152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5202; }","duration":"125.482618ms","start":"2026-10-15T11:25:16.026043Z","end":"2026-10-15T11:25:16.151525Z","steps":["trace[210505152] 'agreement among raft nodes before linearized reading' (duration: 125.245543ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:16.151219Z","caller":"traceutil/trace.go:171","msg":"trace[1992138236] linearizableReadLoop","detail":"{readStateIndex:5398; appliedIndex:5397; }","duration":"125.056359ms","start":"2026-10-15T11:25:16.026095Z","end":"2026-10-15T11:25:16.151152Z","steps":["trace[1992138236] 'read index received' (duration: 118.743931ms)","trace[1992138236] 'applied index is now lower than readState.Index' (duration: 6.311028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:25:20.034894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.357976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e249599-9b97-4a17-8afb-a915858dfd00\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T11:25:20.034982Z","caller":"traceutil/trace.go:171","msg":"trace[509436378] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6e249599-9b97-4a17-8afb-a915858dfd00; range_end:; response_count:1; response_revision:5320; }","duration":"229.501479ms","start":"2026-10-15T11:25:19.80546Z","end":"2026-10-15T11:25:20.034962Z","steps":["trace[509436378] 'range keys from in-memory index tree' (duration: 229.135692ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:20.141243Z","caller":"traceutil/trace.go:171","msg":"trace[718055386] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"100.506985ms","start":"2026-10-15T11:25:20.040687Z","end":"2026-10-15T11:25:20.141194Z","steps":["trace[718055386] 'process raft request' (duration: 94.718515ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:35.920198Z","caller":"traceutil/trace.go:171","msg":"trace[572118696] linearizableReadLoop","detail":"{readStateIndex:5809; appliedIndex:5808; }","duration":"140.014353ms","start":"2026-10-15T11:25:35.780152Z","end":"2026-10-15T11:25:35.920166Z","steps":["trace[572118696] 'read index received' (duration: 139.85623ms)","trace[572118696] 'applied index is now lower than readState.Index' (duration: 156.863µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:25:35.920328Z","caller":"traceutil/trace.go:171","msg":"trace[214170789] transaction","detail":"{read_only:false; response_revision:5600; number_of_response:1; }","duration":"143.731513ms","start":"2026-10-15T11:25:35.776562Z","end":"2026-10-15T11:25:35.920293Z","steps":["trace[214170789] 'process raft request' (duration: 143.382987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:25:35.920481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.281968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-15T11:25:35.920551Z","caller":"traceutil/trace.go:171","msg":"trace[1203002685] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5600; }","duration":"140.418351ms","start":"2026-10-15T11:25:35.780113Z","end":"2026-10-15T11:25:35.920532Z","steps":["trace[1203002685] 'agreement among raft nodes before linearized reading' (duration: 140.190507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:25:36.252388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.684974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-5g7td\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-10-15T11:25:36.252523Z","caller":"traceutil/trace.go:171","msg":"trace[2006023636] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-5g7td; range_end:; response_count:1; response_revision:5600; }","duration":"122.872768ms","start":"2026-10-15T11:25:36.129621Z","end":"2026-10-15T11:25:36.252494Z","steps":["trace[2006023636] 'range keys from in-memory index tree' (duration: 122.46681ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:25:38.538221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2931} {"level":"info","ts":"2026-10-15T11:25:38.621898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2931,"took":"82.184548ms","hash":1914709183,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T11:25:38.621994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1914709183,"revision":2931,"compact-revision":-1} {"level":"info","ts":"2026-10-15T11:30:38.545863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5667} {"level":"info","ts":"2026-10-15T11:30:38.638891Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5667,"took":"90.986625ms","hash":3338790952,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":26505216,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T11:30:38.638975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3338790952,"revision":5667,"compact-revision":2931} {"level":"info","ts":"2026-10-15T11:31:54.828752Z","caller":"traceutil/trace.go:171","msg":"trace[131980057] linearizableReadLoop","detail":"{readStateIndex:8923; appliedIndex:8922; }","duration":"165.200813ms","start":"2026-10-15T11:31:54.663517Z","end":"2026-10-15T11:31:54.828718Z","steps":["trace[131980057] 'read index received' (duration: 164.943538ms)","trace[131980057] 'applied index is now lower than readState.Index' (duration: 255.935µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:31:54.828917Z","caller":"traceutil/trace.go:171","msg":"trace[1602741685] transaction","detail":"{read_only:false; response_revision:8620; number_of_response:1; }","duration":"380.273321ms","start":"2026-10-15T11:31:54.448631Z","end":"2026-10-15T11:31:54.828904Z","steps":["trace[1602741685] 'process raft request' (duration: 379.863353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:31:54.829067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:31:54.448584Z","time spent":"380.364473ms","remote":"127.0.0.1:47184","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-10-15T11:31:54.829231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.609911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:31:54.829331Z","caller":"traceutil/trace.go:171","msg":"trace[1787610023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8620; }","duration":"164.738894ms","start":"2026-10-15T11:31:54.664569Z","end":"2026-10-15T11:31:54.829308Z","steps":["trace[1787610023] 'agreement among raft nodes before linearized reading' (duration: 164.50691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:31:54.829625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.108251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:31:54.829887Z","caller":"traceutil/trace.go:171","msg":"trace[1785493525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8620; }","duration":"166.248794ms","start":"2026-10-15T11:31:54.663435Z","end":"2026-10-15T11:31:54.829683Z","steps":["trace[1785493525] 'agreement among raft nodes before linearized reading' (duration: 165.881626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:31:54.830074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.106696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T11:31:54.830175Z","caller":"traceutil/trace.go:171","msg":"trace[590222510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8620; }","duration":"161.235218ms","start":"2026-10-15T11:31:54.668919Z","end":"2026-10-15T11:31:54.830155Z","steps":["trace[590222510] 'agreement among raft nodes before linearized reading' (duration: 160.902722ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:07.278077Z","caller":"traceutil/trace.go:171","msg":"trace[1482765919] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"122.022294ms","start":"2026-10-15T11:32:07.156027Z","end":"2026-10-15T11:32:07.278049Z","steps":["trace[1482765919] 'process raft request' (duration: 55.791128ms)","trace[1482765919] 'compare' (duration: 66.099283ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:07.421968Z","caller":"traceutil/trace.go:171","msg":"trace[2071341467] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"144.058723ms","start":"2026-10-15T11:32:07.277872Z","end":"2026-10-15T11:32:07.42193Z","steps":["trace[2071341467] 'process raft request' (duration: 143.844439ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:36.683364Z","caller":"traceutil/trace.go:171","msg":"trace[2019982373] linearizableReadLoop","detail":"{readStateIndex:9211; appliedIndex:9210; }","duration":"191.449176ms","start":"2026-10-15T11:32:36.491873Z","end":"2026-10-15T11:32:36.683322Z","steps":["trace[2019982373] 'read index received' (duration: 191.136301ms)","trace[2019982373] 'applied index is now lower than readState.Index' (duration: 311.395µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:36.683431Z","caller":"traceutil/trace.go:171","msg":"trace[239347501] transaction","detail":"{read_only:false; response_revision:8897; number_of_response:1; }","duration":"268.97954ms","start":"2026-10-15T11:32:36.414415Z","end":"2026-10-15T11:32:36.683395Z","steps":["trace[239347501] 'process raft request' (duration: 268.699785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:36.683758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.815194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s8qm7\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T11:32:36.683861Z","caller":"traceutil/trace.go:171","msg":"trace[1359771011] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s8qm7; range_end:; response_count:1; response_revision:8897; }","duration":"192.022007ms","start":"2026-10-15T11:32:36.491818Z","end":"2026-10-15T11:32:36.68384Z","steps":["trace[1359771011] 'agreement among raft nodes before linearized reading' (duration: 191.68673ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:45.392905Z","caller":"traceutil/trace.go:171","msg":"trace[2025874326] transaction","detail":"{read_only:false; response_revision:8946; number_of_response:1; }","duration":"108.165984ms","start":"2026-10-15T11:32:45.284705Z","end":"2026-10-15T11:32:45.392871Z","steps":["trace[2025874326] 'process raft request' (duration: 98.241726ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:45.397899Z","caller":"traceutil/trace.go:171","msg":"trace[555558095] transaction","detail":"{read_only:false; response_revision:8947; number_of_response:1; }","duration":"112.472637ms","start":"2026-10-15T11:32:45.285363Z","end":"2026-10-15T11:32:45.397836Z","steps":["trace[555558095] 'process raft request' (duration: 112.243403ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:51.554168Z","caller":"traceutil/trace.go:171","msg":"trace[1856130418] transaction","detail":"{read_only:false; response_revision:9012; number_of_response:1; }","duration":"233.433624ms","start":"2026-10-15T11:32:51.320697Z","end":"2026-10-15T11:32:51.554131Z","steps":["trace[1856130418] 'process raft request' (duration: 161.527458ms)","trace[1856130418] 'compare' (duration: 71.758543ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:32:51.617421Z","caller":"traceutil/trace.go:171","msg":"trace[1239050449] transaction","detail":"{read_only:false; response_revision:9013; number_of_response:1; }","duration":"286.982222ms","start":"2026-10-15T11:32:51.330408Z","end":"2026-10-15T11:32:51.61739Z","steps":["trace[1239050449] 'process raft request' (duration: 286.596764ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:51.61742Z","caller":"traceutil/trace.go:171","msg":"trace[1519595268] linearizableReadLoop","detail":"{readStateIndex:9332; appliedIndex:9330; }","duration":"278.100453ms","start":"2026-10-15T11:32:51.339269Z","end":"2026-10-15T11:32:51.61737Z","steps":["trace[1519595268] 'read index received' (duration: 142.977506ms)","trace[1519595268] 'applied index is now lower than readState.Index' (duration: 135.120917ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:32:51.617659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.361138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-p9kmq\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-10-15T11:32:51.617835Z","caller":"traceutil/trace.go:171","msg":"trace[1838430227] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-p9kmq; range_end:; response_count:1; response_revision:9014; }","duration":"278.630034ms","start":"2026-10-15T11:32:51.33917Z","end":"2026-10-15T11:32:51.6178Z","steps":["trace[1838430227] 'agreement among raft nodes before linearized reading' (duration: 278.318018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:51.618082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.365706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:32:51.618134Z","caller":"traceutil/trace.go:171","msg":"trace[818593397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9014; }","duration":"191.461288ms","start":"2026-10-15T11:32:51.42666Z","end":"2026-10-15T11:32:51.618121Z","steps":["trace[818593397] 'agreement among raft nodes before linearized reading' (duration: 191.336635ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:51.617876Z","caller":"traceutil/trace.go:171","msg":"trace[1298886358] transaction","detail":"{read_only:false; response_revision:9014; number_of_response:1; }","duration":"277.052343ms","start":"2026-10-15T11:32:51.340804Z","end":"2026-10-15T11:32:51.617857Z","steps":["trace[1298886358] 'process raft request' (duration: 276.444181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:51.618473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.637031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:32:51.618534Z","caller":"traceutil/trace.go:171","msg":"trace[852302434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9014; }","duration":"191.767714ms","start":"2026-10-15T11:32:51.426754Z","end":"2026-10-15T11:32:51.618521Z","steps":["trace[852302434] 'agreement among raft nodes before linearized reading' (duration: 191.531799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:32:51.61879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.004901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T11:32:51.618848Z","caller":"traceutil/trace.go:171","msg":"trace[975501751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9014; }","duration":"188.123313ms","start":"2026-10-15T11:32:51.430716Z","end":"2026-10-15T11:32:51.618839Z","steps":["trace[975501751] 'agreement among raft nodes before linearized reading' (duration: 188.016881ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:05.798428Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024fe.wal"} {"level":"info","ts":"2026-10-15T11:33:54.127389Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5739a4baaf9cb6ee","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T11:33:54.13606Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T11:33:54.136225Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T11:35:38.55507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7989} {"level":"info","ts":"2026-10-15T11:35:38.643654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7989,"took":"85.466538ms","hash":2262957856,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":24858624,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-15T11:35:38.643973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2262957856,"revision":7989,"compact-revision":5667} {"level":"warn","ts":"2026-10-15T11:39:37.541382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.732245ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T11:39:37.541659Z","caller":"traceutil/trace.go:171","msg":"trace[264314261] linearizableReadLoop","detail":"{readStateIndex:12560; appliedIndex:12559; }","duration":"174.49464ms","start":"2026-10-15T11:39:37.367107Z","end":"2026-10-15T11:39:37.541602Z","steps":["trace[264314261] 'read index received' (duration: 49.537761ms)","trace[264314261] 'applied index is now lower than readState.Index' (duration: 124.954729ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:39:37.541869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.737074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:39:37.541931Z","caller":"traceutil/trace.go:171","msg":"trace[696237852] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:12144; }","duration":"174.847857ms","start":"2026-10-15T11:39:37.367057Z","end":"2026-10-15T11:39:37.541905Z","steps":["trace[696237852] 'agreement among raft nodes before linearized reading' (duration: 174.700984ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:39:37.541925Z","caller":"traceutil/trace.go:171","msg":"trace[417684695] transaction","detail":"{read_only:false; response_revision:12144; number_of_response:1; }","duration":"173.825657ms","start":"2026-10-15T11:39:37.368065Z","end":"2026-10-15T11:39:37.541891Z","steps":["trace[417684695] 'process raft request' (duration: 173.441579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:39:37.542355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.004453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:39:37.542467Z","caller":"traceutil/trace.go:171","msg":"trace[1088789050] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:12145; }","duration":"124.235416ms","start":"2026-10-15T11:39:37.418209Z","end":"2026-10-15T11:39:37.542444Z","steps":["trace[1088789050] 'agreement among raft nodes before linearized reading' (duration: 123.92736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:39:58.531688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.583296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:39:58.531822Z","caller":"traceutil/trace.go:171","msg":"trace[701046427] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12264; }","duration":"111.766169ms","start":"2026-10-15T11:39:58.420028Z","end":"2026-10-15T11:39:58.531794Z","steps":["trace[701046427] 'range keys from in-memory index tree' (duration: 111.399872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:39:58.531789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.313789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T11:39:58.531831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.643867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:39:58.531916Z","caller":"traceutil/trace.go:171","msg":"trace[1220821538] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:12264; }","duration":"122.533714ms","start":"2026-10-15T11:39:58.409352Z","end":"2026-10-15T11:39:58.531886Z","steps":["trace[1220821538] 'count revisions from in-memory index tree' (duration: 122.159586ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:39:58.531941Z","caller":"traceutil/trace.go:171","msg":"trace[1932145642] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12264; }","duration":"111.78828ms","start":"2026-10-15T11:39:58.420126Z","end":"2026-10-15T11:39:58.531914Z","steps":["trace[1932145642] 'range keys from in-memory index tree' (duration: 111.538735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:39:58.532058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.652396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s8qm7\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T11:39:58.532167Z","caller":"traceutil/trace.go:171","msg":"trace[1364402582] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s8qm7; range_end:; response_count:1; response_revision:12264; }","duration":"151.83779ms","start":"2026-10-15T11:39:58.380305Z","end":"2026-10-15T11:39:58.532142Z","steps":["trace[1364402582] 'range keys from in-memory index tree' (duration: 151.399872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:39:58.532333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.948843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-15T11:39:58.532394Z","caller":"traceutil/trace.go:171","msg":"trace[731416064] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12264; }","duration":"112.026274ms","start":"2026-10-15T11:39:58.420347Z","end":"2026-10-15T11:39:58.532373Z","steps":["trace[731416064] 'range keys from in-memory index tree' (duration: 111.715909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:39:58.532465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.405841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:39:58.532531Z","caller":"traceutil/trace.go:171","msg":"trace[166507006] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12264; }","duration":"112.533354ms","start":"2026-10-15T11:39:58.419978Z","end":"2026-10-15T11:39:58.532511Z","steps":["trace[166507006] 'range keys from in-memory index tree' (duration: 112.232698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:39:58.532615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.864962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:39:58.532664Z","caller":"traceutil/trace.go:171","msg":"trace[171430564] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12264; }","duration":"111.913012ms","start":"2026-10-15T11:39:58.420738Z","end":"2026-10-15T11:39:58.532651Z","steps":["trace[171430564] 'range keys from in-memory index tree' (duration: 111.81937ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:40:38.568372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10382} {"level":"info","ts":"2026-10-15T11:40:38.657686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10382,"took":"86.005711ms","hash":1623477196,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":25673728,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T11:40:38.657764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1623477196,"revision":10382,"compact-revision":7989} {"level":"info","ts":"2026-10-15T11:45:38.575053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12559} {"level":"info","ts":"2026-10-15T11:45:38.660806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12559,"took":"82.021295ms","hash":1567684293,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":27316224,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T11:45:38.660985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1567684293,"revision":12559,"compact-revision":10382} {"level":"info","ts":"2026-10-15T11:46:32.831465Z","caller":"traceutil/trace.go:171","msg":"trace[2049005844] linearizableReadLoop","detail":"{readStateIndex:15418; appliedIndex:15417; }","duration":"221.586789ms","start":"2026-10-15T11:46:32.609845Z","end":"2026-10-15T11:46:32.831432Z","steps":["trace[2049005844] 'read index received' (duration: 221.263923ms)","trace[2049005844] 'applied index is now lower than readState.Index' (duration: 321.426µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:46:32.831528Z","caller":"traceutil/trace.go:171","msg":"trace[1174196136] transaction","detail":"{read_only:false; response_revision:14901; number_of_response:1; }","duration":"237.050252ms","start":"2026-10-15T11:46:32.594438Z","end":"2026-10-15T11:46:32.831488Z","steps":["trace[1174196136] 'process raft request' (duration: 236.728386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:46:32.83185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.983547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T11:46:32.831964Z","caller":"traceutil/trace.go:171","msg":"trace[1104146804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14901; }","duration":"222.2003ms","start":"2026-10-15T11:46:32.609739Z","end":"2026-10-15T11:46:32.831939Z","steps":["trace[1104146804] 'agreement among raft nodes before linearized reading' (duration: 221.833854ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:46:32.909105Z","caller":"traceutil/trace.go:171","msg":"trace[459812371] transaction","detail":"{read_only:false; response_revision:14902; number_of_response:1; }","duration":"116.197353ms","start":"2026-10-15T11:46:32.792866Z","end":"2026-10-15T11:46:32.909063Z","steps":["trace[459812371] 'process raft request' (duration: 100.153619ms)","trace[459812371] 'compare' (duration: 15.780529ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:46:32.909509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.590433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-15T11:46:32.909693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.628337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T11:46:32.90981Z","caller":"traceutil/trace.go:171","msg":"trace[1910438532] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14903; }","duration":"252.781141ms","start":"2026-10-15T11:46:32.656999Z","end":"2026-10-15T11:46:32.90978Z","steps":["trace[1910438532] 'agreement among raft nodes before linearized reading' (duration: 252.126028ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:46:32.909734Z","caller":"traceutil/trace.go:171","msg":"trace[1153417872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14903; }","duration":"246.862638ms","start":"2026-10-15T11:46:32.662846Z","end":"2026-10-15T11:46:32.909708Z","steps":["trace[1153417872] 'agreement among raft nodes before linearized reading' (duration: 246.266017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:46:32.90965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.675281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:46:32.910003Z","caller":"traceutil/trace.go:171","msg":"trace[2039027991] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14903; }","duration":"299.036537ms","start":"2026-10-15T11:46:32.610944Z","end":"2026-10-15T11:46:32.909981Z","steps":["trace[2039027991] 'agreement among raft nodes before linearized reading' (duration: 298.14902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:46:32.90955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.528435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:46:32.91012Z","caller":"traceutil/trace.go:171","msg":"trace[16994473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14903; }","duration":"254.203308ms","start":"2026-10-15T11:46:32.655893Z","end":"2026-10-15T11:46:32.910097Z","steps":["trace[16994473] 'agreement among raft nodes before linearized reading' (duration: 253.245729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:46:32.910101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.651047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:46:32.910417Z","caller":"traceutil/trace.go:171","msg":"trace[885626643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14903; }","duration":"254.270898ms","start":"2026-10-15T11:46:32.656116Z","end":"2026-10-15T11:46:32.910387Z","steps":["trace[885626643] 'agreement among raft nodes before linearized reading' (duration: 253.020875ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:25.903484Z","caller":"traceutil/trace.go:171","msg":"trace[1484409531] linearizableReadLoop","detail":"{readStateIndex:16594; appliedIndex:16593; }","duration":"244.433104ms","start":"2026-10-15T11:48:25.659017Z","end":"2026-10-15T11:48:25.90345Z","steps":["trace[1484409531] 'read index received' (duration: 244.239201ms)","trace[1484409531] 'applied index is now lower than readState.Index' (duration: 192.503µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:48:25.903555Z","caller":"traceutil/trace.go:171","msg":"trace[1083303791] transaction","detail":"{read_only:false; response_revision:16043; number_of_response:1; }","duration":"270.11204ms","start":"2026-10-15T11:48:25.633399Z","end":"2026-10-15T11:48:25.903511Z","steps":["trace[1083303791] 'process raft request' (duration: 269.844064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:25.903744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.7201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:48:25.903786Z","caller":"traceutil/trace.go:171","msg":"trace[45003594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16043; }","duration":"244.822943ms","start":"2026-10-15T11:48:25.658953Z","end":"2026-10-15T11:48:25.903776Z","steps":["trace[45003594] 'agreement among raft nodes before linearized reading' (duration: 244.625899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.18109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.982532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:48:26.181375Z","caller":"traceutil/trace.go:171","msg":"trace[186490385] transaction","detail":"{read_only:false; response_revision:16046; number_of_response:1; }","duration":"273.697558ms","start":"2026-10-15T11:48:25.907658Z","end":"2026-10-15T11:48:26.181355Z","steps":["trace[186490385] 'process raft request' (duration: 273.623156ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.181521Z","caller":"traceutil/trace.go:171","msg":"trace[1169804056] transaction","detail":"{read_only:false; response_revision:16044; number_of_response:1; }","duration":"300.458365ms","start":"2026-10-15T11:48:25.881019Z","end":"2026-10-15T11:48:26.181478Z","steps":["trace[1169804056] 'process raft request' (duration: 153.985064ms)","trace[1169804056] 'compare' (duration: 145.793558ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:48:26.181608Z","caller":"traceutil/trace.go:171","msg":"trace[2119296509] linearizableReadLoop","detail":"{readStateIndex:16595; appliedIndex:16594; }","duration":"278.02225ms","start":"2026-10-15T11:48:25.903574Z","end":"2026-10-15T11:48:26.181596Z","steps":["trace[2119296509] 'read index received' (duration: 131.540469ms)","trace[2119296509] 'applied index is now lower than readState.Index' (duration: 146.480381ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:48:26.181623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.880992Z","time spent":"300.581787ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:48:26.181741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.595967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:48:26.181774Z","caller":"traceutil/trace.go:171","msg":"trace[75422211] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16046; }","duration":"477.685598ms","start":"2026-10-15T11:48:25.704079Z","end":"2026-10-15T11:48:26.181765Z","steps":["trace[75422211] 'agreement among raft nodes before linearized reading' (duration: 477.627247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.1818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.704053Z","time spent":"477.740139ms","remote":"127.0.0.1:47376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-15T11:48:26.181867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.613319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T11:48:26.181962Z","caller":"traceutil/trace.go:171","msg":"trace[1584992634] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:16046; }","duration":"466.803143ms","start":"2026-10-15T11:48:25.715132Z","end":"2026-10-15T11:48:26.181935Z","steps":["trace[1584992634] 'agreement among raft nodes before linearized reading' (duration: 466.542318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.181972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.172747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T11:48:26.182009Z","caller":"traceutil/trace.go:171","msg":"trace[1669009432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16046; }","duration":"506.217398ms","start":"2026-10-15T11:48:25.675776Z","end":"2026-10-15T11:48:26.181993Z","steps":["trace[1669009432] 'agreement among raft nodes before linearized reading' (duration: 506.090925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.182014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.715093Z","time spent":"466.910404ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-15T11:48:26.182032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.675768Z","time spent":"506.257449ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T11:48:26.18213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.956993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T11:48:26.182097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.441605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T11:48:26.182097Z","caller":"traceutil/trace.go:171","msg":"trace[1502037810] transaction","detail":"{read_only:false; response_revision:16045; number_of_response:1; }","duration":"276.195977ms","start":"2026-10-15T11:48:25.905837Z","end":"2026-10-15T11:48:26.182033Z","steps":["trace[1502037810] 'process raft request' (duration: 275.379671ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.182205Z","caller":"traceutil/trace.go:171","msg":"trace[360666786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16046; }","duration":"522.571897ms","start":"2026-10-15T11:48:25.659606Z","end":"2026-10-15T11:48:26.182177Z","steps":["trace[360666786] 'agreement among raft nodes before linearized reading' (duration: 522.278692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.182174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.963499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-15T11:48:26.182227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.108463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-15T11:48:26.182253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.659598Z","time spent":"522.646739ms","remote":"127.0.0.1:47184","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-10-15T11:48:26.182261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.834299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-10-15T11:48:26.182264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.751699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-15T11:48:26.182272Z","caller":"traceutil/trace.go:171","msg":"trace[344912492] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16046; }","duration":"508.173814ms","start":"2026-10-15T11:48:25.674088Z","end":"2026-10-15T11:48:26.182262Z","steps":["trace[344912492] 'agreement among raft nodes before linearized reading' (duration: 508.074763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.182223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.764409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:48:26.182295Z","caller":"traceutil/trace.go:171","msg":"trace[2115531514] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16046; }","duration":"459.89081ms","start":"2026-10-15T11:48:25.722394Z","end":"2026-10-15T11:48:26.182284Z","steps":["trace[2115531514] 'agreement among raft nodes before linearized reading' (duration: 459.249638ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.182319Z","caller":"traceutil/trace.go:171","msg":"trace[19348941] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16046; }","duration":"319.80669ms","start":"2026-10-15T11:48:25.862483Z","end":"2026-10-15T11:48:26.18229Z","steps":["trace[19348941] 'agreement among raft nodes before linearized reading' (duration: 319.706109ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.182295Z","caller":"traceutil/trace.go:171","msg":"trace[1404310480] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16046; }","duration":"306.139881ms","start":"2026-10-15T11:48:25.876119Z","end":"2026-10-15T11:48:26.182259Z","steps":["trace[1404310480] 'agreement among raft nodes before linearized reading' (duration: 305.754325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.182323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.674079Z","time spent":"508.237065ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T11:48:26.182345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.722383Z","time spent":"459.955242ms","remote":"127.0.0.1:47084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-15T11:48:26.182354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.862474Z","time spent":"319.872111ms","remote":"127.0.0.1:47084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-15T11:48:26.182375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.876094Z","time spent":"306.269274ms","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-10-15T11:48:26.182172Z","caller":"traceutil/trace.go:171","msg":"trace[277187581] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16046; }","duration":"320.996524ms","start":"2026-10-15T11:48:25.861163Z","end":"2026-10-15T11:48:26.182159Z","steps":["trace[277187581] 'agreement among raft nodes before linearized reading' (duration: 320.928893ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.18237Z","caller":"traceutil/trace.go:171","msg":"trace[1611884420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16046; }","duration":"237.968843ms","start":"2026-10-15T11:48:25.944367Z","end":"2026-10-15T11:48:26.182336Z","steps":["trace[1611884420] 'agreement among raft nodes before linearized reading' (duration: 237.691608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.182459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.861099Z","time spent":"321.320069ms","remote":"127.0.0.1:47152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-15T11:48:26.182477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.232891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-s8qm7\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-10-15T11:48:26.182577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.725971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-tzf7m\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-15T11:48:26.182612Z","caller":"traceutil/trace.go:171","msg":"trace[1688531567] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-tzf7m; range_end:; response_count:1; response_revision:16046; }","duration":"233.806453ms","start":"2026-10-15T11:48:25.948795Z","end":"2026-10-15T11:48:26.182601Z","steps":["trace[1688531567] 'agreement among raft nodes before linearized reading' (duration: 233.606969ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.182604Z","caller":"traceutil/trace.go:171","msg":"trace[1415442062] range","detail":"{range_begin:/registry/pods/openstack/keepalived-s8qm7; range_end:; response_count:1; response_revision:16046; }","duration":"512.387854ms","start":"2026-10-15T11:48:25.670179Z","end":"2026-10-15T11:48:26.182566Z","steps":["trace[1415442062] 'agreement among raft nodes before linearized reading' (duration: 511.998847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.182664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:48:25.670169Z","time spent":"512.482816ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-s8qm7\" "} {"level":"info","ts":"2026-10-15T11:48:26.418991Z","caller":"traceutil/trace.go:171","msg":"trace[153399862] linearizableReadLoop","detail":"{readStateIndex:16598; appliedIndex:16597; }","duration":"230.716096ms","start":"2026-10-15T11:48:26.188235Z","end":"2026-10-15T11:48:26.418951Z","steps":["trace[153399862] 'read index received' (duration: 134.841152ms)","trace[153399862] 'applied index is now lower than readState.Index' (duration: 95.872914ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:48:26.419007Z","caller":"traceutil/trace.go:171","msg":"trace[385259669] transaction","detail":"{read_only:false; response_revision:16047; number_of_response:1; }","duration":"230.867528ms","start":"2026-10-15T11:48:26.188098Z","end":"2026-10-15T11:48:26.418965Z","steps":["trace[385259669] 'process raft request' (duration: 134.876742ms)","trace[385259669] 'compare' (duration: 95.718141ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:48:26.419168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.905879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:48:26.419346Z","caller":"traceutil/trace.go:171","msg":"trace[1126520159] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16047; }","duration":"231.149854ms","start":"2026-10-15T11:48:26.188167Z","end":"2026-10-15T11:48:26.419316Z","steps":["trace[1126520159] 'agreement among raft nodes before linearized reading' (duration: 230.911819ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.422642Z","caller":"traceutil/trace.go:171","msg":"trace[787600663] transaction","detail":"{read_only:false; response_revision:16048; number_of_response:1; }","duration":"232.56448ms","start":"2026-10-15T11:48:26.190049Z","end":"2026-10-15T11:48:26.422614Z","steps":["trace[787600663] 'process raft request' (duration: 232.273085ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.422888Z","caller":"traceutil/trace.go:171","msg":"trace[629896127] transaction","detail":"{read_only:false; response_revision:16050; number_of_response:1; }","duration":"230.249746ms","start":"2026-10-15T11:48:26.192563Z","end":"2026-10-15T11:48:26.422813Z","steps":["trace[629896127] 'process raft request' (duration: 230.006651ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:26.422655Z","caller":"traceutil/trace.go:171","msg":"trace[1961291214] transaction","detail":"{read_only:false; response_revision:16049; number_of_response:1; }","duration":"232.541279ms","start":"2026-10-15T11:48:26.190075Z","end":"2026-10-15T11:48:26.422617Z","steps":["trace[1961291214] 'process raft request' (duration: 232.437957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.423789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.884183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737348"} {"level":"info","ts":"2026-10-15T11:48:26.424462Z","caller":"traceutil/trace.go:171","msg":"trace[201260656] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16050; }","duration":"235.554687ms","start":"2026-10-15T11:48:26.188854Z","end":"2026-10-15T11:48:26.424408Z","steps":["trace[201260656] 'agreement among raft nodes before linearized reading' (duration: 233.937637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:26.423996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.784884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737348"} {"level":"info","ts":"2026-10-15T11:48:26.424688Z","caller":"traceutil/trace.go:171","msg":"trace[2099011520] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16050; }","duration":"233.516959ms","start":"2026-10-15T11:48:26.191149Z","end":"2026-10-15T11:48:26.424666Z","steps":["trace[2099011520] 'agreement among raft nodes before linearized reading' (duration: 231.710045ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:50:38.581215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14565} {"level":"info","ts":"2026-10-15T11:50:38.665733Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14565,"took":"81.301399ms","hash":3014266348,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T11:50:38.665817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3014266348,"revision":14565,"compact-revision":12559} {"level":"warn","ts":"2026-10-15T11:54:24.837651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.012433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T11:54:24.837781Z","caller":"traceutil/trace.go:171","msg":"trace[842451220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18674; }","duration":"147.202127ms","start":"2026-10-15T11:54:24.690549Z","end":"2026-10-15T11:54:24.837751Z","steps":["trace[842451220] 'range keys from in-memory index tree' (duration: 146.795999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:54:24.838641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.84644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T11:54:24.838726Z","caller":"traceutil/trace.go:171","msg":"trace[1660350227] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18674; }","duration":"147.954321ms","start":"2026-10-15T11:54:24.690755Z","end":"2026-10-15T11:54:24.838709Z","steps":["trace[1660350227] 'range keys from in-memory index tree' (duration: 147.092075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:54:31.885271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.733983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T11:54:31.88564Z","caller":"traceutil/trace.go:171","msg":"trace[711099665] transaction","detail":"{read_only:false; response_revision:18718; number_of_response:1; }","duration":"154.700339ms","start":"2026-10-15T11:54:31.730914Z","end":"2026-10-15T11:54:31.885614Z","steps":["trace[711099665] 'process raft request' (duration: 154.585236ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:54:31.885781Z","caller":"traceutil/trace.go:171","msg":"trace[819923957] transaction","detail":"{read_only:false; response_revision:18717; number_of_response:1; }","duration":"167.739814ms","start":"2026-10-15T11:54:31.717983Z","end":"2026-10-15T11:54:31.885722Z","steps":["trace[819923957] 'process raft request' (duration: 48.39269ms)","trace[819923957] 'compare' (duration: 118.525949ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:54:32.446768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.516262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.139\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-15T11:54:32.446863Z","caller":"traceutil/trace.go:171","msg":"trace[1122514886] range","detail":"{range_begin:/registry/masterleases/199.204.45.139; range_end:; response_count:1; response_revision:18719; }","duration":"224.681655ms","start":"2026-10-15T11:54:32.22216Z","end":"2026-10-15T11:54:32.446842Z","steps":["trace[1122514886] 'range keys from in-memory index tree' (duration: 224.294857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:54:32.447145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.679888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T11:54:32.447281Z","caller":"traceutil/trace.go:171","msg":"trace[1615350940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18719; }","duration":"188.858002ms","start":"2026-10-15T11:54:32.258399Z","end":"2026-10-15T11:54:32.447257Z","steps":["trace[1615350940] 'range keys from in-memory index tree' (duration: 188.305251ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:54:32.912527Z","caller":"traceutil/trace.go:171","msg":"trace[1811777529] linearizableReadLoop","detail":"{readStateIndex:19363; appliedIndex:19361; }","duration":"330.787279ms","start":"2026-10-15T11:54:32.581719Z","end":"2026-10-15T11:54:32.912506Z","steps":["trace[1811777529] 'read index received' (duration: 330.526974ms)","trace[1811777529] 'applied index is now lower than readState.Index' (duration: 258.495µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:54:32.912576Z","caller":"traceutil/trace.go:171","msg":"trace[1459220126] transaction","detail":"{read_only:false; response_revision:18721; number_of_response:1; }","duration":"436.913445ms","start":"2026-10-15T11:54:32.475614Z","end":"2026-10-15T11:54:32.912527Z","steps":["trace[1459220126] 'process raft request' (duration: 436.620689ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:54:32.912626Z","caller":"traceutil/trace.go:171","msg":"trace[643156889] transaction","detail":"{read_only:false; response_revision:18722; number_of_response:1; }","duration":"432.845198ms","start":"2026-10-15T11:54:32.479729Z","end":"2026-10-15T11:54:32.912575Z","steps":["trace[643156889] 'process raft request' (duration: 432.680995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:54:32.91278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.051765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T11:54:32.912837Z","caller":"traceutil/trace.go:171","msg":"trace[1979354419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18722; }","duration":"331.170247ms","start":"2026-10-15T11:54:32.581649Z","end":"2026-10-15T11:54:32.912819Z","steps":["trace[1979354419] 'agreement among raft nodes before linearized reading' (duration: 330.966733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:54:32.912838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:54:32.475584Z","time spent":"437.085498ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:54:32.91287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:54:32.581624Z","time spent":"331.237778ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T11:54:32.912886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:54:32.479702Z","time spent":"433.034723ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:54:32.913622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.037192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zr66l\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-10-15T11:54:32.913797Z","caller":"traceutil/trace.go:171","msg":"trace[1563396916] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zr66l; range_end:; response_count:1; response_revision:18722; }","duration":"208.911428ms","start":"2026-10-15T11:54:32.704826Z","end":"2026-10-15T11:54:32.913738Z","steps":["trace[1563396916] 'agreement among raft nodes before linearized reading' (duration: 207.867409ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:54:33.032104Z","caller":"traceutil/trace.go:171","msg":"trace[1448491667] transaction","detail":"{read_only:false; response_revision:18724; number_of_response:1; }","duration":"113.100137ms","start":"2026-10-15T11:54:32.918967Z","end":"2026-10-15T11:54:33.032067Z","steps":["trace[1448491667] 'process raft request' (duration: 64.097186ms)","trace[1448491667] 'compare' (duration: 48.797087ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:54:33.03271Z","caller":"traceutil/trace.go:171","msg":"trace[1654179534] transaction","detail":"{read_only:false; response_revision:18725; number_of_response:1; }","duration":"109.585091ms","start":"2026-10-15T11:54:32.923109Z","end":"2026-10-15T11:54:33.032694Z","steps":["trace[1654179534] 'process raft request' (duration: 108.904077ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:54:33.032674Z","caller":"traceutil/trace.go:171","msg":"trace[1298078819] linearizableReadLoop","detail":"{readStateIndex:19365; appliedIndex:19363; }","duration":"113.056966ms","start":"2026-10-15T11:54:32.919589Z","end":"2026-10-15T11:54:33.032646Z","steps":["trace[1298078819] 'read index received' (duration: 21.856141ms)","trace[1298078819] 'applied index is now lower than readState.Index' (duration: 91.199505ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:54:33.03301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.390073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T11:54:33.033069Z","caller":"traceutil/trace.go:171","msg":"trace[2123646087] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:18726; }","duration":"113.474265ms","start":"2026-10-15T11:54:32.919582Z","end":"2026-10-15T11:54:33.033056Z","steps":["trace[2123646087] 'agreement among raft nodes before linearized reading' (duration: 113.157698ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:54:33.033383Z","caller":"traceutil/trace.go:171","msg":"trace[1274392167] transaction","detail":"{read_only:false; response_revision:18726; number_of_response:1; }","duration":"107.639244ms","start":"2026-10-15T11:54:32.925702Z","end":"2026-10-15T11:54:33.033342Z","steps":["trace[1274392167] 'process raft request' (duration: 106.819538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:54:33.034237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.272325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946038"} {"level":"info","ts":"2026-10-15T11:54:33.034303Z","caller":"traceutil/trace.go:171","msg":"trace[1527711398] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18726; }","duration":"109.385366ms","start":"2026-10-15T11:54:32.924906Z","end":"2026-10-15T11:54:33.034292Z","steps":["trace[1527711398] 'agreement among raft nodes before linearized reading' (duration: 108.258116ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:55:23.242074Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5739a4baaf9cb6ee","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T11:55:23.248748Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T11:55:23.248886Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T11:55:38.593964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16946} {"level":"info","ts":"2026-10-15T11:55:38.701755Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16946,"took":"103.250221ms","hash":3183332766,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":33058816,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T11:55:38.701849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3183332766,"revision":16946,"compact-revision":14565} {"level":"info","ts":"2026-10-15T12:00:08.230979Z","caller":"traceutil/trace.go:171","msg":"trace[2088762881] transaction","detail":"{read_only:false; response_revision:21400; number_of_response:1; }","duration":"143.445428ms","start":"2026-10-15T12:00:08.087508Z","end":"2026-10-15T12:00:08.230953Z","steps":["trace[2088762881] 'process raft request' (duration: 143.312485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:00:08.464297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.082823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:00:08.464408Z","caller":"traceutil/trace.go:171","msg":"trace[1248624515] linearizableReadLoop","detail":"{readStateIndex:22130; appliedIndex:22128; }","duration":"256.971811ms","start":"2026-10-15T12:00:08.207418Z","end":"2026-10-15T12:00:08.46439Z","steps":["trace[1248624515] 'read index received' (duration: 23.516475ms)","trace[1248624515] 'applied index is now lower than readState.Index' (duration: 233.454106ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:00:08.464637Z","caller":"traceutil/trace.go:171","msg":"trace[409277404] transaction","detail":"{read_only:false; response_revision:21401; number_of_response:1; }","duration":"338.045028ms","start":"2026-10-15T12:00:08.126579Z","end":"2026-10-15T12:00:08.464624Z","steps":["trace[409277404] 'process raft request' (duration: 186.546606ms)","trace[409277404] 'compare' (duration: 150.971892ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:00:08.4647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:00:08.126551Z","time spent":"338.118409ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:00:08.464943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.486351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:00:08.465045Z","caller":"traceutil/trace.go:171","msg":"trace[985983384] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:21401; }","duration":"257.654774ms","start":"2026-10-15T12:00:08.20737Z","end":"2026-10-15T12:00:08.465025Z","steps":["trace[985983384] 'agreement among raft nodes before linearized reading' (duration: 257.454581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:00:08.712375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.437535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:00:08.712521Z","caller":"traceutil/trace.go:171","msg":"trace[288514561] linearizableReadLoop","detail":"{readStateIndex:22131; appliedIndex:22130; }","duration":"248.071482ms","start":"2026-10-15T12:00:08.464431Z","end":"2026-10-15T12:00:08.712503Z","steps":["trace[288514561] 'read index received' (duration: 139.338601ms)","trace[288514561] 'applied index is now lower than readState.Index' (duration: 108.731751ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:00:08.712572Z","caller":"traceutil/trace.go:171","msg":"trace[889361478] transaction","detail":"{read_only:false; response_revision:21402; number_of_response:1; }","duration":"304.750267ms","start":"2026-10-15T12:00:08.407781Z","end":"2026-10-15T12:00:08.712531Z","steps":["trace[889361478] 'process raft request' (duration: 196.051387ms)","trace[889361478] 'compare' (duration: 108.287392ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:00:08.712743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.708856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T12:00:08.71274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:00:08.407755Z","time spent":"304.90034ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:00:08.712787Z","caller":"traceutil/trace.go:171","msg":"trace[1408523177] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:21402; }","duration":"332.758707ms","start":"2026-10-15T12:00:08.380016Z","end":"2026-10-15T12:00:08.712775Z","steps":["trace[1408523177] 'agreement among raft nodes before linearized reading' (duration: 332.668026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:00:08.712852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:00:08.379948Z","time spent":"332.892221ms","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:00:08.712876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.005699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-10-15T12:00:08.712963Z","caller":"traceutil/trace.go:171","msg":"trace[560808707] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:21402; }","duration":"374.135402ms","start":"2026-10-15T12:00:08.338808Z","end":"2026-10-15T12:00:08.712944Z","steps":["trace[560808707] 'agreement among raft nodes before linearized reading' (duration: 373.905727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:00:08.713008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:00:08.338785Z","time spent":"374.214793ms","remote":"127.0.0.1:47376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11858,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-15T12:00:08.714365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.389532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T12:00:08.714422Z","caller":"traceutil/trace.go:171","msg":"trace[1907955324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21403; }","duration":"216.485894ms","start":"2026-10-15T12:00:08.49792Z","end":"2026-10-15T12:00:08.714406Z","steps":["trace[1907955324] 'agreement among raft nodes before linearized reading' (duration: 216.28537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:00:08.714643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.525967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-15T12:00:08.714741Z","caller":"traceutil/trace.go:171","msg":"trace[15395326] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:21403; }","duration":"118.687949ms","start":"2026-10-15T12:00:08.596032Z","end":"2026-10-15T12:00:08.71472Z","steps":["trace[15395326] 'agreement among raft nodes before linearized reading' (duration: 118.349403ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:00:08.8765Z","caller":"traceutil/trace.go:171","msg":"trace[181393768] linearizableReadLoop","detail":"{readStateIndex:22133; appliedIndex:22132; }","duration":"156.061559ms","start":"2026-10-15T12:00:08.720381Z","end":"2026-10-15T12:00:08.876442Z","steps":["trace[181393768] 'read index received' (duration: 141.451572ms)","trace[181393768] 'applied index is now lower than readState.Index' (duration: 14.608927ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:00:08.876573Z","caller":"traceutil/trace.go:171","msg":"trace[1636822738] transaction","detail":"{read_only:false; response_revision:21404; number_of_response:1; }","duration":"157.789591ms","start":"2026-10-15T12:00:08.718746Z","end":"2026-10-15T12:00:08.876535Z","steps":["trace[1636822738] 'process raft request' (duration: 143.045492ms)","trace[1636822738] 'compare' (duration: 14.507625ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:00:08.876748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.340995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-10-15T12:00:08.876848Z","caller":"traceutil/trace.go:171","msg":"trace[441599598] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:21404; }","duration":"156.485068ms","start":"2026-10-15T12:00:08.720342Z","end":"2026-10-15T12:00:08.876827Z","steps":["trace[441599598] 'agreement among raft nodes before linearized reading' (duration: 156.231853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:00:09.161298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.139914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T12:00:09.161381Z","caller":"traceutil/trace.go:171","msg":"trace[46899364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21404; }","duration":"123.271096ms","start":"2026-10-15T12:00:09.038095Z","end":"2026-10-15T12:00:09.161366Z","steps":["trace[46899364] 'range keys from in-memory index tree' (duration: 123.000011ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:00:09.163941Z","caller":"traceutil/trace.go:171","msg":"trace[1521498365] transaction","detail":"{read_only:false; response_revision:21405; number_of_response:1; }","duration":"117.337274ms","start":"2026-10-15T12:00:09.046568Z","end":"2026-10-15T12:00:09.163905Z","steps":["trace[1521498365] 'process raft request' (duration: 117.157491ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:00:09.366846Z","caller":"traceutil/trace.go:171","msg":"trace[1203410762] linearizableReadLoop","detail":"{readStateIndex:22136; appliedIndex:22135; }","duration":"150.2942ms","start":"2026-10-15T12:00:09.21652Z","end":"2026-10-15T12:00:09.366815Z","steps":["trace[1203410762] 'read index received' (duration: 110.639458ms)","trace[1203410762] 'applied index is now lower than readState.Index' (duration: 39.653932ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:00:09.366959Z","caller":"traceutil/trace.go:171","msg":"trace[2110746354] transaction","detail":"{read_only:false; response_revision:21407; number_of_response:1; }","duration":"151.297687ms","start":"2026-10-15T12:00:09.215653Z","end":"2026-10-15T12:00:09.366951Z","steps":["trace[2110746354] 'process raft request' (duration: 111.490293ms)","trace[2110746354] 'compare' (duration: 39.110031ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:00:09.367169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.623116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-10-15T12:00:09.367301Z","caller":"traceutil/trace.go:171","msg":"trace[1866065221] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:21407; }","duration":"150.789678ms","start":"2026-10-15T12:00:09.216487Z","end":"2026-10-15T12:00:09.367277Z","steps":["trace[1866065221] 'agreement among raft nodes before linearized reading' (duration: 150.536033ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:00:09.371278Z","caller":"traceutil/trace.go:171","msg":"trace[430171461] transaction","detail":"{read_only:false; response_revision:21408; number_of_response:1; }","duration":"153.368647ms","start":"2026-10-15T12:00:09.217885Z","end":"2026-10-15T12:00:09.371253Z","steps":["trace[430171461] 'process raft request' (duration: 152.787666ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:00:38.601062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19452} {"level":"info","ts":"2026-10-15T12:00:38.745131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19452,"took":"139.463748ms","hash":3985526418,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":30064640,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T12:00:38.745238Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3985526418,"revision":19452,"compact-revision":16946} {"level":"info","ts":"2026-10-15T12:04:20.992061Z","caller":"traceutil/trace.go:171","msg":"trace[1341872589] transaction","detail":"{read_only:false; response_revision:23977; number_of_response:1; }","duration":"236.975442ms","start":"2026-10-15T12:04:20.755027Z","end":"2026-10-15T12:04:20.992003Z","steps":["trace[1341872589] 'process raft request' (duration: 233.439335ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:05:38.608152Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21604} {"level":"info","ts":"2026-10-15T12:05:38.758411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21604,"took":"145.140454ms","hash":3552326119,"current-db-size-bytes":56827904,"current-db-size":"57 MB","current-db-size-in-use-bytes":44380160,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-15T12:05:38.758732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3552326119,"revision":21604,"compact-revision":19452} {"level":"info","ts":"2026-10-15T12:07:32.222027Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000685f.wal"} {"level":"warn","ts":"2026-10-15T12:07:52.120583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.548549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:07:52.120716Z","caller":"traceutil/trace.go:171","msg":"trace[1005513886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26080; }","duration":"175.723572ms","start":"2026-10-15T12:07:51.944965Z","end":"2026-10-15T12:07:52.120689Z","steps":["trace[1005513886] 'range keys from in-memory index tree' (duration: 175.424176ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:52.602819Z","caller":"traceutil/trace.go:171","msg":"trace[1201041444] transaction","detail":"{read_only:false; response_revision:26082; number_of_response:1; }","duration":"251.004408ms","start":"2026-10-15T12:07:52.35176Z","end":"2026-10-15T12:07:52.602765Z","steps":["trace[1201041444] 'process raft request' (duration: 182.454671ms)","trace[1201041444] 'compare' (duration: 68.014227ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:07:52.602857Z","caller":"traceutil/trace.go:171","msg":"trace[514476785] transaction","detail":"{read_only:false; response_revision:26083; number_of_response:1; }","duration":"250.897816ms","start":"2026-10-15T12:07:52.351923Z","end":"2026-10-15T12:07:52.602821Z","steps":["trace[514476785] 'process raft request' (duration: 250.707892ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:52.602963Z","caller":"traceutil/trace.go:171","msg":"trace[1580056351] linearizableReadLoop","detail":"{readStateIndex:26944; appliedIndex:26941; }","duration":"222.177382ms","start":"2026-10-15T12:07:52.38075Z","end":"2026-10-15T12:07:52.602927Z","steps":["trace[1580056351] 'read index received' (duration: 153.527843ms)","trace[1580056351] 'applied index is now lower than readState.Index' (duration: 68.647629ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:07:52.603166Z","caller":"traceutil/trace.go:171","msg":"trace[1941133646] transaction","detail":"{read_only:false; response_revision:26084; number_of_response:1; }","duration":"236.88603ms","start":"2026-10-15T12:07:52.366263Z","end":"2026-10-15T12:07:52.603149Z","steps":["trace[1941133646] 'process raft request' (duration: 236.491493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:52.603601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.472852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T12:07:52.60366Z","caller":"traceutil/trace.go:171","msg":"trace[859775845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26084; }","duration":"217.548114ms","start":"2026-10-15T12:07:52.386097Z","end":"2026-10-15T12:07:52.603645Z","steps":["trace[859775845] 'agreement among raft nodes before linearized reading' (duration: 217.252449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:52.603822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.045768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T12:07:52.603923Z","caller":"traceutil/trace.go:171","msg":"trace[1733547840] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26084; }","duration":"223.196171ms","start":"2026-10-15T12:07:52.380703Z","end":"2026-10-15T12:07:52.603899Z","steps":["trace[1733547840] 'agreement among raft nodes before linearized reading' (duration: 222.637921ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:08:54.449017Z","caller":"traceutil/trace.go:171","msg":"trace[250711158] transaction","detail":"{read_only:false; response_revision:26643; number_of_response:1; }","duration":"121.08598ms","start":"2026-10-15T12:08:54.327887Z","end":"2026-10-15T12:08:54.448973Z","steps":["trace[250711158] 'process raft request' (duration: 96.75761ms)","trace[250711158] 'compare' (duration: 24.150227ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:08:54.714328Z","caller":"traceutil/trace.go:171","msg":"trace[1134374765] transaction","detail":"{read_only:false; response_revision:26644; number_of_response:1; }","duration":"103.818713ms","start":"2026-10-15T12:08:54.610472Z","end":"2026-10-15T12:08:54.71429Z","steps":["trace[1134374765] 'process raft request' (duration: 103.576528ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:08:55.071252Z","caller":"traceutil/trace.go:171","msg":"trace[2058102912] linearizableReadLoop","detail":"{readStateIndex:27519; appliedIndex:27518; }","duration":"126.812169ms","start":"2026-10-15T12:08:54.944405Z","end":"2026-10-15T12:08:55.071217Z","steps":["trace[2058102912] 'read index received' (duration: 126.477022ms)","trace[2058102912] 'applied index is now lower than readState.Index' (duration: 332.937µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:08:55.071308Z","caller":"traceutil/trace.go:171","msg":"trace[102287434] transaction","detail":"{read_only:false; response_revision:26645; number_of_response:1; }","duration":"135.913261ms","start":"2026-10-15T12:08:54.935361Z","end":"2026-10-15T12:08:55.071274Z","steps":["trace[102287434] 'process raft request' (duration: 135.565414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:08:55.071543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.112374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:08:55.071651Z","caller":"traceutil/trace.go:171","msg":"trace[1454509439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26645; }","duration":"127.235646ms","start":"2026-10-15T12:08:54.944386Z","end":"2026-10-15T12:08:55.071622Z","steps":["trace[1454509439] 'agreement among raft nodes before linearized reading' (duration: 127.013312ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:08:55.234239Z","caller":"traceutil/trace.go:171","msg":"trace[887476363] linearizableReadLoop","detail":"{readStateIndex:27520; appliedIndex:27519; }","duration":"162.85824ms","start":"2026-10-15T12:08:55.07135Z","end":"2026-10-15T12:08:55.234208Z","steps":["trace[887476363] 'read index received' (duration: 143.118277ms)","trace[887476363] 'applied index is now lower than readState.Index' (duration: 19.720202ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:08:55.234839Z","caller":"traceutil/trace.go:171","msg":"trace[278767403] transaction","detail":"{read_only:false; response_revision:26646; number_of_response:1; }","duration":"250.445975ms","start":"2026-10-15T12:08:54.984369Z","end":"2026-10-15T12:08:55.234815Z","steps":["trace[278767403] 'process raft request' (duration: 230.203934ms)","trace[278767403] 'compare' (duration: 19.492688ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:08:55.234898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.280355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-15T12:08:55.234896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.183714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T12:08:55.234989Z","caller":"traceutil/trace.go:171","msg":"trace[1363895473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26646; }","duration":"219.413528ms","start":"2026-10-15T12:08:55.01555Z","end":"2026-10-15T12:08:55.234963Z","steps":["trace[1363895473] 'agreement among raft nodes before linearized reading' (duration: 219.142103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:08:55.234973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.027374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:08:55.235072Z","caller":"traceutil/trace.go:171","msg":"trace[530253902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26646; }","duration":"162.188897ms","start":"2026-10-15T12:08:55.072861Z","end":"2026-10-15T12:08:55.23505Z","steps":["trace[530253902] 'agreement among raft nodes before linearized reading' (duration: 161.996043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:08:55.235087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.208934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T12:08:55.235056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.566675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T12:08:55.235125Z","caller":"traceutil/trace.go:171","msg":"trace[1016969922] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:26646; }","duration":"283.298506ms","start":"2026-10-15T12:08:54.951815Z","end":"2026-10-15T12:08:55.235113Z","steps":["trace[1016969922] 'agreement among raft nodes before linearized reading' (duration: 283.230795ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:08:55.23516Z","caller":"traceutil/trace.go:171","msg":"trace[1566855960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26646; }","duration":"289.737079ms","start":"2026-10-15T12:08:54.945397Z","end":"2026-10-15T12:08:55.235134Z","steps":["trace[1566855960] 'agreement among raft nodes before linearized reading' (duration: 289.430333ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:08:55.235014Z","caller":"traceutil/trace.go:171","msg":"trace[786964829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26646; }","duration":"283.385998ms","start":"2026-10-15T12:08:54.951602Z","end":"2026-10-15T12:08:55.234988Z","steps":["trace[786964829] 'agreement among raft nodes before linearized reading' (duration: 283.074342ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:10:38.617239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24890} {"level":"info","ts":"2026-10-15T12:10:38.772697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24890,"took":"150.686034ms","hash":2192569994,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":36442112,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T12:10:38.772773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2192569994,"revision":24890,"compact-revision":21604} {"level":"info","ts":"2026-10-15T12:12:56.928203Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5739a4baaf9cb6ee","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T12:12:56.933967Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T12:12:56.934105Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T12:15:38.625754Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27671} {"level":"info","ts":"2026-10-15T12:15:38.768231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27671,"took":"137.825712ms","hash":2622518929,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":37220352,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-15T12:15:38.768352Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2622518929,"revision":27671,"compact-revision":24890} {"level":"info","ts":"2026-10-15T12:20:38.640789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30309} {"level":"info","ts":"2026-10-15T12:20:38.800168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30309,"took":"153.977146ms","hash":3067320385,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":30494720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T12:20:38.800254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3067320385,"revision":30309,"compact-revision":27671} {"level":"warn","ts":"2026-10-15T12:21:12.089768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.389699ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:12.089947Z","caller":"traceutil/trace.go:171","msg":"trace[1483898410] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:32797; }","duration":"281.601353ms","start":"2026-10-15T12:21:11.808317Z","end":"2026-10-15T12:21:12.089919Z","steps":["trace[1483898410] 'range keys from in-memory index tree' (duration: 281.366077ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:12.090322Z","caller":"traceutil/trace.go:171","msg":"trace[2052376983] linearizableReadLoop","detail":"{readStateIndex:33839; appliedIndex:33838; }","duration":"307.418572ms","start":"2026-10-15T12:21:11.782858Z","end":"2026-10-15T12:21:12.090276Z","steps":["trace[2052376983] 'read index received' (duration: 286.69099ms)","trace[2052376983] 'applied index is now lower than readState.Index' (duration: 20.725852ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:12.09082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.923671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T12:21:12.090497Z","caller":"traceutil/trace.go:171","msg":"trace[195849349] transaction","detail":"{read_only:false; response_revision:32798; number_of_response:1; }","duration":"330.342596ms","start":"2026-10-15T12:21:11.760081Z","end":"2026-10-15T12:21:12.090424Z","steps":["trace[195849349] 'process raft request' (duration: 309.472271ms)","trace[195849349] 'compare' (duration: 20.489467ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:12.090934Z","caller":"traceutil/trace.go:171","msg":"trace[74613149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:32798; }","duration":"308.097855ms","start":"2026-10-15T12:21:11.782807Z","end":"2026-10-15T12:21:12.090905Z","steps":["trace[74613149] 'agreement among raft nodes before linearized reading' (duration: 307.570465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:12.091331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:11.782784Z","time spent":"308.528233ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T12:21:12.091416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:11.760059Z","time spent":"331.104741ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:12.114545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.395223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T12:21:12.11466Z","caller":"traceutil/trace.go:171","msg":"trace[2085353528] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32799; }","duration":"315.586966ms","start":"2026-10-15T12:21:11.799026Z","end":"2026-10-15T12:21:12.114613Z","steps":["trace[2085353528] 'agreement among raft nodes before linearized reading' (duration: 315.282461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:12.114707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:11.799014Z","time spent":"315.679558ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T12:21:12.114994Z","caller":"traceutil/trace.go:171","msg":"trace[478329358] transaction","detail":"{read_only:false; response_revision:32799; number_of_response:1; }","duration":"178.891838ms","start":"2026-10-15T12:21:11.936073Z","end":"2026-10-15T12:21:12.114965Z","steps":["trace[478329358] 'process raft request' (duration: 177.937331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:12.115102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.025667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T12:21:12.115171Z","caller":"traceutil/trace.go:171","msg":"trace[296434874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32799; }","duration":"304.117399ms","start":"2026-10-15T12:21:11.811039Z","end":"2026-10-15T12:21:12.115156Z","steps":["trace[296434874] 'agreement among raft nodes before linearized reading' (duration: 303.942295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:12.115259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:11.811025Z","time spent":"304.21992ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T12:21:17.57701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13181650452495544193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-15T12:21:17.630938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:17.109503Z","time spent":"521.427645ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-15T12:21:17.631318Z","caller":"traceutil/trace.go:171","msg":"trace[1213426984] transaction","detail":"{read_only:false; response_revision:32840; number_of_response:1; }","duration":"570.572666ms","start":"2026-10-15T12:21:17.060722Z","end":"2026-10-15T12:21:17.631295Z","steps":["trace[1213426984] 'process raft request' (duration: 570.427073ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:17.63139Z","caller":"traceutil/trace.go:171","msg":"trace[2112580070] transaction","detail":"{read_only:false; response_revision:32839; number_of_response:1; }","duration":"570.544945ms","start":"2026-10-15T12:21:17.060724Z","end":"2026-10-15T12:21:17.631269Z","steps":["trace[2112580070] 'process raft request' (duration: 543.499693ms)","trace[2112580070] 'compare' (duration: 26.044743ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:17.631415Z","caller":"traceutil/trace.go:171","msg":"trace[423178724] linearizableReadLoop","detail":"{readStateIndex:33883; appliedIndex:33880; }","duration":"555.016571ms","start":"2026-10-15T12:21:17.07636Z","end":"2026-10-15T12:21:17.631376Z","steps":["trace[423178724] 'read index received' (duration: 527.962339ms)","trace[423178724] 'applied index is now lower than readState.Index' (duration: 27.052412ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:17.631454Z","caller":"traceutil/trace.go:171","msg":"trace[1007436676] transaction","detail":"{read_only:false; response_revision:32841; number_of_response:1; }","duration":"409.516036ms","start":"2026-10-15T12:21:17.22188Z","end":"2026-10-15T12:21:17.631396Z","steps":["trace[1007436676] 'process raft request' (duration: 409.370733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:17.631516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:17.060697Z","time spent":"570.736308ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:17.631659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:17.221856Z","time spent":"409.669828ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:17.631609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:17.060697Z","time spent":"570.80872ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:17.631701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.321467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:17.631784Z","caller":"traceutil/trace.go:171","msg":"trace[641936060] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:32841; }","duration":"555.47108ms","start":"2026-10-15T12:21:17.076292Z","end":"2026-10-15T12:21:17.631763Z","steps":["trace[641936060] 'agreement among raft nodes before linearized reading' (duration: 555.242565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:17.631832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:17.076267Z","time spent":"555.551591ms","remote":"127.0.0.1:41886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"info","ts":"2026-10-15T12:21:20.413325Z","caller":"traceutil/trace.go:171","msg":"trace[349380109] transaction","detail":"{read_only:false; response_revision:32861; number_of_response:1; }","duration":"120.663385ms","start":"2026-10-15T12:21:20.292632Z","end":"2026-10-15T12:21:20.413295Z","steps":["trace[349380109] 'process raft request' (duration: 120.543303ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:20.413695Z","caller":"traceutil/trace.go:171","msg":"trace[2049884887] transaction","detail":"{read_only:false; response_revision:32860; number_of_response:1; }","duration":"121.131344ms","start":"2026-10-15T12:21:20.292524Z","end":"2026-10-15T12:21:20.413656Z","steps":["trace[2049884887] 'process raft request' (duration: 73.869699ms)","trace[2049884887] 'compare' (duration: 46.403738ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:22.422285Z","caller":"traceutil/trace.go:171","msg":"trace[123425323] transaction","detail":"{read_only:false; response_revision:32872; number_of_response:1; }","duration":"128.83765ms","start":"2026-10-15T12:21:22.293413Z","end":"2026-10-15T12:21:22.422251Z","steps":["trace[123425323] 'process raft request' (duration: 63.650525ms)","trace[123425323] 'compare' (duration: 65.021162ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:23.079361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.974966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:23.079457Z","caller":"traceutil/trace.go:171","msg":"trace[1584700471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32879; }","duration":"135.123299ms","start":"2026-10-15T12:21:22.944311Z","end":"2026-10-15T12:21:23.079434Z","steps":["trace[1584700471] 'range keys from in-memory index tree' (duration: 134.827233ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:23.931282Z","caller":"traceutil/trace.go:171","msg":"trace[761843232] transaction","detail":"{read_only:false; response_revision:32882; number_of_response:1; }","duration":"164.025497ms","start":"2026-10-15T12:21:23.767232Z","end":"2026-10-15T12:21:23.931258Z","steps":["trace[761843232] 'process raft request' (duration: 163.834513ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:23.951588Z","caller":"traceutil/trace.go:171","msg":"trace[2130959657] transaction","detail":"{read_only:false; response_revision:32883; number_of_response:1; }","duration":"140.078962ms","start":"2026-10-15T12:21:23.81149Z","end":"2026-10-15T12:21:23.951569Z","steps":["trace[2130959657] 'process raft request' (duration: 139.849588ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:28.153295Z","caller":"traceutil/trace.go:171","msg":"trace[2117986747] transaction","detail":"{read_only:false; response_revision:32914; number_of_response:1; }","duration":"115.979256ms","start":"2026-10-15T12:21:28.037285Z","end":"2026-10-15T12:21:28.153265Z","steps":["trace[2117986747] 'process raft request' (duration: 91.262338ms)","trace[2117986747] 'compare' (duration: 24.235949ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:28.609275Z","caller":"traceutil/trace.go:171","msg":"trace[2082248205] transaction","detail":"{read_only:false; response_revision:32916; number_of_response:1; }","duration":"220.825643ms","start":"2026-10-15T12:21:28.388426Z","end":"2026-10-15T12:21:28.609252Z","steps":["trace[2082248205] 'process raft request' (duration: 220.723501ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:28.609318Z","caller":"traceutil/trace.go:171","msg":"trace[1278357086] transaction","detail":"{read_only:false; response_revision:32915; number_of_response:1; }","duration":"449.126566ms","start":"2026-10-15T12:21:28.160149Z","end":"2026-10-15T12:21:28.609276Z","steps":["trace[1278357086] 'process raft request' (duration: 395.48827ms)","trace[1278357086] 'compare' (duration: 52.944733ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:28.609448Z","caller":"traceutil/trace.go:171","msg":"trace[138170090] linearizableReadLoop","detail":"{readStateIndex:33960; appliedIndex:33959; }","duration":"357.934728ms","start":"2026-10-15T12:21:28.251504Z","end":"2026-10-15T12:21:28.609439Z","steps":["trace[138170090] 'read index received' (duration: 304.11346ms)","trace[138170090] 'applied index is now lower than readState.Index' (duration: 53.820698ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:28.609498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:28.16012Z","time spent":"449.275449ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:28.609604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.095531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-15T12:21:28.609628Z","caller":"traceutil/trace.go:171","msg":"trace[464856849] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:32917; }","duration":"358.134893ms","start":"2026-10-15T12:21:28.251486Z","end":"2026-10-15T12:21:28.609621Z","steps":["trace[464856849] 'agreement among raft nodes before linearized reading' (duration: 358.018791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:28.609647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:28.251426Z","time spent":"358.215404ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-15T12:21:28.610039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.222693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-15T12:21:28.610065Z","caller":"traceutil/trace.go:171","msg":"trace[427816251] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:32917; }","duration":"161.289524ms","start":"2026-10-15T12:21:28.448768Z","end":"2026-10-15T12:21:28.610058Z","steps":["trace[427816251] 'agreement among raft nodes before linearized reading' (duration: 160.947548ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:30.825357Z","caller":"traceutil/trace.go:171","msg":"trace[327789718] transaction","detail":"{read_only:false; response_revision:32935; number_of_response:1; }","duration":"122.938418ms","start":"2026-10-15T12:21:30.702392Z","end":"2026-10-15T12:21:30.82533Z","steps":["trace[327789718] 'process raft request' (duration: 122.805455ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:30.825762Z","caller":"traceutil/trace.go:171","msg":"trace[290176985] transaction","detail":"{read_only:false; response_revision:32934; number_of_response:1; }","duration":"123.599401ms","start":"2026-10-15T12:21:30.702149Z","end":"2026-10-15T12:21:30.825748Z","steps":["trace[290176985] 'process raft request' (duration: 118.27754ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:30.825739Z","caller":"traceutil/trace.go:171","msg":"trace[1193855089] transaction","detail":"{read_only:false; response_revision:32936; number_of_response:1; }","duration":"122.580321ms","start":"2026-10-15T12:21:30.703126Z","end":"2026-10-15T12:21:30.825706Z","steps":["trace[1193855089] 'process raft request' (duration: 122.126023ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:30.826106Z","caller":"traceutil/trace.go:171","msg":"trace[1405857013] transaction","detail":"{read_only:false; response_revision:32937; number_of_response:1; }","duration":"120.670566ms","start":"2026-10-15T12:21:30.705421Z","end":"2026-10-15T12:21:30.826091Z","steps":["trace[1405857013] 'process raft request' (duration: 120.157746ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:32.933629Z","caller":"traceutil/trace.go:171","msg":"trace[832645154] linearizableReadLoop","detail":"{readStateIndex:33993; appliedIndex:33992; }","duration":"103.823766ms","start":"2026-10-15T12:21:32.829782Z","end":"2026-10-15T12:21:32.933606Z","steps":["trace[832645154] 'read index received' (duration: 100.914471ms)","trace[832645154] 'applied index is now lower than readState.Index' (duration: 2.908065ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:32.933749Z","caller":"traceutil/trace.go:171","msg":"trace[1892452071] transaction","detail":"{read_only:false; response_revision:32947; number_of_response:1; }","duration":"198.430818ms","start":"2026-10-15T12:21:32.735308Z","end":"2026-10-15T12:21:32.933739Z","steps":["trace[1892452071] 'process raft request' (duration: 195.480582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:32.934221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.371427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T12:21:32.934405Z","caller":"traceutil/trace.go:171","msg":"trace[1396724518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32947; }","duration":"104.606852ms","start":"2026-10-15T12:21:32.829737Z","end":"2026-10-15T12:21:32.934344Z","steps":["trace[1396724518] 'agreement among raft nodes before linearized reading' (duration: 104.108402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:32.940276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.402572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T12:21:32.940325Z","caller":"traceutil/trace.go:171","msg":"trace[1503234204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:32948; }","duration":"109.471072ms","start":"2026-10-15T12:21:32.830841Z","end":"2026-10-15T12:21:32.940313Z","steps":["trace[1503234204] 'agreement among raft nodes before linearized reading' (duration: 109.385341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:32.940268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.052644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T12:21:32.940401Z","caller":"traceutil/trace.go:171","msg":"trace[867942972] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32948; }","duration":"110.255777ms","start":"2026-10-15T12:21:32.83014Z","end":"2026-10-15T12:21:32.940396Z","steps":["trace[867942972] 'agreement among raft nodes before linearized reading' (duration: 109.978193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:32.940432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.956452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T12:21:32.940572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.627313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-15T12:21:32.940632Z","caller":"traceutil/trace.go:171","msg":"trace[253689627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:32948; }","duration":"110.110164ms","start":"2026-10-15T12:21:32.83044Z","end":"2026-10-15T12:21:32.94055Z","steps":["trace[253689627] 'agreement among raft nodes before linearized reading' (duration: 109.86449ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:32.940654Z","caller":"traceutil/trace.go:171","msg":"trace[822508950] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:32948; }","duration":"103.742025ms","start":"2026-10-15T12:21:32.83689Z","end":"2026-10-15T12:21:32.940632Z","steps":["trace[822508950] 'agreement among raft nodes before linearized reading' (duration: 103.404009ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:34.051766Z","caller":"traceutil/trace.go:171","msg":"trace[323876902] linearizableReadLoop","detail":"{readStateIndex:34001; appliedIndex:34000; }","duration":"164.213409ms","start":"2026-10-15T12:21:33.887533Z","end":"2026-10-15T12:21:34.051746Z","steps":["trace[323876902] 'read index received' (duration: 163.905784ms)","trace[323876902] 'applied index is now lower than readState.Index' (duration: 306.495µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:34.051813Z","caller":"traceutil/trace.go:171","msg":"trace[1300826980] transaction","detail":"{read_only:false; response_revision:32955; number_of_response:1; }","duration":"201.133809ms","start":"2026-10-15T12:21:33.850651Z","end":"2026-10-15T12:21:34.051785Z","steps":["trace[1300826980] 'process raft request' (duration: 200.868215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:34.051947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.395453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:34.052005Z","caller":"traceutil/trace.go:171","msg":"trace[1969897777] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:32955; }","duration":"164.502715ms","start":"2026-10-15T12:21:33.887491Z","end":"2026-10-15T12:21:34.051994Z","steps":["trace[1969897777] 'agreement among raft nodes before linearized reading' (duration: 164.402483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:34.05207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.604377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:34.052118Z","caller":"traceutil/trace.go:171","msg":"trace[501249040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32955; }","duration":"107.691109ms","start":"2026-10-15T12:21:33.944413Z","end":"2026-10-15T12:21:34.052104Z","steps":["trace[501249040] 'agreement among raft nodes before linearized reading' (duration: 107.576197ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:37.194893Z","caller":"traceutil/trace.go:171","msg":"trace[1146932595] transaction","detail":"{read_only:false; response_revision:32980; number_of_response:1; }","duration":"122.202684ms","start":"2026-10-15T12:21:37.072659Z","end":"2026-10-15T12:21:37.194861Z","steps":["trace[1146932595] 'process raft request' (duration: 100.606265ms)","trace[1146932595] 'compare' (duration: 21.367215ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:37.195013Z","caller":"traceutil/trace.go:171","msg":"trace[1887364484] transaction","detail":"{read_only:false; response_revision:32981; number_of_response:1; }","duration":"121.099814ms","start":"2026-10-15T12:21:37.073892Z","end":"2026-10-15T12:21:37.194992Z","steps":["trace[1887364484] 'process raft request' (duration: 120.91031ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:25:38.651653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32557} {"level":"info","ts":"2026-10-15T12:25:38.819778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32557,"took":"162.326784ms","hash":57402097,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":30216192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T12:25:38.819873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":57402097,"revision":32557,"compact-revision":30309} {"level":"info","ts":"2026-10-15T12:30:38.671142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34782} {"level":"info","ts":"2026-10-15T12:30:38.802334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34782,"took":"127.982094ms","hash":1781289838,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":31715328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T12:30:38.802427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1781289838,"revision":34782,"compact-revision":32557} {"level":"info","ts":"2026-10-15T12:34:27.811015Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5739a4baaf9cb6ee","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T12:34:27.816784Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T12:34:27.816904Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T12:35:38.680751Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37075} {"level":"info","ts":"2026-10-15T12:35:38.810357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37075,"took":"125.401964ms","hash":934492438,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":32976896,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T12:35:38.810534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":934492438,"revision":37075,"compact-revision":34782} {"level":"info","ts":"2026-10-15T12:40:38.688026Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39351} {"level":"info","ts":"2026-10-15T12:40:38.812235Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39351,"took":"120.727996ms","hash":3030826504,"current-db-size-bytes":60227584,"current-db-size":"60 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T12:40:38.812281Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3030826504,"revision":39351,"compact-revision":37075}