{"level":"warn","ts":"2026-02-26T01:36:00.845413Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T01:36:00.845512Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T01:36:00.845569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T01:36:00.845583Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-26T01:36:00.845606Z","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-02-26T01:36:00.8463Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-26T01:36:00.846406Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T01:36:00.850531Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.726351ms"} {"level":"info","ts":"2026-02-26T01:36:00.856657Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-26T01:36:00.856729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T01:36:00.856752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-26T01:36:00.856763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T01:36:00.856781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-26T01:36:00.85682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-26T01:36:00.862001Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T01:36:00.864105Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T01:36:00.865386Z","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-02-26T01:36:00.867655Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T01:36:00.867955Z","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-02-26T01:36:00.868028Z","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-02-26T01:36:00.868036Z","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-02-26T01:36:00.867861Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T01:36:00.869887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-26T01:36:00.870048Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-26T01:36:00.870824Z","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-02-26T01:36:00.870934Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-26T01:36:00.871033Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-26T01:36:00.871172Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T01:36:00.871207Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T01:36:01.057746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T01:36:01.057839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T01:36:01.057866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-26T01:36:01.057888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-26T01:36:01.057899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-26T01:36:01.057919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-26T01:36:01.057932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-26T01:36:01.059027Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T01:36:01.059042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:36:01.059063Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:36:01.059131Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:36:01.059311Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T01:36:01.059378Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T01:36:01.061319Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:36:01.061529Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:36:01.061687Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:36:01.063689Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-26T01:36:01.064527Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-26T01:36:13.158473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.108021ms","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-02-26T01:36:13.158601Z","caller":"traceutil/trace.go:171","msg":"trace[699490596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:353; }","duration":"262.295316ms","start":"2026-02-26T01:36:12.896284Z","end":"2026-02-26T01:36:13.158579Z","steps":["trace[699490596] 'range keys from in-memory index tree' (duration: 261.957207ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:13.36972Z","caller":"traceutil/trace.go:171","msg":"trace[620135390] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"206.300063ms","start":"2026-02-26T01:36:13.163382Z","end":"2026-02-26T01:36:13.369682Z","steps":["trace[620135390] 'process raft request' (duration: 140.389294ms)","trace[620135390] 'compare' (duration: 65.765195ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:35.976249Z","caller":"traceutil/trace.go:171","msg":"trace[1667619678] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"182.824994ms","start":"2026-02-26T01:36:35.793397Z","end":"2026-02-26T01:36:35.976222Z","steps":["trace[1667619678] 'process raft request' (duration: 182.633249ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:36.82732Z","caller":"traceutil/trace.go:171","msg":"trace[1647736773] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"222.068497ms","start":"2026-02-26T01:36:36.605225Z","end":"2026-02-26T01:36:36.827294Z","steps":["trace[1647736773] 'process raft request' (duration: 221.857551ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:36.987925Z","caller":"traceutil/trace.go:171","msg":"trace[1982580347] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"113.361998ms","start":"2026-02-26T01:36:36.87454Z","end":"2026-02-26T01:36:36.987902Z","steps":["trace[1982580347] 'process raft request' (duration: 113.214484ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:57.341974Z","caller":"traceutil/trace.go:171","msg":"trace[839027713] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"247.772798ms","start":"2026-02-26T01:36:57.094178Z","end":"2026-02-26T01:36:57.341951Z","steps":["trace[839027713] 'read index received' (duration: 247.643454ms)","trace[839027713] 'applied index is now lower than readState.Index' (duration: 128.304µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:36:57.34204Z","caller":"traceutil/trace.go:171","msg":"trace[837023332] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"335.567682ms","start":"2026-02-26T01:36:57.006418Z","end":"2026-02-26T01:36:57.341986Z","steps":["trace[837023332] 'process raft request' (duration: 335.391047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:57.342187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.991113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T01:36:57.342386Z","caller":"traceutil/trace.go:171","msg":"trace[2114180324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"248.23865ms","start":"2026-02-26T01:36:57.09413Z","end":"2026-02-26T01:36:57.342369Z","steps":["trace[2114180324] 'agreement among raft nodes before linearized reading' (duration: 247.928342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:36:57.343019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:36:57.006392Z","time spent":"335.860249ms","remote":"127.0.0.1:34542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3875,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:36:57.345469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.081757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-9997c64fc-l8wst\" ","response":"range_response_count:1 size:5348"} {"level":"info","ts":"2026-02-26T01:36:57.345557Z","caller":"traceutil/trace.go:171","msg":"trace[1050530061] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-9997c64fc-l8wst; range_end:; response_count:1; response_revision:888; }","duration":"153.214041ms","start":"2026-02-26T01:36:57.192325Z","end":"2026-02-26T01:36:57.345539Z","steps":["trace[1050530061] 'agreement among raft nodes before linearized reading' (duration: 153.023556ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:36:57.345638Z","caller":"traceutil/trace.go:171","msg":"trace[1728368624] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"158.776012ms","start":"2026-02-26T01:36:57.186823Z","end":"2026-02-26T01:36:57.345599Z","steps":["trace[1728368624] 'process raft request' (duration: 158.447852ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:41.411985Z","caller":"traceutil/trace.go:171","msg":"trace[1220190013] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"218.782951ms","start":"2026-02-26T01:37:41.193185Z","end":"2026-02-26T01:37:41.411968Z","steps":["trace[1220190013] 'process raft request' (duration: 218.664868ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:41.806307Z","caller":"traceutil/trace.go:171","msg":"trace[1643701814] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"613.098218ms","start":"2026-02-26T01:37:41.193189Z","end":"2026-02-26T01:37:41.806287Z","steps":["trace[1643701814] 'process raft request' (duration: 612.929343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:41.806492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.193166Z","time spent":"613.235422ms","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:41.806602Z","caller":"traceutil/trace.go:171","msg":"trace[625560639] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"613.379486ms","start":"2026-02-26T01:37:41.193204Z","end":"2026-02-26T01:37:41.806583Z","steps":["trace[625560639] 'process raft request' (duration: 613.041367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:41.806731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.193191Z","time spent":"613.473858ms","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":961,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:37:41.806754Z","caller":"traceutil/trace.go:171","msg":"trace[1382486233] linearizableReadLoop","detail":"{readStateIndex:1567; appliedIndex:1563; }","duration":"600.24751ms","start":"2026-02-26T01:37:41.206474Z","end":"2026-02-26T01:37:41.806722Z","steps":["trace[1382486233] 'read index received' (duration: 205.448979ms)","trace[1382486233] 'applied index is now lower than readState.Index' (duration: 394.797451ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:37:41.80685Z","caller":"traceutil/trace.go:171","msg":"trace[1342719504] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"613.560391ms","start":"2026-02-26T01:37:41.193278Z","end":"2026-02-26T01:37:41.806838Z","steps":["trace[1342719504] 'process raft request' (duration: 613.204812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:41.806923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.193256Z","time spent":"613.634382ms","remote":"127.0.0.1:34364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:37:41.806963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.493587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-02-26T01:37:41.807011Z","caller":"traceutil/trace.go:171","msg":"trace[1650628693] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1525; }","duration":"600.576739ms","start":"2026-02-26T01:37:41.20642Z","end":"2026-02-26T01:37:41.806997Z","steps":["trace[1650628693] 'agreement among raft nodes before linearized reading' (duration: 600.432625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:41.807019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.358907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-26T01:37:41.807056Z","caller":"traceutil/trace.go:171","msg":"trace[1717765976] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1525; }","duration":"412.424999ms","start":"2026-02-26T01:37:41.394619Z","end":"2026-02-26T01:37:41.807044Z","steps":["trace[1717765976] 'agreement among raft nodes before linearized reading' (duration: 412.331166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:37:41.807049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.206404Z","time spent":"600.637561ms","remote":"127.0.0.1:59344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-26T01:37:41.807103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:37:41.3946Z","time spent":"412.495821ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-02-26T01:37:41.807316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.234332ms","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-02-26T01:37:41.807393Z","caller":"traceutil/trace.go:171","msg":"trace[2002035061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1525; }","duration":"237.361215ms","start":"2026-02-26T01:37:41.570014Z","end":"2026-02-26T01:37:41.807375Z","steps":["trace[2002035061] 'agreement among raft nodes before linearized reading' (duration: 237.225842ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:37:55.38063Z","caller":"traceutil/trace.go:171","msg":"trace[1793900788] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"139.750401ms","start":"2026-02-26T01:37:55.240855Z","end":"2026-02-26T01:37:55.380605Z","steps":["trace[1793900788] 'process raft request' (duration: 139.573347ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:24.627837Z","caller":"traceutil/trace.go:171","msg":"trace[1375549350] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"120.41221ms","start":"2026-02-26T01:38:24.507396Z","end":"2026-02-26T01:38:24.627808Z","steps":["trace[1375549350] 'process raft request' (duration: 103.426322ms)","trace[1375549350] 'compare' (duration: 16.644459ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:38:30.284255Z","caller":"traceutil/trace.go:171","msg":"trace[1701439725] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"144.453039ms","start":"2026-02-26T01:38:30.139773Z","end":"2026-02-26T01:38:30.284227Z","steps":["trace[1701439725] 'process raft request' (duration: 120.277116ms)","trace[1701439725] 'compare' (duration: 24.04271ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:38:30.441628Z","caller":"traceutil/trace.go:171","msg":"trace[1044887949] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"300.398348ms","start":"2026-02-26T01:38:30.141195Z","end":"2026-02-26T01:38:30.441593Z","steps":["trace[1044887949] 'process raft request' (duration: 282.831643ms)","trace[1044887949] 'compare' (duration: 17.325919ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:38:30.441697Z","caller":"traceutil/trace.go:171","msg":"trace[1650451322] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"299.950075ms","start":"2026-02-26T01:38:30.141724Z","end":"2026-02-26T01:38:30.441674Z","steps":["trace[1650451322] 'process raft request' (duration: 299.811432ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:38:30.441734Z","caller":"traceutil/trace.go:171","msg":"trace[794519893] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"299.671438ms","start":"2026-02-26T01:38:30.142039Z","end":"2026-02-26T01:38:30.441711Z","steps":["trace[794519893] 'process raft request' (duration: 299.583426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:38:30.441816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:30.141709Z","time spent":"300.033378ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:38:30.44182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:38:30.141168Z","time spent":"300.563773ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:39:37.519855Z","caller":"traceutil/trace.go:171","msg":"trace[1282048990] transaction","detail":"{read_only:false; response_revision:2359; number_of_response:1; }","duration":"114.128227ms","start":"2026-02-26T01:39:37.405698Z","end":"2026-02-26T01:39:37.519827Z","steps":["trace[1282048990] 'process raft request' (duration: 79.48607ms)","trace[1282048990] 'compare' (duration: 34.505983ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:34.172303Z","caller":"traceutil/trace.go:171","msg":"trace[644877802] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"193.934192ms","start":"2026-02-26T01:40:33.978332Z","end":"2026-02-26T01:40:34.172266Z","steps":["trace[644877802] 'process raft request' (duration: 193.609063ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:41:47.214296Z","caller":"traceutil/trace.go:171","msg":"trace[1121478627] linearizableReadLoop","detail":"{readStateIndex:4210; appliedIndex:4209; }","duration":"244.756262ms","start":"2026-02-26T01:41:46.969511Z","end":"2026-02-26T01:41:47.214267Z","steps":["trace[1121478627] 'read index received' (duration: 244.570287ms)","trace[1121478627] 'applied index is now lower than readState.Index' (duration: 184.715µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:41:47.214452Z","caller":"traceutil/trace.go:171","msg":"trace[505414252] transaction","detail":"{read_only:false; response_revision:4032; number_of_response:1; }","duration":"245.299898ms","start":"2026-02-26T01:41:46.969109Z","end":"2026-02-26T01:41:47.214408Z","steps":["trace[505414252] 'process raft request' (duration: 245.00113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:47.214618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.703416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T01:41:47.214639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.123603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-02-26T01:41:47.214663Z","caller":"traceutil/trace.go:171","msg":"trace[1245107541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4032; }","duration":"114.811439ms","start":"2026-02-26T01:41:47.099841Z","end":"2026-02-26T01:41:47.214652Z","steps":["trace[1245107541] 'agreement among raft nodes before linearized reading' (duration: 114.634394ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:41:47.21471Z","caller":"traceutil/trace.go:171","msg":"trace[1548636453] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4032; }","duration":"245.232066ms","start":"2026-02-26T01:41:46.969461Z","end":"2026-02-26T01:41:47.214693Z","steps":["trace[1548636453] 'agreement among raft nodes before linearized reading' (duration: 245.00254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:47.214867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.157421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3285ced-dbe9-4c2e-a9fc-7b8a7e43b41c\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-02-26T01:41:47.214985Z","caller":"traceutil/trace.go:171","msg":"trace[303860276] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f3285ced-dbe9-4c2e-a9fc-7b8a7e43b41c; range_end:; response_count:1; response_revision:4032; }","duration":"102.337276ms","start":"2026-02-26T01:41:47.112622Z","end":"2026-02-26T01:41:47.214959Z","steps":["trace[303860276] 'agreement among raft nodes before linearized reading' (duration: 101.974276ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:41:49.413388Z","caller":"traceutil/trace.go:171","msg":"trace[1941903381] transaction","detail":"{read_only:false; response_revision:4076; number_of_response:1; }","duration":"106.927032ms","start":"2026-02-26T01:41:49.306424Z","end":"2026-02-26T01:41:49.413351Z","steps":["trace[1941903381] 'process raft request' (duration: 20.888902ms)","trace[1941903381] 'compare' (duration: 85.714091ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:41:49.413514Z","caller":"traceutil/trace.go:171","msg":"trace[2031113820] transaction","detail":"{read_only:false; response_revision:4077; number_of_response:1; }","duration":"106.564203ms","start":"2026-02-26T01:41:49.306895Z","end":"2026-02-26T01:41:49.413459Z","steps":["trace[2031113820] 'process raft request' (duration: 106.285935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:49.67701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.114783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:41:49.677447Z","caller":"traceutil/trace.go:171","msg":"trace[1317007374] transaction","detail":"{read_only:false; response_revision:4081; number_of_response:1; }","duration":"184.196542ms","start":"2026-02-26T01:41:49.493213Z","end":"2026-02-26T01:41:49.67741Z","steps":["trace[1317007374] 'process raft request' (duration: 39.348649ms)","trace[1317007374] 'compare' (duration: 143.873097ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:41:59.370281Z","caller":"traceutil/trace.go:171","msg":"trace[518483503] transaction","detail":"{read_only:false; response_revision:4250; number_of_response:1; }","duration":"124.775111ms","start":"2026-02-26T01:41:59.245439Z","end":"2026-02-26T01:41:59.370214Z","steps":["trace[518483503] 'process raft request' (duration: 66.919765ms)","trace[518483503] 'compare' (duration: 57.504997ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:41:59.436492Z","caller":"traceutil/trace.go:171","msg":"trace[776616581] linearizableReadLoop","detail":"{readStateIndex:4436; appliedIndex:4434; }","duration":"109.523934ms","start":"2026-02-26T01:41:59.326922Z","end":"2026-02-26T01:41:59.436446Z","steps":["trace[776616581] 'read index received' (duration: 38.931µs)","trace[776616581] 'applied index is now lower than readState.Index' (duration: 109.482813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:41:59.436741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.796941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T01:41:59.43681Z","caller":"traceutil/trace.go:171","msg":"trace[666498744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4250; }","duration":"109.910735ms","start":"2026-02-26T01:41:59.326883Z","end":"2026-02-26T01:41:59.436793Z","steps":["trace[666498744] 'agreement among raft nodes before linearized reading' (duration: 109.705829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:59.938426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.226935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:41:59.938541Z","caller":"traceutil/trace.go:171","msg":"trace[1024959061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4265; }","duration":"164.40929ms","start":"2026-02-26T01:41:59.774106Z","end":"2026-02-26T01:41:59.938516Z","steps":["trace[1024959061] 'range keys from in-memory index tree' (duration: 164.067201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:59.938593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.894904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T01:41:59.938702Z","caller":"traceutil/trace.go:171","msg":"trace[587010430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4265; }","duration":"215.071989ms","start":"2026-02-26T01:41:59.723606Z","end":"2026-02-26T01:41:59.938678Z","steps":["trace[587010430] 'range keys from in-memory index tree' (duration: 214.627977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:41:59.938593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.245796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g6ldb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T01:41:59.938848Z","caller":"traceutil/trace.go:171","msg":"trace[1805270334] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g6ldb; range_end:; response_count:1; response_revision:4265; }","duration":"210.525584ms","start":"2026-02-26T01:41:59.728291Z","end":"2026-02-26T01:41:59.938816Z","steps":["trace[1805270334] 'range keys from in-memory index tree' (duration: 210.00593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:06.573362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.342332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"warn","ts":"2026-02-26T01:42:06.573417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.983482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-02-26T01:42:06.573435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.835622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74fb6d6f-0da3-4327-8d85-cac1d64821c7\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-02-26T01:42:06.57345Z","caller":"traceutil/trace.go:171","msg":"trace[2130193342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4418; }","duration":"184.456894ms","start":"2026-02-26T01:42:06.388977Z","end":"2026-02-26T01:42:06.573434Z","steps":["trace[2130193342] 'range keys from in-memory index tree' (duration: 184.211808ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:06.573464Z","caller":"traceutil/trace.go:171","msg":"trace[1764936926] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4418; }","duration":"137.065445ms","start":"2026-02-26T01:42:06.436385Z","end":"2026-02-26T01:42:06.57345Z","steps":["trace[1764936926] 'range keys from in-memory index tree' (duration: 136.829598ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:06.57352Z","caller":"traceutil/trace.go:171","msg":"trace[106099206] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-74fb6d6f-0da3-4327-8d85-cac1d64821c7; range_end:; response_count:1; response_revision:4418; }","duration":"127.896214ms","start":"2026-02-26T01:42:06.445579Z","end":"2026-02-26T01:42:06.573475Z","steps":["trace[106099206] 'range keys from in-memory index tree' (duration: 127.747609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:06.573368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.579857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T01:42:06.573626Z","caller":"traceutil/trace.go:171","msg":"trace[572404402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4418; }","duration":"138.888276ms","start":"2026-02-26T01:42:06.434727Z","end":"2026-02-26T01:42:06.573616Z","steps":["trace[572404402] 'range keys from in-memory index tree' (duration: 138.437243ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:06.680406Z","caller":"traceutil/trace.go:171","msg":"trace[1774534467] transaction","detail":"{read_only:false; response_revision:4419; number_of_response:1; }","duration":"102.712554ms","start":"2026-02-26T01:42:06.577664Z","end":"2026-02-26T01:42:06.680376Z","steps":["trace[1774534467] 'process raft request' (duration: 102.375335ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:06.848731Z","caller":"traceutil/trace.go:171","msg":"trace[217892190] transaction","detail":"{read_only:false; response_revision:4420; number_of_response:1; }","duration":"269.875918ms","start":"2026-02-26T01:42:06.57881Z","end":"2026-02-26T01:42:06.848686Z","steps":["trace[217892190] 'process raft request' (duration: 234.452894ms)","trace[217892190] 'compare' (duration: 35.183248ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:06.849Z","caller":"traceutil/trace.go:171","msg":"trace[374774498] linearizableReadLoop","detail":"{readStateIndex:4612; appliedIndex:4609; }","duration":"259.108979ms","start":"2026-02-26T01:42:06.58986Z","end":"2026-02-26T01:42:06.848969Z","steps":["trace[374774498] 'read index received' (duration: 90.436023ms)","trace[374774498] 'applied index is now lower than readState.Index' (duration: 168.671426ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:06.849023Z","caller":"traceutil/trace.go:171","msg":"trace[85853552] transaction","detail":"{read_only:false; response_revision:4421; number_of_response:1; }","duration":"268.478899ms","start":"2026-02-26T01:42:06.580389Z","end":"2026-02-26T01:42:06.848868Z","steps":["trace[85853552] 'process raft request' (duration: 268.195992ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:06.849101Z","caller":"traceutil/trace.go:171","msg":"trace[2052135735] transaction","detail":"{read_only:false; response_revision:4422; number_of_response:1; }","duration":"227.352516ms","start":"2026-02-26T01:42:06.621725Z","end":"2026-02-26T01:42:06.849078Z","steps":["trace[2052135735] 'process raft request' (duration: 227.050578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:06.84929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.412998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74fb6d6f-0da3-4327-8d85-cac1d64821c7\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-02-26T01:42:06.849369Z","caller":"traceutil/trace.go:171","msg":"trace[1610163571] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-74fb6d6f-0da3-4327-8d85-cac1d64821c7; range_end:; response_count:1; response_revision:4422; }","duration":"259.537942ms","start":"2026-02-26T01:42:06.589812Z","end":"2026-02-26T01:42:06.84935Z","steps":["trace[1610163571] 'agreement among raft nodes before linearized reading' (duration: 259.302995ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:06.987637Z","caller":"traceutil/trace.go:171","msg":"trace[401092387] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4422; }","duration":"128.46424ms","start":"2026-02-26T01:42:06.859145Z","end":"2026-02-26T01:42:06.98761Z","steps":["trace[401092387] 'process raft request' (duration: 118.243877ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:06.988637Z","caller":"traceutil/trace.go:171","msg":"trace[594170936] transaction","detail":"{read_only:false; response_revision:4423; number_of_response:1; }","duration":"126.934008ms","start":"2026-02-26T01:42:06.861667Z","end":"2026-02-26T01:42:06.988601Z","steps":["trace[594170936] 'process raft request' (duration: 126.740023ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:01.327047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3124} {"level":"info","ts":"2026-02-26T01:46:01.403603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3124,"took":"74.288473ms","hash":3979984490,"current-db-size-bytes":44847104,"current-db-size":"45 MB","current-db-size-in-use-bytes":32534528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-02-26T01:46:01.403669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3979984490,"revision":3124,"compact-revision":-1} {"level":"warn","ts":"2026-02-26T01:47:49.616759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.804696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T01:47:49.616931Z","caller":"traceutil/trace.go:171","msg":"trace[477408051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7413; }","duration":"213.016822ms","start":"2026-02-26T01:47:49.403889Z","end":"2026-02-26T01:47:49.616906Z","steps":["trace[477408051] 'range keys from in-memory index tree' (duration: 212.59106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:48:01.032112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.040996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-b959ff565-c48wd.1897a8b1fe3a5aa6\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-02-26T01:48:01.03225Z","caller":"traceutil/trace.go:171","msg":"trace[452438392] range","detail":"{range_begin:/registry/events/openstack/barbican-api-b959ff565-c48wd.1897a8b1fe3a5aa6; range_end:; response_count:1; response_revision:7481; }","duration":"208.251851ms","start":"2026-02-26T01:48:00.82397Z","end":"2026-02-26T01:48:01.032222Z","steps":["trace[452438392] 'range keys from in-memory index tree' (duration: 207.786449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:48:01.839799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.347046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:48:01.840203Z","caller":"traceutil/trace.go:171","msg":"trace[734397670] transaction","detail":"{read_only:false; response_revision:7488; number_of_response:1; }","duration":"435.157905ms","start":"2026-02-26T01:48:01.405008Z","end":"2026-02-26T01:48:01.840166Z","steps":["trace[734397670] 'process raft request' (duration: 258.311385ms)","trace[734397670] 'compare' (duration: 176.211392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:48:01.840386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:48:01.404986Z","time spent":"435.30318ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:48:29.784308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.481595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:48:29.784421Z","caller":"traceutil/trace.go:171","msg":"trace[1946284933] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:7712; }","duration":"279.614438ms","start":"2026-02-26T01:48:29.504783Z","end":"2026-02-26T01:48:29.784397Z","steps":["trace[1946284933] 'count revisions from in-memory index tree' (duration: 279.344361ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:41.910278Z","caller":"traceutil/trace.go:171","msg":"trace[98502041] transaction","detail":"{read_only:false; response_revision:7831; number_of_response:1; }","duration":"184.539983ms","start":"2026-02-26T01:48:41.725713Z","end":"2026-02-26T01:48:41.910253Z","steps":["trace[98502041] 'process raft request' (duration: 184.370679ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:48:43.132935Z","caller":"traceutil/trace.go:171","msg":"trace[1035384019] linearizableReadLoop","detail":"{readStateIndex:8209; appliedIndex:8208; }","duration":"184.962745ms","start":"2026-02-26T01:48:42.947948Z","end":"2026-02-26T01:48:43.132911Z","steps":["trace[1035384019] 'read index received' (duration: 184.690008ms)","trace[1035384019] 'applied index is now lower than readState.Index' (duration: 271.487µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:48:43.133003Z","caller":"traceutil/trace.go:171","msg":"trace[1680446734] transaction","detail":"{read_only:false; response_revision:7841; number_of_response:1; }","duration":"229.602845ms","start":"2026-02-26T01:48:42.903362Z","end":"2026-02-26T01:48:43.132965Z","steps":["trace[1680446734] 'process raft request' (duration: 229.381069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:48:43.133283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.295534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-02-26T01:48:43.133412Z","caller":"traceutil/trace.go:171","msg":"trace[1852988059] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7841; }","duration":"185.483929ms","start":"2026-02-26T01:48:42.947903Z","end":"2026-02-26T01:48:43.133387Z","steps":["trace[1852988059] 'agreement among raft nodes before linearized reading' (duration: 185.116829ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:50.056781Z","caller":"traceutil/trace.go:171","msg":"trace[565372329] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"118.376945ms","start":"2026-02-26T01:49:49.93836Z","end":"2026-02-26T01:49:50.056737Z","steps":["trace[565372329] 'process raft request' (duration: 72.127072ms)","trace[565372329] 'compare' (duration: 46.039277ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:51:01.333951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6356} {"level":"info","ts":"2026-02-26T01:51:01.423463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6356,"took":"87.423934ms","hash":2127482012,"current-db-size-bytes":44847104,"current-db-size":"45 MB","current-db-size-in-use-bytes":24375296,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T01:51:01.423559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2127482012,"revision":6356,"compact-revision":3124} {"level":"info","ts":"2026-02-26T01:51:49.114463Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd37ed2859641e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T01:51:49.119034Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T01:51:49.119107Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-26T01:53:24.790054Z","caller":"traceutil/trace.go:171","msg":"trace[1789966442] transaction","detail":"{read_only:false; response_revision:10580; number_of_response:1; }","duration":"146.600408ms","start":"2026-02-26T01:53:24.643425Z","end":"2026-02-26T01:53:24.790026Z","steps":["trace[1789966442] 'process raft request' (duration: 146.432134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:53:25.11086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.492041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T01:53:25.110942Z","caller":"traceutil/trace.go:171","msg":"trace[67385593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10580; }","duration":"226.620724ms","start":"2026-02-26T01:53:24.884304Z","end":"2026-02-26T01:53:25.110925Z","steps":["trace[67385593] 'range keys from in-memory index tree' (duration: 226.325286ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:53:25.245381Z","caller":"traceutil/trace.go:171","msg":"trace[1712822194] transaction","detail":"{read_only:false; response_revision:10581; number_of_response:1; }","duration":"130.469532ms","start":"2026-02-26T01:53:25.114892Z","end":"2026-02-26T01:53:25.245361Z","steps":["trace[1712822194] 'process raft request' (duration: 130.360339ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:53:25.270449Z","caller":"traceutil/trace.go:171","msg":"trace[1528042731] transaction","detail":"{read_only:false; response_revision:10582; number_of_response:1; }","duration":"153.944907ms","start":"2026-02-26T01:53:25.116476Z","end":"2026-02-26T01:53:25.270421Z","steps":["trace[1528042731] 'process raft request' (duration: 153.65967ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:53:25.270487Z","caller":"traceutil/trace.go:171","msg":"trace[237487046] linearizableReadLoop","detail":"{readStateIndex:11080; appliedIndex:11077; }","duration":"126.388202ms","start":"2026-02-26T01:53:25.14406Z","end":"2026-02-26T01:53:25.270448Z","steps":["trace[237487046] 'read index received' (duration: 101.295653ms)","trace[237487046] 'applied index is now lower than readState.Index' (duration: 25.090989ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:53:25.270725Z","caller":"traceutil/trace.go:171","msg":"trace[1463441901] transaction","detail":"{read_only:false; response_revision:10583; number_of_response:1; }","duration":"153.016972ms","start":"2026-02-26T01:53:25.117685Z","end":"2026-02-26T01:53:25.270702Z","steps":["trace[1463441901] 'process raft request' (duration: 152.637342ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:53:25.270815Z","caller":"traceutil/trace.go:171","msg":"trace[1185957771] transaction","detail":"{read_only:false; response_revision:10584; number_of_response:1; }","duration":"152.072707ms","start":"2026-02-26T01:53:25.118697Z","end":"2026-02-26T01:53:25.270769Z","steps":["trace[1185957771] 'process raft request' (duration: 151.695987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:53:25.271025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.937367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:53:25.271085Z","caller":"traceutil/trace.go:171","msg":"trace[1382563009] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:10584; }","duration":"127.065001ms","start":"2026-02-26T01:53:25.144002Z","end":"2026-02-26T01:53:25.271067Z","steps":["trace[1382563009] 'agreement among raft nodes before linearized reading' (duration: 126.589467ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:53:25.399392Z","caller":"traceutil/trace.go:171","msg":"trace[1118347465] transaction","detail":"{read_only:false; response_revision:10586; number_of_response:1; }","duration":"119.891865ms","start":"2026-02-26T01:53:25.279473Z","end":"2026-02-26T01:53:25.399365Z","steps":["trace[1118347465] 'process raft request' (duration: 119.661099ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:01.339413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9142} {"level":"info","ts":"2026-02-26T01:56:01.422604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9142,"took":"80.939151ms","hash":4208595830,"current-db-size-bytes":44847104,"current-db-size":"45 MB","current-db-size-in-use-bytes":25612288,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-02-26T01:56:01.422676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4208595830,"revision":9142,"compact-revision":6356} {"level":"info","ts":"2026-02-26T01:57:09.428898Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000031f5.wal"} {"level":"warn","ts":"2026-02-26T01:59:15.081889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.810223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:59:15.082217Z","caller":"traceutil/trace.go:171","msg":"trace[1209060059] transaction","detail":"{read_only:false; response_revision:13277; number_of_response:1; }","duration":"144.843807ms","start":"2026-02-26T01:59:14.937343Z","end":"2026-02-26T01:59:15.082187Z","steps":["trace[1209060059] 'process raft request' (duration: 20.638652ms)","trace[1209060059] 'compare' (duration: 123.441714ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:59:50.165873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.035415ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T01:59:50.166272Z","caller":"traceutil/trace.go:171","msg":"trace[86114994] linearizableReadLoop","detail":"{readStateIndex:14175; appliedIndex:14174; }","duration":"565.529135ms","start":"2026-02-26T01:59:49.600712Z","end":"2026-02-26T01:59:50.166242Z","steps":["trace[86114994] 'read index received' (duration: 195.115269ms)","trace[86114994] 'applied index is now lower than readState.Index' (duration: 370.408506ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:59:50.166568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.825784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T01:59:50.166665Z","caller":"traceutil/trace.go:171","msg":"trace[879392738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13514; }","duration":"565.968467ms","start":"2026-02-26T01:59:49.600672Z","end":"2026-02-26T01:59:50.166641Z","steps":["trace[879392738] 'agreement among raft nodes before linearized reading' (duration: 565.69841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:59:50.166727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:59:49.600657Z","time spent":"566.05807ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:59:50.166599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.72386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T01:59:50.166905Z","caller":"traceutil/trace.go:171","msg":"trace[478767981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13514; }","duration":"565.995568ms","start":"2026-02-26T01:59:49.600843Z","end":"2026-02-26T01:59:50.166838Z","steps":["trace[478767981] 'agreement among raft nodes before linearized reading' (duration: 565.603308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:59:50.167021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:59:49.600837Z","time spent":"566.143602ms","remote":"127.0.0.1:34360","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-02-26T01:59:50.167254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.775502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:82 size:714926"} {"level":"info","ts":"2026-02-26T01:59:50.1673Z","caller":"traceutil/trace.go:171","msg":"trace[264184613] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:82; response_revision:13514; }","duration":"391.848824ms","start":"2026-02-26T01:59:49.775439Z","end":"2026-02-26T01:59:50.167288Z","steps":["trace[264184613] 'agreement among raft nodes before linearized reading' (duration: 391.196756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:59:50.167341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:59:49.775419Z","time spent":"391.907605ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":82,"response size":714949,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-02-26T01:59:50.336679Z","caller":"traceutil/trace.go:171","msg":"trace[233194770] transaction","detail":"{read_only:false; response_revision:13516; number_of_response:1; }","duration":"165.191435ms","start":"2026-02-26T01:59:50.171449Z","end":"2026-02-26T01:59:50.33664Z","steps":["trace[233194770] 'process raft request' (duration: 154.304962ms)","trace[233194770] 'compare' (duration: 10.639497ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T02:01:01.34535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11790} {"level":"info","ts":"2026-02-26T02:01:01.422076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11790,"took":"74.259251ms","hash":2387788299,"current-db-size-bytes":44847104,"current-db-size":"45 MB","current-db-size-in-use-bytes":28692480,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-26T02:01:01.422141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2387788299,"revision":11790,"compact-revision":9142} {"level":"warn","ts":"2026-02-26T02:01:34.694774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.879443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T02:01:34.694922Z","caller":"traceutil/trace.go:171","msg":"trace[48227897] linearizableReadLoop","detail":"{readStateIndex:15538; appliedIndex:15537; }","duration":"195.040467ms","start":"2026-02-26T02:01:34.499859Z","end":"2026-02-26T02:01:34.694899Z","steps":["trace[48227897] 'read index received' (duration: 65.915037ms)","trace[48227897] 'applied index is now lower than readState.Index' (duration: 129.1231ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T02:01:34.694928Z","caller":"traceutil/trace.go:171","msg":"trace[1576406224] transaction","detail":"{read_only:false; response_revision:14820; number_of_response:1; }","duration":"240.179786ms","start":"2026-02-26T02:01:34.45472Z","end":"2026-02-26T02:01:34.6949Z","steps":["trace[1576406224] 'process raft request' (duration: 111.056976ms)","trace[1576406224] 'compare' (duration: 128.76849ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T02:01:34.695186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.332425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-c2fzl\" ","response":"range_response_count:1 size:20739"} {"level":"info","ts":"2026-02-26T02:01:34.695246Z","caller":"traceutil/trace.go:171","msg":"trace[308695668] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-c2fzl; range_end:; response_count:1; response_revision:14820; }","duration":"195.442189ms","start":"2026-02-26T02:01:34.499789Z","end":"2026-02-26T02:01:34.695231Z","steps":["trace[308695668] 'agreement among raft nodes before linearized reading' (duration: 195.229383ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T02:06:01.353863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14618} {"level":"info","ts":"2026-02-26T02:06:01.474934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14618,"took":"118.394245ms","hash":1819838959,"current-db-size-bytes":44847104,"current-db-size":"45 MB","current-db-size-in-use-bytes":29163520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-26T02:06:01.475024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1819838959,"revision":14618,"compact-revision":11790} {"level":"info","ts":"2026-02-26T02:07:09.996425Z","caller":"traceutil/trace.go:171","msg":"trace[408247098] transaction","detail":"{read_only:false; response_revision:17948; number_of_response:1; }","duration":"174.725223ms","start":"2026-02-26T02:07:09.821667Z","end":"2026-02-26T02:07:09.996392Z","steps":["trace[408247098] 'process raft request' (duration: 107.569207ms)","trace[408247098] 'compare' (duration: 66.377388ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T02:07:15.576678Z","caller":"traceutil/trace.go:171","msg":"trace[1775936102] linearizableReadLoop","detail":"{readStateIndex:18859; appliedIndex:18858; }","duration":"224.697804ms","start":"2026-02-26T02:07:15.35195Z","end":"2026-02-26T02:07:15.576648Z","steps":["trace[1775936102] 'read index received' (duration: 224.423767ms)","trace[1775936102] 'applied index is now lower than readState.Index' (duration: 272.997µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T02:07:15.576741Z","caller":"traceutil/trace.go:171","msg":"trace[125548510] transaction","detail":"{read_only:false; response_revision:17988; number_of_response:1; }","duration":"275.603344ms","start":"2026-02-26T02:07:15.301098Z","end":"2026-02-26T02:07:15.576702Z","steps":["trace[125548510] 'process raft request' (duration: 275.355918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T02:07:15.576851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.287144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T02:07:15.576947Z","caller":"traceutil/trace.go:171","msg":"trace[991980093] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:17988; }","duration":"218.468947ms","start":"2026-02-26T02:07:15.358466Z","end":"2026-02-26T02:07:15.576934Z","steps":["trace[991980093] 'agreement among raft nodes before linearized reading' (duration: 218.325044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T02:07:15.576937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.974861ms","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-02-26T02:07:15.576999Z","caller":"traceutil/trace.go:171","msg":"trace[1698685193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17988; }","duration":"225.112003ms","start":"2026-02-26T02:07:15.351871Z","end":"2026-02-26T02:07:15.576983Z","steps":["trace[1698685193] 'agreement among raft nodes before linearized reading' (duration: 224.887288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T02:07:15.577226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.167289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T02:07:15.577357Z","caller":"traceutil/trace.go:171","msg":"trace[732455204] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:17988; }","duration":"217.316293ms","start":"2026-02-26T02:07:15.360017Z","end":"2026-02-26T02:07:15.577333Z","steps":["trace[732455204] 'agreement among raft nodes before linearized reading' (duration: 217.112738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T02:07:15.577373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.756956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:1 size:5022"} {"level":"warn","ts":"2026-02-26T02:07:15.577378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.732843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T02:07:15.577434Z","caller":"traceutil/trace.go:171","msg":"trace[1850848929] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:1; response_revision:17988; }","duration":"214.855098ms","start":"2026-02-26T02:07:15.362554Z","end":"2026-02-26T02:07:15.577409Z","steps":["trace[1850848929] 'agreement among raft nodes before linearized reading' (duration: 214.530591ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T02:07:15.57747Z","caller":"traceutil/trace.go:171","msg":"trace[129631679] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:17988; }","duration":"218.842935ms","start":"2026-02-26T02:07:15.358598Z","end":"2026-02-26T02:07:15.577441Z","steps":["trace[129631679] 'agreement among raft nodes before linearized reading' (duration: 218.62353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T02:07:15.577537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.759055ms","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-02-26T02:07:15.577673Z","caller":"traceutil/trace.go:171","msg":"trace[333793705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17988; }","duration":"126.049721ms","start":"2026-02-26T02:07:15.451601Z","end":"2026-02-26T02:07:15.577651Z","steps":["trace[333793705] 'agreement among raft nodes before linearized reading' (duration: 125.499219ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T02:07:15.846555Z","caller":"traceutil/trace.go:171","msg":"trace[1214839551] linearizableReadLoop","detail":"{readStateIndex:18861; appliedIndex:18859; }","duration":"156.25007ms","start":"2026-02-26T02:07:15.690279Z","end":"2026-02-26T02:07:15.846529Z","steps":["trace[1214839551] 'read index received' (duration: 75.15488ms)","trace[1214839551] 'applied index is now lower than readState.Index' (duration: 81.09429ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T02:07:15.84658Z","caller":"traceutil/trace.go:171","msg":"trace[1830336325] transaction","detail":"{read_only:false; response_revision:17989; number_of_response:1; }","duration":"264.903791ms","start":"2026-02-26T02:07:15.581626Z","end":"2026-02-26T02:07:15.846529Z","steps":["trace[1830336325] 'process raft request' (duration: 183.806421ms)","trace[1830336325] 'compare' (duration: 80.629ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T02:07:15.846671Z","caller":"traceutil/trace.go:171","msg":"trace[1118255257] transaction","detail":"{read_only:false; response_revision:17990; number_of_response:1; }","duration":"264.127633ms","start":"2026-02-26T02:07:15.582502Z","end":"2026-02-26T02:07:15.846629Z","steps":["trace[1118255257] 'process raft request' (duration: 263.926099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T02:07:15.846736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.444704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T02:07:15.846776Z","caller":"traceutil/trace.go:171","msg":"trace[637758004] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:17990; }","duration":"156.539316ms","start":"2026-02-26T02:07:15.690224Z","end":"2026-02-26T02:07:15.846763Z","steps":["trace[637758004] 'agreement among raft nodes before linearized reading' (duration: 156.441534ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T02:09:50.165464Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd37ed2859641e3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T02:09:50.170846Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-02-26T02:09:50.170949Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-02-26T02:11:01.359883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17075} {"level":"info","ts":"2026-02-26T02:11:01.45149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17075,"took":"88.156609ms","hash":2758792729,"current-db-size-bytes":44847104,"current-db-size":"45 MB","current-db-size-in-use-bytes":28479488,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T02:11:01.451559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2758792729,"revision":17075,"compact-revision":14618}