{"level":"warn","ts":"2026-08-05T11:16:56.603155Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:16:56.60323Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.115:2380","--initial-cluster=instance=https://199.19.213.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-05T11:16:56.603286Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:16:56.603294Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.115:2380"]} {"level":"info","ts":"2026-08-05T11:16:56.603317Z","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-08-05T11:16:56.603935Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.115:2379"]} {"level":"info","ts":"2026-08-05T11:16:56.604004Z","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.19.213.115:2380"],"listen-peer-urls":["https://199.19.213.115:2380"],"advertise-client-urls":["https://199.19.213.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-05T11:16:56.608079Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.746814ms"} {"level":"info","ts":"2026-08-05T11:16:56.61443Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"76964ef0a001678d","cluster-id":"a26f37ac4c69834d"} {"level":"info","ts":"2026-08-05T11:16:56.614512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:16:56.614535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became follower at term 0"} {"level":"info","ts":"2026-08-05T11:16:56.614585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 76964ef0a001678d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T11:16:56.614608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became follower at term 1"} {"level":"info","ts":"2026-08-05T11:16:56.614648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d switched to configuration voters=(8545104138365659021)"} {"level":"warn","ts":"2026-08-05T11:16:56.62058Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:16:56.621736Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:16:56.622799Z","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-08-05T11:16:56.624701Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"76964ef0a001678d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T11:16:56.624846Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"76964ef0a001678d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T11:16:56.624949Z","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-08-05T11:16:56.625225Z","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-08-05T11:16:56.625246Z","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-08-05T11:16:56.626549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d switched to configuration voters=(8545104138365659021)"} {"level":"info","ts":"2026-08-05T11:16:56.626792Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a26f37ac4c69834d","local-member-id":"76964ef0a001678d","added-peer-id":"76964ef0a001678d","added-peer-peer-urls":["https://199.19.213.115:2380"]} {"level":"info","ts":"2026-08-05T11:16:56.629796Z","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-08-05T11:16:56.630055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"76964ef0a001678d","initial-advertise-peer-urls":["https://199.19.213.115:2380"],"listen-peer-urls":["https://199.19.213.115:2380"],"advertise-client-urls":["https://199.19.213.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T11:16:56.630085Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:16:56.630163Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.115:2380"} {"level":"info","ts":"2026-08-05T11:16:56.630181Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.115:2380"} {"level":"info","ts":"2026-08-05T11:16:57.515587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:16:57.515645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:16:57.51567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d received MsgPreVoteResp from 76964ef0a001678d at term 1"} {"level":"info","ts":"2026-08-05T11:16:57.51569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:16:57.515701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d received MsgVoteResp from 76964ef0a001678d at term 2"} {"level":"info","ts":"2026-08-05T11:16:57.515717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became leader at term 2"} {"level":"info","ts":"2026-08-05T11:16:57.515737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 76964ef0a001678d elected leader 76964ef0a001678d at term 2"} {"level":"info","ts":"2026-08-05T11:16:57.516579Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"76964ef0a001678d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.115:2379]}","request-path":"/0/members/76964ef0a001678d/attributes","cluster-id":"a26f37ac4c69834d","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T11:16:57.516743Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:16:57.51681Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:16:57.516865Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:16:57.517121Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:16:57.517168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:16:57.51759Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a26f37ac4c69834d","local-member-id":"76964ef0a001678d","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:16:57.517684Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:16:57.517714Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:16:57.520518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.115:2379"} {"level":"info","ts":"2026-08-05T11:16:57.52052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T11:17:13.245042Z","caller":"traceutil/trace.go:171","msg":"trace[1653533324] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"112.91635ms","start":"2026-08-05T11:17:13.132101Z","end":"2026-08-05T11:17:13.245017Z","steps":["trace[1653533324] 'read index received' (duration: 112.713731ms)","trace[1653533324] 'applied index is now lower than readState.Index' (duration: 199.959µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:17:13.245144Z","caller":"traceutil/trace.go:171","msg":"trace[493484379] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"133.497515ms","start":"2026-08-05T11:17:13.111622Z","end":"2026-08-05T11:17:13.24512Z","steps":["trace[493484379] 'process raft request' (duration: 133.263194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:17:13.245306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.12778ms","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-08-05T11:17:13.245447Z","caller":"traceutil/trace.go:171","msg":"trace[50226973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"113.331698ms","start":"2026-08-05T11:17:13.132085Z","end":"2026-08-05T11:17:13.245416Z","steps":["trace[50226973] 'agreement among raft nodes before linearized reading' (duration: 113.068117ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:17:13.504261Z","caller":"traceutil/trace.go:171","msg":"trace[1231711358] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"255.547698ms","start":"2026-08-05T11:17:13.248688Z","end":"2026-08-05T11:17:13.504236Z","steps":["trace[1231711358] 'process raft request' (duration: 255.382341ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:17:13.567009Z","caller":"traceutil/trace.go:171","msg":"trace[212019081] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:417; }","duration":"191.378348ms","start":"2026-08-05T11:17:13.375613Z","end":"2026-08-05T11:17:13.566992Z","steps":["trace[212019081] 'read index received' (duration: 128.461924ms)","trace[212019081] 'applied index is now lower than readState.Index' (duration: 62.915544ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:17:13.56711Z","caller":"traceutil/trace.go:171","msg":"trace[1946369882] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"317.98031ms","start":"2026-08-05T11:17:13.249085Z","end":"2026-08-05T11:17:13.567065Z","steps":["trace[1946369882] 'process raft request' (duration: 307.162054ms)","trace[1946369882] 'compare' (duration: 10.672199ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:17:13.567275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.64332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:17:13.567708Z","caller":"traceutil/trace.go:171","msg":"trace[820297626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"192.12665ms","start":"2026-08-05T11:17:13.375563Z","end":"2026-08-05T11:17:13.567689Z","steps":["trace[820297626] 'agreement among raft nodes before linearized reading' (duration: 191.522834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:17:13.567991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:17:13.249075Z","time spent":"318.554844ms","remote":"127.0.0.1:41560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:17:38.833259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.845658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:17:38.833374Z","caller":"traceutil/trace.go:171","msg":"trace[745369175] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"507.658815ms","start":"2026-08-05T11:17:38.325698Z","end":"2026-08-05T11:17:38.833357Z","steps":["trace[745369175] 'process raft request' (duration: 356.498093ms)","trace[745369175] 'compare' (duration: 150.471132ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:17:38.833435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:17:38.325666Z","time spent":"507.741888ms","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:17:53.997173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.42007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:17:53.997359Z","caller":"traceutil/trace.go:171","msg":"trace[918604782] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"131.53559ms","start":"2026-08-05T11:17:53.865788Z","end":"2026-08-05T11:17:53.997324Z","steps":["trace[918604782] 'read index received' (duration: 47.422µs)","trace[918604782] 'applied index is now lower than readState.Index' (duration: 131.486088ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:17:53.997476Z","caller":"traceutil/trace.go:171","msg":"trace[1257506530] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"257.15158ms","start":"2026-08-05T11:17:53.740286Z","end":"2026-08-05T11:17:53.997438Z","steps":["trace[1257506530] 'process raft request' (duration: 111.338023ms)","trace[1257506530] 'compare' (duration: 145.282564ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:17:53.997522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.91476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:17:53.99763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.821813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:17:53.99769Z","caller":"traceutil/trace.go:171","msg":"trace[2015883414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"105.070887ms","start":"2026-08-05T11:17:53.892594Z","end":"2026-08-05T11:17:53.997665Z","steps":["trace[2015883414] 'agreement among raft nodes before linearized reading' (duration: 104.889449ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:17:53.997706Z","caller":"traceutil/trace.go:171","msg":"trace[1335911482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"131.960989ms","start":"2026-08-05T11:17:53.865728Z","end":"2026-08-05T11:17:53.997689Z","steps":["trace[1335911482] 'agreement among raft nodes before linearized reading' (duration: 131.707338ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:08.142847Z","caller":"traceutil/trace.go:171","msg":"trace[1029635982] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"354.460853ms","start":"2026-08-05T11:19:07.788358Z","end":"2026-08-05T11:19:08.142819Z","steps":["trace[1029635982] 'read index received' (duration: 350.214094ms)","trace[1029635982] 'applied index is now lower than readState.Index' (duration: 4.245199ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:19:08.142965Z","caller":"traceutil/trace.go:171","msg":"trace[867812572] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"404.069131ms","start":"2026-08-05T11:19:07.738884Z","end":"2026-08-05T11:19:08.142954Z","steps":["trace[867812572] 'process raft request' (duration: 399.771673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:08.143063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:19:07.738874Z","time spent":"404.114871ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:19:08.143268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.608238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:19:08.143514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.1376ms","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-08-05T11:19:08.143384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.963522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd67f5a8-5040-40cc-999c-917a9b06653a\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-08-05T11:19:08.143607Z","caller":"traceutil/trace.go:171","msg":"trace[921210587] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1857; }","duration":"355.27588ms","start":"2026-08-05T11:19:07.788308Z","end":"2026-08-05T11:19:08.143584Z","steps":["trace[921210587] 'agreement among raft nodes before linearized reading' (duration: 354.951781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:08.143661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:19:07.788286Z","time spent":"355.36639ms","remote":"127.0.0.1:50218","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-08-05T11:19:08.143666Z","caller":"traceutil/trace.go:171","msg":"trace[1653205710] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd67f5a8-5040-40cc-999c-917a9b06653a; range_end:; response_count:1; response_revision:1857; }","duration":"181.273981ms","start":"2026-08-05T11:19:07.962372Z","end":"2026-08-05T11:19:08.143646Z","steps":["trace[1653205710] 'agreement among raft nodes before linearized reading' (duration: 180.857032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:08.143462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.258908ms","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-08-05T11:19:08.143751Z","caller":"traceutil/trace.go:171","msg":"trace[814666591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1857; }","duration":"336.617136ms","start":"2026-08-05T11:19:07.807115Z","end":"2026-08-05T11:19:08.143732Z","steps":["trace[814666591] 'agreement among raft nodes before linearized reading' (duration: 336.103028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:08.143813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:19:07.807093Z","time spent":"336.703836ms","remote":"127.0.0.1:41560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-05T11:19:08.143896Z","caller":"traceutil/trace.go:171","msg":"trace[316004707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"250.930137ms","start":"2026-08-05T11:19:07.892609Z","end":"2026-08-05T11:19:08.14354Z","steps":["trace[316004707] 'agreement among raft nodes before linearized reading' (duration: 250.622728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:08.4636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.214166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-05T11:19:08.463624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.396094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:19:08.463677Z","caller":"traceutil/trace.go:171","msg":"trace[1920786292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1863; }","duration":"112.318716ms","start":"2026-08-05T11:19:08.351341Z","end":"2026-08-05T11:19:08.463659Z","steps":["trace[1920786292] 'range keys from in-memory index tree' (duration: 112.081036ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:08.463692Z","caller":"traceutil/trace.go:171","msg":"trace[883414882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1863; }","duration":"116.470164ms","start":"2026-08-05T11:19:08.347205Z","end":"2026-08-05T11:19:08.463675Z","steps":["trace[883414882] 'range keys from in-memory index tree' (duration: 116.249334ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:31.048215Z","caller":"traceutil/trace.go:171","msg":"trace[373460820] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"101.415296ms","start":"2026-08-05T11:19:30.946761Z","end":"2026-08-05T11:19:31.048176Z","steps":["trace[373460820] 'compare' (duration: 96.707022ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:37.47414Z","caller":"traceutil/trace.go:171","msg":"trace[1737632193] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"147.689659ms","start":"2026-08-05T11:19:37.326426Z","end":"2026-08-05T11:19:37.474115Z","steps":["trace[1737632193] 'process raft request' (duration: 147.508849ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:37.485863Z","caller":"traceutil/trace.go:171","msg":"trace[1266379495] linearizableReadLoop","detail":"{readStateIndex:2153; appliedIndex:2151; }","duration":"127.363261ms","start":"2026-08-05T11:19:37.358475Z","end":"2026-08-05T11:19:37.485839Z","steps":["trace[1266379495] 'read index received' (duration: 115.089542ms)","trace[1266379495] 'applied index is now lower than readState.Index' (duration: 12.272589ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:19:37.485942Z","caller":"traceutil/trace.go:171","msg":"trace[2080346156] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"158.879427ms","start":"2026-08-05T11:19:37.32704Z","end":"2026-08-05T11:19:37.485919Z","steps":["trace[2080346156] 'process raft request' (duration: 158.657196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:37.486203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.690471ms","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-08-05T11:19:37.486324Z","caller":"traceutil/trace.go:171","msg":"trace[2112473636] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2076; }","duration":"127.881131ms","start":"2026-08-05T11:19:37.358427Z","end":"2026-08-05T11:19:37.486308Z","steps":["trace[2112473636] 'agreement among raft nodes before linearized reading' (duration: 127.603531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:21:25.492453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.443719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T11:21:25.492577Z","caller":"traceutil/trace.go:171","msg":"trace[256497402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2759; }","duration":"149.612901ms","start":"2026-08-05T11:21:25.342938Z","end":"2026-08-05T11:21:25.492551Z","steps":["trace[256497402] 'range keys from in-memory index tree' (duration: 149.281546ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:01.932792Z","caller":"traceutil/trace.go:171","msg":"trace[770589518] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"164.265412ms","start":"2026-08-05T11:25:01.768495Z","end":"2026-08-05T11:25:01.93276Z","steps":["trace[770589518] 'process raft request' (duration: 164.070597ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:02.196363Z","caller":"traceutil/trace.go:171","msg":"trace[1903614327] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"135.312198ms","start":"2026-08-05T11:25:02.061033Z","end":"2026-08-05T11:25:02.196346Z","steps":["trace[1903614327] 'process raft request' (duration: 66.617686ms)","trace[1903614327] 'compare' (duration: 68.425855ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:02.320877Z","caller":"traceutil/trace.go:171","msg":"trace[1942262476] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"118.44049ms","start":"2026-08-05T11:25:02.202417Z","end":"2026-08-05T11:25:02.320857Z","steps":["trace[1942262476] 'process raft request' (duration: 112.112135ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:14.994342Z","caller":"traceutil/trace.go:171","msg":"trace[764466370] linearizableReadLoop","detail":"{readStateIndex:5059; appliedIndex:5057; }","duration":"103.471604ms","start":"2026-08-05T11:25:14.890829Z","end":"2026-08-05T11:25:14.994301Z","steps":["trace[764466370] 'read index received' (duration: 8.570301ms)","trace[764466370] 'applied index is now lower than readState.Index' (duration: 94.899963ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:14.994461Z","caller":"traceutil/trace.go:171","msg":"trace[1499228111] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"155.923492ms","start":"2026-08-05T11:25:14.838487Z","end":"2026-08-05T11:25:14.994411Z","steps":["trace[1499228111] 'process raft request' (duration: 98.057492ms)","trace[1499228111] 'compare' (duration: 57.613373ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:14.994586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.717391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:25:14.994688Z","caller":"traceutil/trace.go:171","msg":"trace[213231767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4879; }","duration":"103.884655ms","start":"2026-08-05T11:25:14.890785Z","end":"2026-08-05T11:25:14.994669Z","steps":["trace[213231767] 'agreement among raft nodes before linearized reading' (duration: 103.645649ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:57.543531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2930} {"level":"info","ts":"2026-08-05T11:26:57.632966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2930,"took":"87.559609ms","hash":1462578944,"current-db-size-bytes":54046720,"current-db-size":"54 MB","current-db-size-in-use-bytes":29495296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T11:26:57.633137Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1462578944,"revision":2930,"compact-revision":-1} {"level":"info","ts":"2026-08-05T11:31:57.550532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5659} {"level":"info","ts":"2026-08-05T11:31:57.674962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5659,"took":"122.523216ms","hash":2092873785,"current-db-size-bytes":54046720,"current-db-size":"54 MB","current-db-size-in-use-bytes":18292736,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-05T11:31:57.675017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2092873785,"revision":5659,"compact-revision":2930}