{"level":"warn","ts":"2026-10-14T17:44:36.033665Z","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-14T17:44:36.033814Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.115:2380","--initial-cluster=instance=https://162.253.55.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.115: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-14T17:44:36.033944Z","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-14T17:44:36.033963Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.115:2380"]} {"level":"info","ts":"2026-10-14T17:44:36.033985Z","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-14T17:44:36.035654Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"]} {"level":"info","ts":"2026-10-14T17:44:36.035956Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.115:2380"],"listen-peer-urls":["https://162.253.55.115:2380"],"advertise-client-urls":["https://162.253.55.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.115: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-14T17:44:36.040791Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.360092ms"} {"level":"info","ts":"2026-10-14T17:44:36.049441Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3fd97315619643d2","cluster-id":"5ce44a81422ca797"} {"level":"info","ts":"2026-10-14T17:44:36.049536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:44:36.049562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became follower at term 0"} {"level":"info","ts":"2026-10-14T17:44:36.049589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3fd97315619643d2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:44:36.0496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became follower at term 1"} {"level":"info","ts":"2026-10-14T17:44:36.049662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=(4600835030004417490)"} {"level":"warn","ts":"2026-10-14T17:44:36.057505Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:44:36.059178Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:44:36.061435Z","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-14T17:44:36.063974Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3fd97315619643d2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:44:36.06413Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3fd97315619643d2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:44:36.06412Z","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-14T17:44:36.064261Z","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-14T17:44:36.064316Z","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-14T17:44:36.067089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=(4600835030004417490)"} {"level":"info","ts":"2026-10-14T17:44:36.067349Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5ce44a81422ca797","local-member-id":"3fd97315619643d2","added-peer-id":"3fd97315619643d2","added-peer-peer-urls":["https://162.253.55.115:2380"]} {"level":"info","ts":"2026-10-14T17:44:36.068362Z","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-14T17:44:36.068611Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.115:2380"} {"level":"info","ts":"2026-10-14T17:44:36.068741Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:44:36.068691Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3fd97315619643d2","initial-advertise-peer-urls":["https://162.253.55.115:2380"],"listen-peer-urls":["https://162.253.55.115:2380"],"advertise-client-urls":["https://162.253.55.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:44:36.068758Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.115:2380"} {"level":"info","ts":"2026-10-14T17:44:36.250625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:44:36.250744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:44:36.250778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 received MsgPreVoteResp from 3fd97315619643d2 at term 1"} {"level":"info","ts":"2026-10-14T17:44:36.250827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:44:36.250837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 received MsgVoteResp from 3fd97315619643d2 at term 2"} {"level":"info","ts":"2026-10-14T17:44:36.250855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became leader at term 2"} {"level":"info","ts":"2026-10-14T17:44:36.25087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3fd97315619643d2 elected leader 3fd97315619643d2 at term 2"} {"level":"info","ts":"2026-10-14T17:44:36.252572Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:44:36.252809Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3fd97315619643d2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.115:2379]}","request-path":"/0/members/3fd97315619643d2/attributes","cluster-id":"5ce44a81422ca797","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:44:36.252954Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:44:36.253057Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:44:36.253249Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:44:36.253437Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:44:36.254105Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5ce44a81422ca797","local-member-id":"3fd97315619643d2","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:44:36.25437Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:44:36.25442Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:44:36.25704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.115:2379"} {"level":"info","ts":"2026-10-14T17:44:36.257366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:45:28.30948Z","caller":"traceutil/trace.go:171","msg":"trace[879999206] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"102.370111ms","start":"2026-10-14T17:45:28.207062Z","end":"2026-10-14T17:45:28.309432Z","steps":["trace[879999206] 'read index received' (duration: 23.288252ms)","trace[879999206] 'applied index is now lower than readState.Index' (duration: 79.080809ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:45:28.309517Z","caller":"traceutil/trace.go:171","msg":"trace[1833529340] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"117.253509ms","start":"2026-10-14T17:45:28.192223Z","end":"2026-10-14T17:45:28.309477Z","steps":["trace[1833529340] 'process raft request' (duration: 38.408524ms)","trace[1833529340] 'compare' (duration: 78.332245ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:45:28.310031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.941241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p9cc6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T17:45:28.31026Z","caller":"traceutil/trace.go:171","msg":"trace[1939577079] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p9cc6; range_end:; response_count:1; response_revision:647; }","duration":"103.187226ms","start":"2026-10-14T17:45:28.207022Z","end":"2026-10-14T17:45:28.310209Z","steps":["trace[1939577079] 'agreement among raft nodes before linearized reading' (duration: 102.707057ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:28.535074Z","caller":"traceutil/trace.go:171","msg":"trace[263208313] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"199.015526ms","start":"2026-10-14T17:45:28.336034Z","end":"2026-10-14T17:45:28.53505Z","steps":["trace[263208313] 'process raft request' (duration: 198.875144ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:28.590712Z","caller":"traceutil/trace.go:171","msg":"trace[1024614617] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"211.495837ms","start":"2026-10-14T17:45:28.379198Z","end":"2026-10-14T17:45:28.590694Z","steps":["trace[1024614617] 'process raft request' (duration: 211.379915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:29.088023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.378565ms","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-14T17:45:29.088153Z","caller":"traceutil/trace.go:171","msg":"trace[1322639152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"387.801703ms","start":"2026-10-14T17:45:28.700331Z","end":"2026-10-14T17:45:29.088132Z","steps":["trace[1322639152] 'range keys from in-memory index tree' (duration: 387.179871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:29.088192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:45:28.700299Z","time spent":"387.880693ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-14T17:47:02.92085Z","caller":"traceutil/trace.go:171","msg":"trace[1164880995] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"155.647197ms","start":"2026-10-14T17:47:02.76517Z","end":"2026-10-14T17:47:02.920818Z","steps":["trace[1164880995] 'process raft request' (duration: 155.405343ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:03.018568Z","caller":"traceutil/trace.go:171","msg":"trace[89156713] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"212.651196ms","start":"2026-10-14T17:47:02.805886Z","end":"2026-10-14T17:47:03.018537Z","steps":["trace[89156713] 'read index received' (duration: 114.69702ms)","trace[89156713] 'applied index is now lower than readState.Index' (duration: 97.952536ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:03.018656Z","caller":"traceutil/trace.go:171","msg":"trace[1349491092] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"214.861557ms","start":"2026-10-14T17:47:02.803762Z","end":"2026-10-14T17:47:03.018623Z","steps":["trace[1349491092] 'process raft request' (duration: 190.828197ms)","trace[1349491092] 'compare' (duration: 23.802766ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:47:03.018834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.822334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:47:03.018872Z","caller":"traceutil/trace.go:171","msg":"trace[2043804889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1891; }","duration":"136.944076ms","start":"2026-10-14T17:47:02.881918Z","end":"2026-10-14T17:47:03.018862Z","steps":["trace[2043804889] 'agreement among raft nodes before linearized reading' (duration: 136.831634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:03.018931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.009613ms","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-14T17:47:03.019058Z","caller":"traceutil/trace.go:171","msg":"trace[1336023033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1891; }","duration":"213.168667ms","start":"2026-10-14T17:47:02.805857Z","end":"2026-10-14T17:47:03.019026Z","steps":["trace[1336023033] 'agreement among raft nodes before linearized reading' (duration: 212.85227ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:47.728796Z","caller":"traceutil/trace.go:171","msg":"trace[162284101] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2273; }","duration":"109.401094ms","start":"2026-10-14T17:47:47.619371Z","end":"2026-10-14T17:47:47.728772Z","steps":["trace[162284101] 'read index received' (duration: 109.133948ms)","trace[162284101] 'applied index is now lower than readState.Index' (duration: 266.126µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:47.728886Z","caller":"traceutil/trace.go:171","msg":"trace[364034017] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"112.526293ms","start":"2026-10-14T17:47:47.616305Z","end":"2026-10-14T17:47:47.728831Z","steps":["trace[364034017] 'process raft request' (duration: 112.287978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:47.729197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.798422ms","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-14T17:47:47.729335Z","caller":"traceutil/trace.go:171","msg":"trace[798819005] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2188; }","duration":"109.987725ms","start":"2026-10-14T17:47:47.619313Z","end":"2026-10-14T17:47:47.729301Z","steps":["trace[798819005] 'agreement among raft nodes before linearized reading' (duration: 109.576058ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:47.873331Z","caller":"traceutil/trace.go:171","msg":"trace[432491016] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"143.704505ms","start":"2026-10-14T17:47:47.729594Z","end":"2026-10-14T17:47:47.873298Z","steps":["trace[432491016] 'process raft request' (duration: 80.010455ms)","trace[432491016] 'compare' (duration: 63.499416ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:47.873589Z","caller":"traceutil/trace.go:171","msg":"trace[1370108353] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"140.949844ms","start":"2026-10-14T17:47:47.732602Z","end":"2026-10-14T17:47:47.873552Z","steps":["trace[1370108353] 'process raft request' (duration: 140.643768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:53.528734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.942826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:47:53.528852Z","caller":"traceutil/trace.go:171","msg":"trace[2037300396] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"259.141392ms","start":"2026-10-14T17:47:53.269693Z","end":"2026-10-14T17:47:53.528834Z","steps":["trace[2037300396] 'process raft request' (duration: 134.31171ms)","trace[2037300396] 'compare' (duration: 123.807414ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:53.888015Z","caller":"traceutil/trace.go:171","msg":"trace[1644734087] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2307; }","duration":"113.694883ms","start":"2026-10-14T17:47:53.774296Z","end":"2026-10-14T17:47:53.887991Z","steps":["trace[1644734087] 'read index received' (duration: 113.443298ms)","trace[1644734087] 'applied index is now lower than readState.Index' (duration: 250.805µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:47:53.888207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.900498ms","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-14T17:47:53.888289Z","caller":"traceutil/trace.go:171","msg":"trace[1590464353] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2221; }","duration":"114.029999ms","start":"2026-10-14T17:47:53.77424Z","end":"2026-10-14T17:47:53.88827Z","steps":["trace[1590464353] 'agreement among raft nodes before linearized reading' (duration: 113.841136ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:53.888104Z","caller":"traceutil/trace.go:171","msg":"trace[1515398359] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"138.486897ms","start":"2026-10-14T17:47:53.749567Z","end":"2026-10-14T17:47:53.888054Z","steps":["trace[1515398359] 'process raft request' (duration: 138.263373ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:23.469804Z","caller":"traceutil/trace.go:171","msg":"trace[973893966] transaction","detail":"{read_only:false; response_revision:4053; number_of_response:1; }","duration":"116.853434ms","start":"2026-10-14T17:52:23.35291Z","end":"2026-10-14T17:52:23.469763Z","steps":["trace[973893966] 'process raft request' (duration: 106.746545ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:52:23.470244Z","caller":"traceutil/trace.go:171","msg":"trace[528717954] transaction","detail":"{read_only:false; response_revision:4054; number_of_response:1; }","duration":"116.962426ms","start":"2026-10-14T17:52:23.353237Z","end":"2026-10-14T17:52:23.470199Z","steps":["trace[528717954] 'process raft request' (duration: 116.725931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:55.088465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.35409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a3277320-9610-4b65-8a0a-fc8baa31777e\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-10-14T17:53:55.088765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.413134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:53:55.088811Z","caller":"traceutil/trace.go:171","msg":"trace[142327817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5244; }","duration":"207.498026ms","start":"2026-10-14T17:53:54.881303Z","end":"2026-10-14T17:53:55.088801Z","steps":["trace[142327817] 'range keys from in-memory index tree' (duration: 207.346903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:53:55.089009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.742078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728743"} {"level":"info","ts":"2026-10-14T17:53:55.089076Z","caller":"traceutil/trace.go:171","msg":"trace[1461401783] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5244; }","duration":"144.841109ms","start":"2026-10-14T17:53:54.944221Z","end":"2026-10-14T17:53:55.089062Z","steps":["trace[1461401783] 'range keys from in-memory index tree' (duration: 144.423772ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:53:55.088557Z","caller":"traceutil/trace.go:171","msg":"trace[1697563173] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a3277320-9610-4b65-8a0a-fc8baa31777e; range_end:; response_count:1; response_revision:5244; }","duration":"247.496592ms","start":"2026-10-14T17:53:54.841041Z","end":"2026-10-14T17:53:55.088538Z","steps":["trace[1697563173] 'range keys from in-memory index tree' (duration: 247.175477ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:54:11.446472Z","caller":"traceutil/trace.go:171","msg":"trace[308420171] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"411.561549ms","start":"2026-10-14T17:54:11.034888Z","end":"2026-10-14T17:54:11.446449Z","steps":["trace[308420171] 'process raft request' (duration: 411.424117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:54:11.446625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:54:11.034872Z","time spent":"411.678071ms","remote":"127.0.0.1:50482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T17:54:18.69987Z","caller":"traceutil/trace.go:171","msg":"trace[306182460] transaction","detail":"{read_only:false; response_revision:5684; number_of_response:1; }","duration":"128.604977ms","start":"2026-10-14T17:54:18.571228Z","end":"2026-10-14T17:54:18.699833Z","steps":["trace[306182460] 'process raft request' (duration: 114.666057ms)","trace[306182460] 'compare' (duration: 13.524032ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:54:36.718226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-10-14T17:54:36.801135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"81.353826ms","hash":1131492190,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":32198656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T17:54:36.80123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1131492190,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:55:10.046589Z","caller":"traceutil/trace.go:171","msg":"trace[1054530997] transaction","detail":"{read_only:false; response_revision:6192; number_of_response:1; }","duration":"105.087319ms","start":"2026-10-14T17:55:09.941471Z","end":"2026-10-14T17:55:10.046558Z","steps":["trace[1054530997] 'process raft request' (duration: 104.540249ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:59:36.727231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5880} {"level":"info","ts":"2026-10-14T17:59:36.81513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5880,"took":"85.306059ms","hash":1026859578,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":25509888,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T17:59:36.815221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1026859578,"revision":5880,"compact-revision":2977} {"level":"info","ts":"2026-10-14T18:00:34.440741Z","caller":"traceutil/trace.go:171","msg":"trace[1368465381] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"182.983396ms","start":"2026-10-14T18:00:34.257724Z","end":"2026-10-14T18:00:34.440707Z","steps":["trace[1368465381] 'process raft request' (duration: 122.274276ms)","trace[1368465381] 'compare' (duration: 60.565748ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:00:35.251244Z","caller":"traceutil/trace.go:171","msg":"trace[2103182880] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"238.015831ms","start":"2026-10-14T18:00:35.013191Z","end":"2026-10-14T18:00:35.251207Z","steps":["trace[2103182880] 'process raft request' (duration: 237.797157ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:00:35.33284Z","caller":"traceutil/trace.go:171","msg":"trace[763540405] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"310.942349ms","start":"2026-10-14T18:00:35.021853Z","end":"2026-10-14T18:00:35.332796Z","steps":["trace[763540405] 'process raft request' (duration: 266.251776ms)","trace[763540405] 'compare' (duration: 44.416097ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:00:35.333073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:00:35.021835Z","time spent":"311.107631ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:00:35.365759Z","caller":"traceutil/trace.go:171","msg":"trace[783278160] linearizableReadLoop","detail":"{readStateIndex:8877; appliedIndex:8875; }","duration":"329.597915ms","start":"2026-10-14T18:00:35.036132Z","end":"2026-10-14T18:00:35.36573Z","steps":["trace[783278160] 'read index received' (duration: 214.884669ms)","trace[783278160] 'applied index is now lower than readState.Index' (duration: 114.710896ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:00:35.366053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.918071ms","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-14T18:00:35.36611Z","caller":"traceutil/trace.go:171","msg":"trace[461457307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8571; }","duration":"330.019652ms","start":"2026-10-14T18:00:35.036074Z","end":"2026-10-14T18:00:35.366094Z","steps":["trace[461457307] 'agreement among raft nodes before linearized reading' (duration: 329.827419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:35.366149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:00:35.036053Z","time spent":"330.085934ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T18:00:35.36609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.58744ms","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-14T18:00:35.366245Z","caller":"traceutil/trace.go:171","msg":"trace[29973810] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8571; }","duration":"325.781084ms","start":"2026-10-14T18:00:35.04041Z","end":"2026-10-14T18:00:35.366191Z","steps":["trace[29973810] 'agreement among raft nodes before linearized reading' (duration: 325.54291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:35.366316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:00:35.040382Z","time spent":"325.922277ms","remote":"127.0.0.1:50760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-14T18:00:36.00535Z","caller":"traceutil/trace.go:171","msg":"trace[2146430722] linearizableReadLoop","detail":"{readStateIndex:8880; appliedIndex:8879; }","duration":"122.247826ms","start":"2026-10-14T18:00:35.88307Z","end":"2026-10-14T18:00:36.005318Z","steps":["trace[2146430722] 'read index received' (duration: 121.982521ms)","trace[2146430722] 'applied index is now lower than readState.Index' (duration: 253.024µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:00:36.005434Z","caller":"traceutil/trace.go:171","msg":"trace[1724026498] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"197.273703ms","start":"2026-10-14T18:00:35.808134Z","end":"2026-10-14T18:00:36.005408Z","steps":["trace[1724026498] 'process raft request' (duration: 196.989678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:36.005594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.256154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:00:36.005675Z","caller":"traceutil/trace.go:171","msg":"trace[139774380] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8573; }","duration":"109.373026ms","start":"2026-10-14T18:00:35.896286Z","end":"2026-10-14T18:00:36.005659Z","steps":["trace[139774380] 'agreement among raft nodes before linearized reading' (duration: 109.213893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:00:36.005532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.44532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:00:36.005827Z","caller":"traceutil/trace.go:171","msg":"trace[594289288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8573; }","duration":"122.800466ms","start":"2026-10-14T18:00:35.883001Z","end":"2026-10-14T18:00:36.005802Z","steps":["trace[594289288] 'agreement among raft nodes before linearized reading' (duration: 122.42583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:01:04.743502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.867325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:01:04.743727Z","caller":"traceutil/trace.go:171","msg":"trace[235182786] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8785; }","duration":"377.163471ms","start":"2026-10-14T18:01:04.366539Z","end":"2026-10-14T18:01:04.743702Z","steps":["trace[235182786] 'count revisions from in-memory index tree' (duration: 376.754003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:01:04.744021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:01:04.366517Z","time spent":"377.486536ms","remote":"127.0.0.1:50696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:01:04.744286Z","caller":"traceutil/trace.go:171","msg":"trace[417088683] linearizableReadLoop","detail":"{readStateIndex:9100; appliedIndex:9099; }","duration":"303.360618ms","start":"2026-10-14T18:01:04.440881Z","end":"2026-10-14T18:01:04.744242Z","steps":["trace[417088683] 'read index received' (duration: 203.611871ms)","trace[417088683] 'applied index is now lower than readState.Index' (duration: 99.747417ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:01:04.744363Z","caller":"traceutil/trace.go:171","msg":"trace[1819764559] transaction","detail":"{read_only:false; response_revision:8786; number_of_response:1; }","duration":"375.96174ms","start":"2026-10-14T18:01:04.368348Z","end":"2026-10-14T18:01:04.74431Z","steps":["trace[1819764559] 'process raft request' (duration: 276.147491ms)","trace[1819764559] 'compare' (duration: 99.021193ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:01:04.744528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.627443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:01:04.744629Z","caller":"traceutil/trace.go:171","msg":"trace[1539627267] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8786; }","duration":"303.797156ms","start":"2026-10-14T18:01:04.44081Z","end":"2026-10-14T18:01:04.744607Z","steps":["trace[1539627267] 'agreement among raft nodes before linearized reading' (duration: 303.632153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:01:04.744674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:01:04.368318Z","time spent":"376.183034ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:01:04.744707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:01:04.440784Z","time spent":"303.911568ms","remote":"127.0.0.1:58256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:01:04.744851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.094511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:01:04.744969Z","caller":"traceutil/trace.go:171","msg":"trace[263215312] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8786; }","duration":"232.265844ms","start":"2026-10-14T18:01:04.512683Z","end":"2026-10-14T18:01:04.744949Z","steps":["trace[263215312] 'agreement among raft nodes before linearized reading' (duration: 231.909558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:01:04.745138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.697775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-14T18:01:04.74518Z","caller":"traceutil/trace.go:171","msg":"trace[1368283873] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8786; }","duration":"143.788576ms","start":"2026-10-14T18:01:04.60138Z","end":"2026-10-14T18:01:04.745168Z","steps":["trace[1368283873] 'agreement among raft nodes before linearized reading' (duration: 143.638984ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:01:12.549741Z","caller":"traceutil/trace.go:171","msg":"trace[1671720354] transaction","detail":"{read_only:false; response_revision:8829; number_of_response:1; }","duration":"183.425026ms","start":"2026-10-14T18:01:12.366287Z","end":"2026-10-14T18:01:12.549712Z","steps":["trace[1671720354] 'process raft request' (duration: 183.371375ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:01:12.549935Z","caller":"traceutil/trace.go:171","msg":"trace[816109259] transaction","detail":"{read_only:false; response_revision:8828; number_of_response:1; }","duration":"186.638875ms","start":"2026-10-14T18:01:12.363254Z","end":"2026-10-14T18:01:12.549893Z","steps":["trace[816109259] 'process raft request' (duration: 124.262264ms)","trace[816109259] 'compare' (duration: 61.960453ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:01:40.050211Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024bf.wal"} {"level":"info","ts":"2026-10-14T18:02:37.117331Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:02:37.124422Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T18:02:37.124559Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T18:04:36.734574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8059} {"level":"info","ts":"2026-10-14T18:04:36.810331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8059,"took":"73.120425ms","hash":1683850376,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":28934144,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T18:04:36.810403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1683850376,"revision":8059,"compact-revision":5880} {"level":"info","ts":"2026-10-14T18:06:17.499457Z","caller":"traceutil/trace.go:171","msg":"trace[772465256] transaction","detail":"{read_only:false; response_revision:11481; number_of_response:1; }","duration":"106.969568ms","start":"2026-10-14T18:06:17.39246Z","end":"2026-10-14T18:06:17.49943Z","steps":["trace[772465256] 'process raft request' (duration: 106.787534ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:09:36.74232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10512} {"level":"info","ts":"2026-10-14T18:09:36.838955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10512,"took":"93.235443ms","hash":3770826145,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":27578368,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:09:36.839045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3770826145,"revision":10512,"compact-revision":8059} {"level":"info","ts":"2026-10-14T18:12:12.539135Z","caller":"traceutil/trace.go:171","msg":"trace[1636115662] transaction","detail":"{read_only:false; response_revision:13898; number_of_response:1; }","duration":"161.997807ms","start":"2026-10-14T18:12:12.37711Z","end":"2026-10-14T18:12:12.539108Z","steps":["trace[1636115662] 'process raft request' (duration: 161.832964ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:12:12.54032Z","caller":"traceutil/trace.go:171","msg":"trace[214611538] transaction","detail":"{read_only:false; response_revision:13899; number_of_response:1; }","duration":"160.04654ms","start":"2026-10-14T18:12:12.380239Z","end":"2026-10-14T18:12:12.540285Z","steps":["trace[214611538] 'process raft request' (duration: 159.807685ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:12:53.228178Z","caller":"traceutil/trace.go:171","msg":"trace[1308914169] linearizableReadLoop","detail":"{readStateIndex:14623; appliedIndex:14622; }","duration":"278.098377ms","start":"2026-10-14T18:12:52.950054Z","end":"2026-10-14T18:12:53.228152Z","steps":["trace[1308914169] 'read index received' (duration: 277.76085ms)","trace[1308914169] 'applied index is now lower than readState.Index' (duration: 336.067µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:12:53.228261Z","caller":"traceutil/trace.go:171","msg":"trace[1853846413] transaction","detail":"{read_only:false; response_revision:14132; number_of_response:1; }","duration":"304.861597ms","start":"2026-10-14T18:12:52.923348Z","end":"2026-10-14T18:12:53.22821Z","steps":["trace[1853846413] 'process raft request' (duration: 304.604322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:53.228474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:12:52.923305Z","time spent":"305.05805ms","remote":"127.0.0.1:50482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12713,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:12:53.228491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.378923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-14T18:12:53.22856Z","caller":"traceutil/trace.go:171","msg":"trace[686974477] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14132; }","duration":"126.146117ms","start":"2026-10-14T18:12:53.102373Z","end":"2026-10-14T18:12:53.228519Z","steps":["trace[686974477] 'agreement among raft nodes before linearized reading' (duration: 125.964754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:53.228567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.475144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xcdr8\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-14T18:12:53.228666Z","caller":"traceutil/trace.go:171","msg":"trace[1350162160] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xcdr8; range_end:; response_count:1; response_revision:14132; }","duration":"278.655427ms","start":"2026-10-14T18:12:52.949989Z","end":"2026-10-14T18:12:53.228644Z","steps":["trace[1350162160] 'agreement among raft nodes before linearized reading' (duration: 278.313071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:12:53.511283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.950266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:12:53.511427Z","caller":"traceutil/trace.go:171","msg":"trace[587512841] linearizableReadLoop","detail":"{readStateIndex:14624; appliedIndex:14623; }","duration":"277.406194ms","start":"2026-10-14T18:12:53.233998Z","end":"2026-10-14T18:12:53.511404Z","steps":["trace[587512841] 'read index received' (duration: 114.221694ms)","trace[587512841] 'applied index is now lower than readState.Index' (duration: 163.18287ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:12:53.511775Z","caller":"traceutil/trace.go:171","msg":"trace[282046804] transaction","detail":"{read_only:false; response_revision:14133; number_of_response:1; }","duration":"279.094785ms","start":"2026-10-14T18:12:53.232658Z","end":"2026-10-14T18:12:53.511753Z","steps":["trace[282046804] 'process raft request' (duration: 115.526828ms)","trace[282046804] 'compare' (duration: 162.806903ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:12:53.513255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.209857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:595172"} {"level":"info","ts":"2026-10-14T18:12:53.513358Z","caller":"traceutil/trace.go:171","msg":"trace[1032877255] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14133; }","duration":"279.38427ms","start":"2026-10-14T18:12:53.233946Z","end":"2026-10-14T18:12:53.51333Z","steps":["trace[1032877255] 'agreement among raft nodes before linearized reading' (duration: 277.953434ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:36.749802Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12775} {"level":"info","ts":"2026-10-14T18:14:36.843216Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12775,"took":"89.625245ms","hash":1891725463,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":30187520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:14:36.843295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1891725463,"revision":12775,"compact-revision":10512} {"level":"info","ts":"2026-10-14T18:14:39.166827Z","caller":"traceutil/trace.go:171","msg":"trace[1806165980] linearizableReadLoop","detail":"{readStateIndex:15761; appliedIndex:15760; }","duration":"136.109112ms","start":"2026-10-14T18:14:39.030657Z","end":"2026-10-14T18:14:39.166766Z","steps":["trace[1806165980] 'read index received' (duration: 119.004802ms)","trace[1806165980] 'applied index is now lower than readState.Index' (duration: 17.10255ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:14:39.167137Z","caller":"traceutil/trace.go:171","msg":"trace[528731984] transaction","detail":"{read_only:false; response_revision:15233; number_of_response:1; }","duration":"143.726416ms","start":"2026-10-14T18:14:39.023392Z","end":"2026-10-14T18:14:39.167119Z","steps":["trace[528731984] 'process raft request' (duration: 126.413982ms)","trace[528731984] 'compare' (duration: 16.826444ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:14:39.168176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.036611ms","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-14T18:14:39.168262Z","caller":"traceutil/trace.go:171","msg":"trace[356388415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15233; }","duration":"121.153183ms","start":"2026-10-14T18:14:39.04709Z","end":"2026-10-14T18:14:39.168243Z","steps":["trace[356388415] 'agreement among raft nodes before linearized reading' (duration: 120.99818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:39.174412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.703055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737338"} {"level":"info","ts":"2026-10-14T18:14:39.174548Z","caller":"traceutil/trace.go:171","msg":"trace[1506967067] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15233; }","duration":"143.852868ms","start":"2026-10-14T18:14:39.030652Z","end":"2026-10-14T18:14:39.174505Z","steps":["trace[1506967067] 'agreement among raft nodes before linearized reading' (duration: 136.685504ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:39.307407Z","caller":"traceutil/trace.go:171","msg":"trace[191271384] linearizableReadLoop","detail":"{readStateIndex:15763; appliedIndex:15761; }","duration":"123.242103ms","start":"2026-10-14T18:14:39.184139Z","end":"2026-10-14T18:14:39.307381Z","steps":["trace[191271384] 'read index received' (duration: 54.164552ms)","trace[191271384] 'applied index is now lower than readState.Index' (duration: 69.076571ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:14:39.307487Z","caller":"traceutil/trace.go:171","msg":"trace[442247731] transaction","detail":"{read_only:false; response_revision:15235; number_of_response:1; }","duration":"128.330188ms","start":"2026-10-14T18:14:39.179151Z","end":"2026-10-14T18:14:39.307482Z","steps":["trace[442247731] 'process raft request' (duration: 127.973561ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:39.307448Z","caller":"traceutil/trace.go:171","msg":"trace[1892512935] transaction","detail":"{read_only:false; response_revision:15236; number_of_response:1; }","duration":"112.284288ms","start":"2026-10-14T18:14:39.19514Z","end":"2026-10-14T18:14:39.307425Z","steps":["trace[1892512935] 'process raft request' (duration: 112.155146ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:39.307565Z","caller":"traceutil/trace.go:171","msg":"trace[12255870] transaction","detail":"{read_only:false; response_revision:15237; number_of_response:1; }","duration":"112.137736ms","start":"2026-10-14T18:14:39.195364Z","end":"2026-10-14T18:14:39.307502Z","steps":["trace[12255870] 'process raft request' (duration: 111.977493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:39.307765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.59055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T18:14:39.307838Z","caller":"traceutil/trace.go:171","msg":"trace[2137614773] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15237; }","duration":"123.725211ms","start":"2026-10-14T18:14:39.184096Z","end":"2026-10-14T18:14:39.307821Z","steps":["trace[2137614773] 'agreement among raft nodes before linearized reading' (duration: 123.448136ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:19:36.757491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15220} {"level":"info","ts":"2026-10-14T18:19:36.884494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15220,"took":"121.588174ms","hash":3220468292,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:19:36.884657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3220468292,"revision":15220,"compact-revision":12775} {"level":"info","ts":"2026-10-14T18:20:36.930309Z","caller":"traceutil/trace.go:171","msg":"trace[307700790] transaction","detail":"{read_only:false; response_revision:17869; number_of_response:1; }","duration":"107.071023ms","start":"2026-10-14T18:20:36.823203Z","end":"2026-10-14T18:20:36.930274Z","steps":["trace[307700790] 'process raft request' (duration: 60.98245ms)","trace[307700790] 'compare' (duration: 45.374859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:20:37.394994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.205396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:20:37.395126Z","caller":"traceutil/trace.go:171","msg":"trace[2127506501] transaction","detail":"{read_only:false; response_revision:17870; number_of_response:1; }","duration":"418.58495ms","start":"2026-10-14T18:20:36.976518Z","end":"2026-10-14T18:20:37.395103Z","steps":["trace[2127506501] 'process raft request' (duration: 64.16771ms)","trace[2127506501] 'compare' (duration: 353.998082ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:20:37.39519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:20:36.976486Z","time spent":"418.671942ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:20:37.584229Z","caller":"traceutil/trace.go:171","msg":"trace[1596978407] linearizableReadLoop","detail":"{readStateIndex:18486; appliedIndex:18485; }","duration":"522.254188ms","start":"2026-10-14T18:20:37.061942Z","end":"2026-10-14T18:20:37.584196Z","steps":["trace[1596978407] 'read index received' (duration: 497.441345ms)","trace[1596978407] 'applied index is now lower than readState.Index' (duration: 24.811673ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:20:37.584275Z","caller":"traceutil/trace.go:171","msg":"trace[144393099] transaction","detail":"{read_only:false; response_revision:17871; number_of_response:1; }","duration":"543.484846ms","start":"2026-10-14T18:20:37.040744Z","end":"2026-10-14T18:20:37.584229Z","steps":["trace[144393099] 'process raft request' (duration: 518.619752ms)","trace[144393099] 'compare' (duration: 24.711251ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:20:37.584494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.480354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-14T18:20:37.584495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.554213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-dhnm7\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-10-14T18:20:37.584534Z","caller":"traceutil/trace.go:171","msg":"trace[1030996864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17871; }","duration":"305.589396ms","start":"2026-10-14T18:20:37.278935Z","end":"2026-10-14T18:20:37.584524Z","steps":["trace[1030996864] 'agreement among raft nodes before linearized reading' (duration: 305.414603ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:20:37.584547Z","caller":"traceutil/trace.go:171","msg":"trace[490884448] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-dhnm7; range_end:; response_count:1; response_revision:17871; }","duration":"522.672876ms","start":"2026-10-14T18:20:37.061862Z","end":"2026-10-14T18:20:37.584535Z","steps":["trace[490884448] 'agreement among raft nodes before linearized reading' (duration: 522.466151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:37.584558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:20:37.278909Z","time spent":"305.642528ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-14T18:20:37.584577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:20:37.061807Z","time spent":"522.760578ms","remote":"127.0.0.1:50482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20785,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-dhnm7\" "} {"level":"warn","ts":"2026-10-14T18:20:37.58449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:20:37.040714Z","time spent":"543.642418ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:20:37.584744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.12645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T18:20:37.58476Z","caller":"traceutil/trace.go:171","msg":"trace[278067281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17871; }","duration":"156.201311ms","start":"2026-10-14T18:20:37.428554Z","end":"2026-10-14T18:20:37.584756Z","steps":["trace[278067281] 'agreement among raft nodes before linearized reading' (duration: 156.139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:20:37.584836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.794995ms","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-14T18:20:37.584858Z","caller":"traceutil/trace.go:171","msg":"trace[1546949596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17871; }","duration":"293.839216ms","start":"2026-10-14T18:20:37.291015Z","end":"2026-10-14T18:20:37.584854Z","steps":["trace[1546949596] 'agreement among raft nodes before linearized reading' (duration: 293.784165ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:23:30.251527Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:23:30.259345Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T18:23:30.259486Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T18:24:36.76633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17449} {"level":"info","ts":"2026-10-14T18:24:36.874242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17449,"took":"102.589921ms","hash":2510380583,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":34070528,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T18:24:36.874315Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2510380583,"revision":17449,"compact-revision":15220} {"level":"warn","ts":"2026-10-14T18:25:58.469187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.275539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:25:58.469494Z","caller":"traceutil/trace.go:171","msg":"trace[1456506652] transaction","detail":"{read_only:false; response_revision:20420; number_of_response:1; }","duration":"179.342508ms","start":"2026-10-14T18:25:58.290114Z","end":"2026-10-14T18:25:58.469456Z","steps":["trace[1456506652] 'process raft request' (duration: 17.705531ms)","trace[1456506652] 'compare' (duration: 161.162657ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:19.84864Z","caller":"traceutil/trace.go:171","msg":"trace[325579198] linearizableReadLoop","detail":"{readStateIndex:21230; appliedIndex:21228; }","duration":"179.98072ms","start":"2026-10-14T18:26:19.668621Z","end":"2026-10-14T18:26:19.848602Z","steps":["trace[325579198] 'read index received' (duration: 20.324861ms)","trace[325579198] 'applied index is now lower than readState.Index' (duration: 159.653709ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:19.848829Z","caller":"traceutil/trace.go:171","msg":"trace[1977120645] transaction","detail":"{read_only:false; response_revision:20527; number_of_response:1; }","duration":"211.923847ms","start":"2026-10-14T18:26:19.63689Z","end":"2026-10-14T18:26:19.848814Z","steps":["trace[1977120645] 'process raft request' (duration: 211.494289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:19.848996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.589814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-14T18:26:19.849082Z","caller":"traceutil/trace.go:171","msg":"trace[988427450] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20527; }","duration":"170.703927ms","start":"2026-10-14T18:26:19.678358Z","end":"2026-10-14T18:26:19.849062Z","steps":["trace[988427450] 'agreement among raft nodes before linearized reading' (duration: 170.473142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:19.849164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.057125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T18:26:19.849235Z","caller":"traceutil/trace.go:171","msg":"trace[1440339563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20527; }","duration":"169.207387ms","start":"2026-10-14T18:26:19.680009Z","end":"2026-10-14T18:26:19.849216Z","steps":["trace[1440339563] 'agreement among raft nodes before linearized reading' (duration: 168.935502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:19.849287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.641542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T18:26:19.849398Z","caller":"traceutil/trace.go:171","msg":"trace[1106000350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20527; }","duration":"180.794716ms","start":"2026-10-14T18:26:19.668584Z","end":"2026-10-14T18:26:19.849378Z","steps":["trace[1106000350] 'agreement among raft nodes before linearized reading' (duration: 180.50712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:19.849569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.341873ms","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-14T18:26:19.849698Z","caller":"traceutil/trace.go:171","msg":"trace[321820576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20527; }","duration":"160.571257ms","start":"2026-10-14T18:26:19.689103Z","end":"2026-10-14T18:26:19.849674Z","steps":["trace[321820576] 'agreement among raft nodes before linearized reading' (duration: 159.819372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:20.184374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.330742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:26:20.184648Z","caller":"traceutil/trace.go:171","msg":"trace[966466357] transaction","detail":"{read_only:false; response_revision:20528; number_of_response:1; }","duration":"330.526519ms","start":"2026-10-14T18:26:19.85408Z","end":"2026-10-14T18:26:20.184607Z","steps":["trace[966466357] 'process raft request' (duration: 138.859621ms)","trace[966466357] 'compare' (duration: 190.979976ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:20.184728Z","caller":"traceutil/trace.go:171","msg":"trace[156084513] transaction","detail":"{read_only:false; response_revision:20530; number_of_response:1; }","duration":"328.954379ms","start":"2026-10-14T18:26:19.855753Z","end":"2026-10-14T18:26:20.184707Z","steps":["trace[156084513] 'process raft request' (duration: 328.905248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:20.184812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:19.85572Z","time spent":"329.056391ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:26:20.184814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:19.854039Z","time spent":"330.685782ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:26:20.185023Z","caller":"traceutil/trace.go:171","msg":"trace[1499219309] linearizableReadLoop","detail":"{readStateIndex:21231; appliedIndex:21230; }","duration":"330.507969ms","start":"2026-10-14T18:26:19.854504Z","end":"2026-10-14T18:26:20.185012Z","steps":["trace[1499219309] 'read index received' (duration: 138.549545ms)","trace[1499219309] 'applied index is now lower than readState.Index' (duration: 191.957684ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:26:20.185091Z","caller":"traceutil/trace.go:171","msg":"trace[585207690] transaction","detail":"{read_only:false; response_revision:20529; number_of_response:1; }","duration":"329.409328ms","start":"2026-10-14T18:26:19.855669Z","end":"2026-10-14T18:26:20.185078Z","steps":["trace[585207690] 'process raft request' (duration: 328.914759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:20.185162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:19.855633Z","time spent":"329.490679ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:26:20.185221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.713322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-14T18:26:20.185256Z","caller":"traceutil/trace.go:171","msg":"trace[1692527010] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20530; }","duration":"330.777194ms","start":"2026-10-14T18:26:19.85447Z","end":"2026-10-14T18:26:20.185247Z","steps":["trace[1692527010] 'agreement among raft nodes before linearized reading' (duration: 330.636681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:20.185288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:19.854445Z","time spent":"330.836465ms","remote":"127.0.0.1:50482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T18:26:20.185503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.766119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:20.185539Z","caller":"traceutil/trace.go:171","msg":"trace[1405555482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20530; }","duration":"303.82923ms","start":"2026-10-14T18:26:19.881701Z","end":"2026-10-14T18:26:20.18553Z","steps":["trace[1405555482] 'agreement among raft nodes before linearized reading' (duration: 303.771039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:26:20.185566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:26:19.881684Z","time spent":"303.876431ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:26:20.185724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.309385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:26:20.185746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.946769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:26:20.185759Z","caller":"traceutil/trace.go:171","msg":"trace[1286227022] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:20530; }","duration":"253.370775ms","start":"2026-10-14T18:26:19.932379Z","end":"2026-10-14T18:26:20.18575Z","steps":["trace[1286227022] 'agreement among raft nodes before linearized reading' (duration: 253.307844ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:20.185786Z","caller":"traceutil/trace.go:171","msg":"trace[18071446] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:20530; }","duration":"197.048571ms","start":"2026-10-14T18:26:19.988724Z","end":"2026-10-14T18:26:20.185773Z","steps":["trace[18071446] 'agreement among raft nodes before linearized reading' (duration: 196.960839ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:26:20.853214Z","caller":"traceutil/trace.go:171","msg":"trace[1369134723] transaction","detail":"{read_only:false; response_revision:20533; number_of_response:1; }","duration":"228.607021ms","start":"2026-10-14T18:26:20.6244Z","end":"2026-10-14T18:26:20.853007Z","steps":["trace[1369134723] 'process raft request' (duration: 134.412727ms)","trace[1369134723] 'compare' (duration: 93.97906ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:29:36.776952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19843} {"level":"info","ts":"2026-10-14T18:29:36.904422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19843,"took":"123.037779ms","hash":3504446047,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":29761536,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:29:36.904496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3504446047,"revision":19843,"compact-revision":17449} {"level":"info","ts":"2026-10-14T18:33:54.851997Z","caller":"traceutil/trace.go:171","msg":"trace[1799105995] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"182.519739ms","start":"2026-10-14T18:33:54.669449Z","end":"2026-10-14T18:33:54.851968Z","steps":["trace[1799105995] 'process raft request' (duration: 182.300055ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:55.47391Z","caller":"traceutil/trace.go:171","msg":"trace[228137916] linearizableReadLoop","detail":"{readStateIndex:25924; appliedIndex:25923; }","duration":"265.314256ms","start":"2026-10-14T18:33:55.208573Z","end":"2026-10-14T18:33:55.473887Z","steps":["trace[228137916] 'read index received' (duration: 265.241215ms)","trace[228137916] 'applied index is now lower than readState.Index' (duration: 71.501µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:33:55.473967Z","caller":"traceutil/trace.go:171","msg":"trace[1750952819] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"287.697135ms","start":"2026-10-14T18:33:55.186237Z","end":"2026-10-14T18:33:55.473934Z","steps":["trace[1750952819] 'process raft request' (duration: 287.466511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:55.474387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.752976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T18:33:55.474534Z","caller":"traceutil/trace.go:171","msg":"trace[787684979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25093; }","duration":"265.971349ms","start":"2026-10-14T18:33:55.208528Z","end":"2026-10-14T18:33:55.474499Z","steps":["trace[787684979] 'agreement among raft nodes before linearized reading' (duration: 265.46266ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:55.486945Z","caller":"traceutil/trace.go:171","msg":"trace[1260247109] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"141.036195ms","start":"2026-10-14T18:33:55.345878Z","end":"2026-10-14T18:33:55.486915Z","steps":["trace[1260247109] 'process raft request' (duration: 140.730599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:55.535502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.273081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:33:55.535628Z","caller":"traceutil/trace.go:171","msg":"trace[2085688014] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25097; }","duration":"193.469946ms","start":"2026-10-14T18:33:55.342132Z","end":"2026-10-14T18:33:55.535602Z","steps":["trace[2085688014] 'agreement among raft nodes before linearized reading' (duration: 193.249461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:33:55.535827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.087073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-14T18:33:55.535914Z","caller":"traceutil/trace.go:171","msg":"trace[309516995] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25097; }","duration":"167.225094ms","start":"2026-10-14T18:33:55.36867Z","end":"2026-10-14T18:33:55.535896Z","steps":["trace[309516995] 'agreement among raft nodes before linearized reading' (duration: 167.00774ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:36.789055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22114} {"level":"info","ts":"2026-10-14T18:34:36.911625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22114,"took":"115.821983ms","hash":454722538,"current-db-size-bytes":55373824,"current-db-size":"55 MB","current-db-size-in-use-bytes":44724224,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-14T18:34:36.911698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":454722538,"revision":22114,"compact-revision":19843} {"level":"info","ts":"2026-10-14T18:34:52.386505Z","caller":"traceutil/trace.go:171","msg":"trace[663987303] linearizableReadLoop","detail":"{readStateIndex:26460; appliedIndex:26459; }","duration":"112.29494ms","start":"2026-10-14T18:34:52.274172Z","end":"2026-10-14T18:34:52.386467Z","steps":["trace[663987303] 'read index received' (duration: 110.321162ms)","trace[663987303] 'applied index is now lower than readState.Index' (duration: 1.972318ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:34:52.386551Z","caller":"traceutil/trace.go:171","msg":"trace[1867414642] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"157.451222ms","start":"2026-10-14T18:34:52.229054Z","end":"2026-10-14T18:34:52.386505Z","steps":["trace[1867414642] 'process raft request' (duration: 155.508866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:52.386704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.511133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:34:52.387647Z","caller":"traceutil/trace.go:171","msg":"trace[5789310] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25615; }","duration":"113.526831ms","start":"2026-10-14T18:34:52.27408Z","end":"2026-10-14T18:34:52.387607Z","steps":["trace[5789310] 'agreement among raft nodes before linearized reading' (duration: 112.512363ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:58.004742Z","caller":"traceutil/trace.go:171","msg":"trace[638435091] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"238.700329ms","start":"2026-10-14T18:34:57.766007Z","end":"2026-10-14T18:34:58.004707Z","steps":["trace[638435091] 'process raft request' (duration: 238.609788ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:58.004754Z","caller":"traceutil/trace.go:171","msg":"trace[1635884463] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"238.830402ms","start":"2026-10-14T18:34:57.765872Z","end":"2026-10-14T18:34:58.004703Z","steps":["trace[1635884463] 'process raft request' (duration: 238.530087ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:58.004931Z","caller":"traceutil/trace.go:171","msg":"trace[523185807] linearizableReadLoop","detail":"{readStateIndex:26501; appliedIndex:26497; }","duration":"171.061996ms","start":"2026-10-14T18:34:57.833828Z","end":"2026-10-14T18:34:58.00489Z","steps":["trace[523185807] 'read index received' (duration: 13.141846ms)","trace[523185807] 'applied index is now lower than readState.Index' (duration: 157.91815ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:34:58.005038Z","caller":"traceutil/trace.go:171","msg":"trace[1779016030] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"230.573548ms","start":"2026-10-14T18:34:57.774433Z","end":"2026-10-14T18:34:58.005007Z","steps":["trace[1779016030] 'process raft request' (duration: 230.224111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:58.005228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.388192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T18:34:58.005263Z","caller":"traceutil/trace.go:171","msg":"trace[472145264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25655; }","duration":"171.495274ms","start":"2026-10-14T18:34:57.833759Z","end":"2026-10-14T18:34:58.005254Z","steps":["trace[472145264] 'agreement among raft nodes before linearized reading' (duration: 171.25629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:58.005548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.33393ms","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-14T18:34:58.005605Z","caller":"traceutil/trace.go:171","msg":"trace[1970542688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25655; }","duration":"111.473872ms","start":"2026-10-14T18:34:57.894115Z","end":"2026-10-14T18:34:58.005588Z","steps":["trace[1970542688] 'agreement among raft nodes before linearized reading' (duration: 111.285769ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:58.161419Z","caller":"traceutil/trace.go:171","msg":"trace[1038757146] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"150.501542ms","start":"2026-10-14T18:34:58.010879Z","end":"2026-10-14T18:34:58.161381Z","steps":["trace[1038757146] 'process raft request' (duration: 148.900581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:58.540958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.556855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:34:58.541201Z","caller":"traceutil/trace.go:171","msg":"trace[607281003] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"362.175387ms","start":"2026-10-14T18:34:58.179011Z","end":"2026-10-14T18:34:58.541186Z","steps":["trace[607281003] 'process raft request' (duration: 362.119966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:58.541278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:34:58.178978Z","time spent":"362.274509ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:34:58.541309Z","caller":"traceutil/trace.go:171","msg":"trace[504102214] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"473.614349ms","start":"2026-10-14T18:34:58.067661Z","end":"2026-10-14T18:34:58.541276Z","steps":["trace[504102214] 'process raft request' (duration: 286.473502ms)","trace[504102214] 'compare' (duration: 185.885023ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:34:58.541407Z","caller":"traceutil/trace.go:171","msg":"trace[1198242796] linearizableReadLoop","detail":"{readStateIndex:26504; appliedIndex:26502; }","duration":"446.541594ms","start":"2026-10-14T18:34:58.094799Z","end":"2026-10-14T18:34:58.541341Z","steps":["trace[1198242796] 'read index received' (duration: 64.994815ms)","trace[1198242796] 'applied index is now lower than readState.Index' (duration: 381.542789ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:34:58.541453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:34:58.067637Z","time spent":"473.755381ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:34:58.541672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.035826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T18:34:58.541721Z","caller":"traceutil/trace.go:171","msg":"trace[1828934271] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25659; }","duration":"118.121016ms","start":"2026-10-14T18:34:58.42359Z","end":"2026-10-14T18:34:58.541711Z","steps":["trace[1828934271] 'agreement among raft nodes before linearized reading' (duration: 118.021465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:58.541709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.88391ms","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-14T18:34:58.541792Z","caller":"traceutil/trace.go:171","msg":"trace[197107896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25659; }","duration":"447.029933ms","start":"2026-10-14T18:34:58.094746Z","end":"2026-10-14T18:34:58.541776Z","steps":["trace[197107896] 'agreement among raft nodes before linearized reading' (duration: 446.758488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:34:58.541839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:34:58.094731Z","time spent":"447.096144ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T18:34:58.836839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.801611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:34:58.837172Z","caller":"traceutil/trace.go:171","msg":"trace[565035859] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"278.429022ms","start":"2026-10-14T18:34:58.558716Z","end":"2026-10-14T18:34:58.837145Z","steps":["trace[565035859] 'process raft request' (duration: 278.238448ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:34:58.837188Z","caller":"traceutil/trace.go:171","msg":"trace[276819975] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"289.918276ms","start":"2026-10-14T18:34:58.547236Z","end":"2026-10-14T18:34:58.837155Z","steps":["trace[276819975] 'process raft request' (duration: 179.692817ms)","trace[276819975] 'compare' (duration: 109.653409ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:35:29.928269Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068e6.wal"} {"level":"info","ts":"2026-10-14T18:39:36.796798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25488} {"level":"info","ts":"2026-10-14T18:39:36.975275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25488,"took":"172.974554ms","hash":3852597479,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":35680256,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T18:39:36.97538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3852597479,"revision":25488,"compact-revision":22114} {"level":"info","ts":"2026-10-14T18:41:08.993275Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:41:09.000905Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T18:41:09.001036Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T18:44:36.807673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28357} {"level":"info","ts":"2026-10-14T18:44:36.975468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28357,"took":"163.016667ms","hash":2298523652,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30699520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:44:36.975555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2298523652,"revision":28357,"compact-revision":25488} {"level":"info","ts":"2026-10-14T18:49:36.815678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30583} {"level":"info","ts":"2026-10-14T18:49:36.942328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30583,"took":"122.176848ms","hash":2128300352,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31031296,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:49:36.942407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2128300352,"revision":30583,"compact-revision":28357} {"level":"info","ts":"2026-10-14T18:54:36.829107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32837} {"level":"info","ts":"2026-10-14T18:54:36.998993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32837,"took":"163.508931ms","hash":2759396415,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30015488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:54:36.999087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2759396415,"revision":32837,"compact-revision":30583} {"level":"info","ts":"2026-10-14T18:59:36.83705Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35063} {"level":"info","ts":"2026-10-14T18:59:36.986482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35063,"took":"143.574147ms","hash":3919717636,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":32161792,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:59:36.986571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3919717636,"revision":35063,"compact-revision":32837} {"level":"info","ts":"2026-10-14T18:59:50.740371Z","caller":"traceutil/trace.go:171","msg":"trace[23726192] transaction","detail":"{read_only:false; response_revision:37457; number_of_response:1; }","duration":"138.487602ms","start":"2026-10-14T18:59:50.601843Z","end":"2026-10-14T18:59:50.740331Z","steps":["trace[23726192] 'process raft request' (duration: 138.186637ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:59:50.740361Z","caller":"traceutil/trace.go:171","msg":"trace[173862528] transaction","detail":"{read_only:false; response_revision:37458; number_of_response:1; }","duration":"135.909384ms","start":"2026-10-14T18:59:50.604413Z","end":"2026-10-14T18:59:50.740322Z","steps":["trace[173862528] 'process raft request' (duration: 135.832473ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:02:33.447104Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T19:02:33.454122Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T19:02:33.454364Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T19:04:36.844536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37357} {"level":"info","ts":"2026-10-14T19:04:36.979573Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37357,"took":"130.028961ms","hash":2012569553,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":32706560,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T19:04:36.979693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2012569553,"revision":37357,"compact-revision":35063} {"level":"info","ts":"2026-10-14T19:09:36.852218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39694} {"level":"info","ts":"2026-10-14T19:09:37.005598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39694,"took":"142.26831ms","hash":3908250418,"current-db-size-bytes":59228160,"current-db-size":"59 MB","current-db-size-in-use-bytes":31166464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T19:09:37.00569Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3908250418,"revision":39694,"compact-revision":37357}