{"level":"warn","ts":"2026-04-22T20:39:14.050214Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T20:39:14.050339Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T20:39:14.050436Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T20:39:14.050454Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-22T20:39:14.050511Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T20:39:14.051784Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-04-22T20:39:14.051911Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T20:39:14.057055Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.746221ms"} {"level":"info","ts":"2026-04-22T20:39:14.063631Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-04-22T20:39:14.063734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T20:39:14.063764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-04-22T20:39:14.063776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T20:39:14.063801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-04-22T20:39:14.063845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-04-22T20:39:14.069707Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T20:39:14.071492Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T20:39:14.072653Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T20:39:14.074742Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T20:39:14.077288Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T20:39:14.077348Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T20:39:14.07736Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T20:39:14.079274Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T20:39:14.079874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-04-22T20:39:14.080142Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-22T20:39:14.080671Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T20:39:14.080801Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-22T20:39:14.080844Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-22T20:39:14.08093Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T20:39:14.080975Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T20:39:14.76422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T20:39:14.764278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T20:39:14.764311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-04-22T20:39:14.764325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-04-22T20:39:14.764336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-22T20:39:14.764348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-04-22T20:39:14.76436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-22T20:39:14.766027Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T20:39:14.7662Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T20:39:14.767113Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T20:39:14.767542Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T20:39:14.767605Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T20:39:14.767626Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T20:39:14.76787Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T20:39:14.769989Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T20:39:14.770273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-04-22T20:39:14.771286Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T20:39:14.771309Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T20:39:51.676477Z","caller":"traceutil/trace.go:171","msg":"trace[1549029211] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"236.667264ms","start":"2026-04-22T20:39:51.439781Z","end":"2026-04-22T20:39:51.676448Z","steps":["trace[1549029211] 'read index received' (duration: 235.861364ms)","trace[1549029211] 'applied index is now lower than readState.Index' (duration: 804.98µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T20:39:51.676751Z","caller":"traceutil/trace.go:171","msg":"trace[1834813654] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"268.687723ms","start":"2026-04-22T20:39:51.408015Z","end":"2026-04-22T20:39:51.676703Z","steps":["trace[1834813654] 'process raft request' (duration: 267.897702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:39:51.676788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.986055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-22T20:39:51.676869Z","caller":"traceutil/trace.go:171","msg":"trace[1091507490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"237.104085ms","start":"2026-04-22T20:39:51.439752Z","end":"2026-04-22T20:39:51.676856Z","steps":["trace[1091507490] 'agreement among raft nodes before linearized reading' (duration: 236.797384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:39:51.889377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.050463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T20:39:51.889671Z","caller":"traceutil/trace.go:171","msg":"trace[689095831] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"155.443912ms","start":"2026-04-22T20:39:51.734204Z","end":"2026-04-22T20:39:51.889648Z","steps":["trace[689095831] 'process raft request' (duration: 46.342238ms)","trace[689095831] 'compare' (duration: 107.895802ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T20:39:51.889892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.988041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s25tq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-22T20:39:51.890029Z","caller":"traceutil/trace.go:171","msg":"trace[1466613743] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s25tq; range_end:; response_count:1; response_revision:576; }","duration":"101.175882ms","start":"2026-04-22T20:39:51.788835Z","end":"2026-04-22T20:39:51.89001Z","steps":["trace[1466613743] 'agreement among raft nodes before linearized reading' (duration: 100.920371ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:39:51.890378Z","caller":"traceutil/trace.go:171","msg":"trace[1742143785] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"100.691871ms","start":"2026-04-22T20:39:51.788895Z","end":"2026-04-22T20:39:51.889587Z","steps":["trace[1742143785] 'read index received' (duration: 53.52µs)","trace[1742143785] 'applied index is now lower than readState.Index' (duration: 100.635621ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T20:39:52.537479Z","caller":"traceutil/trace.go:171","msg":"trace[1788917038] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"131.575526ms","start":"2026-04-22T20:39:52.405877Z","end":"2026-04-22T20:39:52.537452Z","steps":["trace[1788917038] 'read index received' (duration: 130.642624ms)","trace[1788917038] 'applied index is now lower than readState.Index' (duration: 931.852µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T20:39:52.537575Z","caller":"traceutil/trace.go:171","msg":"trace[2045426148] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"206.164577ms","start":"2026-04-22T20:39:52.331358Z","end":"2026-04-22T20:39:52.537522Z","steps":["trace[2045426148] 'process raft request' (duration: 205.457895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:39:52.537757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.855236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T20:39:52.53779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.153455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T20:39:52.537818Z","caller":"traceutil/trace.go:171","msg":"trace[772842619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"131.970646ms","start":"2026-04-22T20:39:52.405833Z","end":"2026-04-22T20:39:52.537804Z","steps":["trace[772842619] 'agreement among raft nodes before linearized reading' (duration: 131.787706ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:39:52.53787Z","caller":"traceutil/trace.go:171","msg":"trace[424121356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"131.266826ms","start":"2026-04-22T20:39:52.406586Z","end":"2026-04-22T20:39:52.537853Z","steps":["trace[424121356] 'agreement among raft nodes before linearized reading' (duration: 131.087675ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:41:23.122857Z","caller":"traceutil/trace.go:171","msg":"trace[956554717] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"221.230885ms","start":"2026-04-22T20:41:22.9016Z","end":"2026-04-22T20:41:23.122831Z","steps":["trace[956554717] 'read index received' (duration: 221.055501ms)","trace[956554717] 'applied index is now lower than readState.Index' (duration: 174.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T20:41:23.122972Z","caller":"traceutil/trace.go:171","msg":"trace[1862192342] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"254.891334ms","start":"2026-04-22T20:41:22.868073Z","end":"2026-04-22T20:41:23.122965Z","steps":["trace[1862192342] 'process raft request' (duration: 254.611709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:41:23.123356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.723324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-22T20:41:23.123427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.455432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T20:41:23.123442Z","caller":"traceutil/trace.go:171","msg":"trace[1345283879] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1814; }","duration":"221.857857ms","start":"2026-04-22T20:41:22.901566Z","end":"2026-04-22T20:41:23.123424Z","steps":["trace[1345283879] 'agreement among raft nodes before linearized reading' (duration: 221.637023ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:41:23.123475Z","caller":"traceutil/trace.go:171","msg":"trace[1706512583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1814; }","duration":"146.514204ms","start":"2026-04-22T20:41:22.976943Z","end":"2026-04-22T20:41:23.123457Z","steps":["trace[1706512583] 'agreement among raft nodes before linearized reading' (duration: 146.447753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:41:23.123392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.946084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c47ca8ea-b739-4519-81a0-b434567a924d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-22T20:41:23.123687Z","caller":"traceutil/trace.go:171","msg":"trace[1509060109] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c47ca8ea-b739-4519-81a0-b434567a924d; range_end:; response_count:1; response_revision:1814; }","duration":"147.30363ms","start":"2026-04-22T20:41:22.976355Z","end":"2026-04-22T20:41:23.123658Z","steps":["trace[1509060109] 'agreement among raft nodes before linearized reading' (duration: 146.721638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:41:23.344514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.212623ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T20:41:23.34522Z","caller":"traceutil/trace.go:171","msg":"trace[529188016] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"114.675283ms","start":"2026-04-22T20:41:23.2305Z","end":"2026-04-22T20:41:23.345175Z","steps":["trace[529188016] 'read index received' (duration: 5.722366ms)","trace[529188016] 'applied index is now lower than readState.Index' (duration: 108.949487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T20:41:23.345492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.976009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-04-22T20:41:23.345526Z","caller":"traceutil/trace.go:171","msg":"trace[1001394432] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1818; }","duration":"115.067831ms","start":"2026-04-22T20:41:23.230449Z","end":"2026-04-22T20:41:23.345517Z","steps":["trace[1001394432] 'agreement among raft nodes before linearized reading' (duration: 114.918148ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:41:23.54578Z","caller":"traceutil/trace.go:171","msg":"trace[1613368864] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"189.857106ms","start":"2026-04-22T20:41:23.355901Z","end":"2026-04-22T20:41:23.545759Z","steps":["trace[1613368864] 'process raft request' (duration: 189.650692ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:41:39.870209Z","caller":"traceutil/trace.go:171","msg":"trace[1555870727] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"215.863502ms","start":"2026-04-22T20:41:39.654319Z","end":"2026-04-22T20:41:39.870183Z","steps":["trace[1555870727] 'process raft request' (duration: 215.653869ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:41:39.870858Z","caller":"traceutil/trace.go:171","msg":"trace[1063714301] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"129.105242ms","start":"2026-04-22T20:41:39.741733Z","end":"2026-04-22T20:41:39.870838Z","steps":["trace[1063714301] 'process raft request' (duration: 128.888519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:42:02.993637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.259643ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T20:42:03.535829Z","caller":"traceutil/trace.go:171","msg":"trace[72092920] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2170; }","duration":"165.816306ms","start":"2026-04-22T20:42:03.369986Z","end":"2026-04-22T20:42:03.535802Z","steps":["trace[72092920] 'read index received' (duration: 165.663214ms)","trace[72092920] 'applied index is now lower than readState.Index' (duration: 151.932µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T20:42:03.535867Z","caller":"traceutil/trace.go:171","msg":"trace[127897582] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"182.306379ms","start":"2026-04-22T20:42:03.35353Z","end":"2026-04-22T20:42:03.535836Z","steps":["trace[127897582] 'process raft request' (duration: 182.109066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:42:03.5361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.109089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T20:42:03.536192Z","caller":"traceutil/trace.go:171","msg":"trace[13364225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2091; }","duration":"166.24605ms","start":"2026-04-22T20:42:03.369931Z","end":"2026-04-22T20:42:03.536177Z","steps":["trace[13364225] 'agreement among raft nodes before linearized reading' (duration: 166.003457ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:42:03.714711Z","caller":"traceutil/trace.go:171","msg":"trace[327942283] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"208.672626ms","start":"2026-04-22T20:42:03.50602Z","end":"2026-04-22T20:42:03.714692Z","steps":["trace[327942283] 'process raft request' (duration: 126.258129ms)","trace[327942283] 'compare' (duration: 82.300346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T20:42:03.714827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.270629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-22T20:42:03.714827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.167796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-22T20:42:03.714865Z","caller":"traceutil/trace.go:171","msg":"trace[908877727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2092; }","duration":"306.248586ms","start":"2026-04-22T20:42:03.408605Z","end":"2026-04-22T20:42:03.714854Z","steps":["trace[908877727] 'agreement among raft nodes before linearized reading' (duration: 306.091434ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:42:03.714853Z","caller":"traceutil/trace.go:171","msg":"trace[2045375650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2092; }","duration":"343.34944ms","start":"2026-04-22T20:42:03.371498Z","end":"2026-04-22T20:42:03.714847Z","steps":["trace[2045375650] 'agreement among raft nodes before linearized reading' (duration: 343.236999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:42:03.714903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T20:42:03.371476Z","time spent":"343.419401ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T20:42:03.714698Z","caller":"traceutil/trace.go:171","msg":"trace[1012056973] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2171; }","duration":"178.774771ms","start":"2026-04-22T20:42:03.535901Z","end":"2026-04-22T20:42:03.714676Z","steps":["trace[1012056973] 'read index received' (duration: 96.376024ms)","trace[1012056973] 'applied index is now lower than readState.Index' (duration: 82.397957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T20:42:03.714901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T20:42:03.408584Z","time spent":"306.308028ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-22T20:42:03.740313Z","caller":"traceutil/trace.go:171","msg":"trace[1015400995] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"200.242111ms","start":"2026-04-22T20:42:03.540043Z","end":"2026-04-22T20:42:03.740285Z","steps":["trace[1015400995] 'process raft request' (duration: 200.124ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:42:53.902075Z","caller":"traceutil/trace.go:171","msg":"trace[1645038196] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"139.322564ms","start":"2026-04-22T20:42:53.762726Z","end":"2026-04-22T20:42:53.902049Z","steps":["trace[1645038196] 'process raft request' (duration: 139.178963ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:43:26.251021Z","caller":"traceutil/trace.go:171","msg":"trace[45405386] linearizableReadLoop","detail":"{readStateIndex:2746; appliedIndex:2745; }","duration":"206.09529ms","start":"2026-04-22T20:43:26.044899Z","end":"2026-04-22T20:43:26.250994Z","steps":["trace[45405386] 'read index received' (duration: 205.849238ms)","trace[45405386] 'applied index is now lower than readState.Index' (duration: 244.712µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T20:43:26.251214Z","caller":"traceutil/trace.go:171","msg":"trace[163273618] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"223.727271ms","start":"2026-04-22T20:43:26.02747Z","end":"2026-04-22T20:43:26.251198Z","steps":["trace[163273618] 'process raft request' (duration: 223.365208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:43:26.251401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.782815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T20:43:26.251456Z","caller":"traceutil/trace.go:171","msg":"trace[1187648735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2647; }","duration":"123.887386ms","start":"2026-04-22T20:43:26.127556Z","end":"2026-04-22T20:43:26.251443Z","steps":["trace[1187648735] 'agreement among raft nodes before linearized reading' (duration: 123.707214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:43:26.251561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.670024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-22T20:43:26.251577Z","caller":"traceutil/trace.go:171","msg":"trace[584923844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2647; }","duration":"206.723026ms","start":"2026-04-22T20:43:26.04485Z","end":"2026-04-22T20:43:26.251573Z","steps":["trace[584923844] 'agreement among raft nodes before linearized reading' (duration: 206.680105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:43:26.251651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.14559ms","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-04-22T20:43:26.251666Z","caller":"traceutil/trace.go:171","msg":"trace[1717735798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2647; }","duration":"123.17808ms","start":"2026-04-22T20:43:26.128485Z","end":"2026-04-22T20:43:26.251663Z","steps":["trace[1717735798] 'agreement among raft nodes before linearized reading' (duration: 123.12863ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:43:26.361467Z","caller":"traceutil/trace.go:171","msg":"trace[1367097457] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"104.698993ms","start":"2026-04-22T20:43:26.256739Z","end":"2026-04-22T20:43:26.361438Z","steps":["trace[1367097457] 'process raft request' (duration: 81.607499ms)","trace[1367097457] 'compare' (duration: 22.990143ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T20:43:26.370773Z","caller":"traceutil/trace.go:171","msg":"trace[57445809] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"113.560045ms","start":"2026-04-22T20:43:26.257189Z","end":"2026-04-22T20:43:26.370749Z","steps":["trace[57445809] 'process raft request' (duration: 113.416643ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:43:26.371092Z","caller":"traceutil/trace.go:171","msg":"trace[1578569786] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"113.378843ms","start":"2026-04-22T20:43:26.257685Z","end":"2026-04-22T20:43:26.371064Z","steps":["trace[1578569786] 'process raft request' (duration: 113.0223ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:47:27.406644Z","caller":"traceutil/trace.go:171","msg":"trace[1514284452] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"132.960671ms","start":"2026-04-22T20:47:27.273343Z","end":"2026-04-22T20:47:27.406304Z","steps":["trace[1514284452] 'process raft request' (duration: 132.728205ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:47:27.467676Z","caller":"traceutil/trace.go:171","msg":"trace[792715130] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"181.278162ms","start":"2026-04-22T20:47:27.28619Z","end":"2026-04-22T20:47:27.467468Z","steps":["trace[792715130] 'process raft request' (duration: 181.136709ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:47:28.22322Z","caller":"traceutil/trace.go:171","msg":"trace[1847667953] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"150.640416ms","start":"2026-04-22T20:47:28.07255Z","end":"2026-04-22T20:47:28.223191Z","steps":["trace[1847667953] 'process raft request' (duration: 150.219396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:47:28.223223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.416378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T20:47:28.223656Z","caller":"traceutil/trace.go:171","msg":"trace[1416964393] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4831; }","duration":"141.88609ms","start":"2026-04-22T20:47:28.08174Z","end":"2026-04-22T20:47:28.223626Z","steps":["trace[1416964393] 'agreement among raft nodes before linearized reading' (duration: 141.355967ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:47:55.968302Z","caller":"traceutil/trace.go:171","msg":"trace[949369339] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"107.909716ms","start":"2026-04-22T20:47:55.86037Z","end":"2026-04-22T20:47:55.96828Z","steps":["trace[949369339] 'process raft request' (duration: 107.787023ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:49:14.793455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2907} {"level":"info","ts":"2026-04-22T20:49:14.890691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2907,"took":"95.88227ms","hash":2508603382,"current-db-size-bytes":54603776,"current-db-size":"55 MB","current-db-size-in-use-bytes":29470720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-22T20:49:14.890763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2508603382,"revision":2907,"compact-revision":-1} {"level":"info","ts":"2026-04-22T20:51:39.193263Z","caller":"traceutil/trace.go:171","msg":"trace[934655691] linearizableReadLoop","detail":"{readStateIndex:6892; appliedIndex:6891; }","duration":"138.828326ms","start":"2026-04-22T20:51:39.054409Z","end":"2026-04-22T20:51:39.193238Z","steps":["trace[934655691] 'read index received' (duration: 138.635584ms)","trace[934655691] 'applied index is now lower than readState.Index' (duration: 191.732µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T20:51:39.193602Z","caller":"traceutil/trace.go:171","msg":"trace[521229265] transaction","detail":"{read_only:false; response_revision:6652; number_of_response:1; }","duration":"165.512914ms","start":"2026-04-22T20:51:39.028074Z","end":"2026-04-22T20:51:39.193587Z","steps":["trace[521229265] 'process raft request' (duration: 165.028526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T20:51:39.193755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.330234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T20:51:39.195423Z","caller":"traceutil/trace.go:171","msg":"trace[1262825222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6652; }","duration":"141.037897ms","start":"2026-04-22T20:51:39.054368Z","end":"2026-04-22T20:51:39.195406Z","steps":["trace[1262825222] 'agreement among raft nodes before linearized reading' (duration: 139.218433ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T20:54:14.799431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5665} {"level":"info","ts":"2026-04-22T20:54:14.906607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5665,"took":"105.488557ms","hash":457932957,"current-db-size-bytes":54603776,"current-db-size":"55 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-22T20:54:14.906667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":457932957,"revision":5665,"compact-revision":2907}