{"level":"warn","ts":"2026-05-15T13:42:36.610128Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T13:42:36.610213Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229: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.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229: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-05-15T13:42:36.61028Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T13:42:36.610298Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-05-15T13:42:36.610322Z","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-05-15T13:42:36.611003Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-05-15T13:42:36.611087Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229: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-05-15T13:42:36.618561Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.268377ms"} {"level":"info","ts":"2026-05-15T13:42:36.63256Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-05-15T13:42:36.63268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-15T13:42:36.632711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-05-15T13:42:36.632727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-15T13:42:36.632806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-05-15T13:42:36.632912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-05-15T13:42:36.640335Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-15T13:42:36.643157Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-15T13:42:36.644552Z","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-05-15T13:42:36.648042Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-15T13:42:36.648305Z","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-05-15T13:42:36.648414Z","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-05-15T13:42:36.648428Z","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-05-15T13:42:36.648389Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-15T13:42:36.650116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-05-15T13:42:36.650307Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-05-15T13:42:36.654295Z","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-05-15T13:42:36.654441Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-05-15T13:42:36.654496Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-05-15T13:42:36.655022Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-15T13:42:36.655154Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-15T13:42:37.433523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-15T13:42:37.433602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-15T13:42:37.433682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-05-15T13:42:37.433704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-05-15T13:42:37.433716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-05-15T13:42:37.433741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-05-15T13:42:37.43376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-05-15T13:42:37.434839Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-05-15T13:42:37.434889Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T13:42:37.434853Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T13:42:37.434838Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T13:42:37.435143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-15T13:42:37.435319Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-15T13:42:37.436433Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T13:42:37.436538Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T13:42:37.43658Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T13:42:37.437709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-15T13:42:37.437926Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-05-15T13:42:57.644713Z","caller":"traceutil/trace.go:171","msg":"trace[1664905017] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"108.468609ms","start":"2026-05-15T13:42:57.536218Z","end":"2026-05-15T13:42:57.644687Z","steps":["trace[1664905017] 'process raft request' (duration: 108.328396ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:43:03.427315Z","caller":"traceutil/trace.go:171","msg":"trace[1522096756] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"172.670644ms","start":"2026-05-15T13:43:03.254603Z","end":"2026-05-15T13:43:03.427273Z","steps":["trace[1522096756] 'process raft request' (duration: 165.948921ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:43:14.213304Z","caller":"traceutil/trace.go:171","msg":"trace[1128225526] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"192.302421ms","start":"2026-05-15T13:43:14.020974Z","end":"2026-05-15T13:43:14.213276Z","steps":["trace[1128225526] 'process raft request' (duration: 191.654715ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:43:14.44749Z","caller":"traceutil/trace.go:171","msg":"trace[950513232] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"178.206309ms","start":"2026-05-15T13:43:14.269225Z","end":"2026-05-15T13:43:14.447431Z","steps":["trace[950513232] 'process raft request' (duration: 177.943832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:43:14.886869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.313819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-15T13:43:14.886984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.661458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-47t8f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-15T13:43:14.887001Z","caller":"traceutil/trace.go:171","msg":"trace[1054704882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"227.492284ms","start":"2026-05-15T13:43:14.659485Z","end":"2026-05-15T13:43:14.886977Z","steps":["trace[1054704882] 'range keys from in-memory index tree' (duration: 227.092843ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:43:14.887037Z","caller":"traceutil/trace.go:171","msg":"trace[182957274] range","detail":"{range_begin:/registry/pods/kube-system/cilium-47t8f; range_end:; response_count:1; response_revision:622; }","duration":"338.7455ms","start":"2026-05-15T13:43:14.548276Z","end":"2026-05-15T13:43:14.887022Z","steps":["trace[182957274] 'range keys from in-memory index tree' (duration: 338.503954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:43:14.887072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T13:43:14.548256Z","time spent":"338.807292ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-47t8f\" "} {"level":"info","ts":"2026-05-15T13:43:15.01251Z","caller":"traceutil/trace.go:171","msg":"trace[19070057] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"127.321916ms","start":"2026-05-15T13:43:14.885162Z","end":"2026-05-15T13:43:15.012484Z","steps":["trace[19070057] 'process raft request' (duration: 126.704871ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:43:15.039236Z","caller":"traceutil/trace.go:171","msg":"trace[1203046389] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"138.65449ms","start":"2026-05-15T13:43:14.900558Z","end":"2026-05-15T13:43:15.039212Z","steps":["trace[1203046389] 'process raft request' (duration: 138.597848ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:43:15.039523Z","caller":"traceutil/trace.go:171","msg":"trace[962675683] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"146.482401ms","start":"2026-05-15T13:43:14.893034Z","end":"2026-05-15T13:43:15.039517Z","steps":["trace[962675683] 'process raft request' (duration: 146.023609ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:43:33.71928Z","caller":"traceutil/trace.go:171","msg":"trace[472824026] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"163.995055ms","start":"2026-05-15T13:43:33.555251Z","end":"2026-05-15T13:43:33.719246Z","steps":["trace[472824026] 'process raft request' (duration: 157.299562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:44:49.454713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.400411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f12be920-907a-4bbf-8b6e-699a54434a04\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-15T13:44:49.454926Z","caller":"traceutil/trace.go:171","msg":"trace[337554598] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f12be920-907a-4bbf-8b6e-699a54434a04; range_end:; response_count:1; response_revision:1866; }","duration":"415.662168ms","start":"2026-05-15T13:44:49.039232Z","end":"2026-05-15T13:44:49.454894Z","steps":["trace[337554598] 'range keys from in-memory index tree' (duration: 415.054953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:44:49.4551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T13:44:49.039207Z","time spent":"415.761359ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f12be920-907a-4bbf-8b6e-699a54434a04\" "} {"level":"warn","ts":"2026-05-15T13:44:49.455311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.473962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T13:44:49.455437Z","caller":"traceutil/trace.go:171","msg":"trace[1065475099] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"100.575526ms","start":"2026-05-15T13:44:49.354843Z","end":"2026-05-15T13:44:49.455419Z","steps":["trace[1065475099] 'read index received' (duration: 71.842µs)","trace[1065475099] 'applied index is now lower than readState.Index' (duration: 100.500744ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T13:44:49.455493Z","caller":"traceutil/trace.go:171","msg":"trace[235560421] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"392.960947ms","start":"2026-05-15T13:44:49.062489Z","end":"2026-05-15T13:44:49.45545Z","steps":["trace[235560421] 'process raft request' (duration: 71.744627ms)","trace[235560421] 'compare' (duration: 320.232406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T13:44:49.455623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.784431ms","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-05-15T13:44:49.455871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T13:44:49.062462Z","time spent":"393.100441ms","remote":"127.0.0.1:37706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T13:44:49.455914Z","caller":"traceutil/trace.go:171","msg":"trace[1262914664] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"101.116489ms","start":"2026-05-15T13:44:49.354786Z","end":"2026-05-15T13:44:49.455902Z","steps":["trace[1262914664] 'agreement among raft nodes before linearized reading' (duration: 100.729269ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:44:49.847016Z","caller":"traceutil/trace.go:171","msg":"trace[219093759] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"335.151747ms","start":"2026-05-15T13:44:49.511838Z","end":"2026-05-15T13:44:49.84699Z","steps":["trace[219093759] 'read index received' (duration: 334.82974ms)","trace[219093759] 'applied index is now lower than readState.Index' (duration: 318.897µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T13:44:49.847056Z","caller":"traceutil/trace.go:171","msg":"trace[1983443686] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"381.232473ms","start":"2026-05-15T13:44:49.465809Z","end":"2026-05-15T13:44:49.847042Z","steps":["trace[1983443686] 'process raft request' (duration: 380.962467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:44:49.84724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T13:44:49.46579Z","time spent":"381.350496ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T13:44:49.847304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.472465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T13:44:49.847383Z","caller":"traceutil/trace.go:171","msg":"trace[488467567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1868; }","duration":"335.590868ms","start":"2026-05-15T13:44:49.511783Z","end":"2026-05-15T13:44:49.847374Z","steps":["trace[488467567] 'agreement among raft nodes before linearized reading' (duration: 335.303462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:44:49.847457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T13:44:49.511762Z","time spent":"335.6871ms","remote":"127.0.0.1:37706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T13:44:49.847433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.078396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f12be920-907a-4bbf-8b6e-699a54434a04\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-15T13:44:49.84756Z","caller":"traceutil/trace.go:171","msg":"trace[1418972843] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f12be920-907a-4bbf-8b6e-699a54434a04; range_end:; response_count:1; response_revision:1868; }","duration":"174.284071ms","start":"2026-05-15T13:44:49.673248Z","end":"2026-05-15T13:44:49.847532Z","steps":["trace[1418972843] 'agreement among raft nodes before linearized reading' (duration: 174.021415ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:45:05.632964Z","caller":"traceutil/trace.go:171","msg":"trace[1790695864] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"121.628614ms","start":"2026-05-15T13:45:05.511297Z","end":"2026-05-15T13:45:05.632925Z","steps":["trace[1790695864] 'process raft request' (duration: 121.376328ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:45:44.238611Z","caller":"traceutil/trace.go:171","msg":"trace[230424455] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"157.877561ms","start":"2026-05-15T13:45:44.080705Z","end":"2026-05-15T13:45:44.238583Z","steps":["trace[230424455] 'process raft request' (duration: 157.423151ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:45:44.604158Z","caller":"traceutil/trace.go:171","msg":"trace[1444309673] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2296; }","duration":"123.824396ms","start":"2026-05-15T13:45:44.480307Z","end":"2026-05-15T13:45:44.604132Z","steps":["trace[1444309673] 'read index received' (duration: 123.539519ms)","trace[1444309673] 'applied index is now lower than readState.Index' (duration: 283.807µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T13:45:44.604344Z","caller":"traceutil/trace.go:171","msg":"trace[1287077293] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"141.524694ms","start":"2026-05-15T13:45:44.462802Z","end":"2026-05-15T13:45:44.604326Z","steps":["trace[1287077293] 'process raft request' (duration: 141.174915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:45:44.604516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.193375ms","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-05-15T13:45:44.604591Z","caller":"traceutil/trace.go:171","msg":"trace[1920629131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2215; }","duration":"124.299177ms","start":"2026-05-15T13:45:44.480265Z","end":"2026-05-15T13:45:44.604565Z","steps":["trace[1920629131] 'agreement among raft nodes before linearized reading' (duration: 124.105133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:45:44.886939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.551268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T13:45:44.887161Z","caller":"traceutil/trace.go:171","msg":"trace[2022743398] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"278.265173ms","start":"2026-05-15T13:45:44.60888Z","end":"2026-05-15T13:45:44.887145Z","steps":["trace[2022743398] 'process raft request' (duration: 278.178881ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:45:44.887237Z","caller":"traceutil/trace.go:171","msg":"trace[1988413866] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"278.679962ms","start":"2026-05-15T13:45:44.608526Z","end":"2026-05-15T13:45:44.887206Z","steps":["trace[1988413866] 'process raft request' (duration: 47.772906ms)","trace[1988413866] 'compare' (duration: 230.271231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T13:45:45.142948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.978132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T13:45:45.143169Z","caller":"traceutil/trace.go:171","msg":"trace[546804485] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"393.897582ms","start":"2026-05-15T13:45:44.749258Z","end":"2026-05-15T13:45:45.143155Z","steps":["trace[546804485] 'process raft request' (duration: 252.642743ms)","trace[546804485] 'compare' (duration: 140.870278ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T13:45:45.143316Z","caller":"traceutil/trace.go:171","msg":"trace[1031875094] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"215.629738ms","start":"2026-05-15T13:45:44.927668Z","end":"2026-05-15T13:45:45.143297Z","steps":["trace[1031875094] 'process raft request' (duration: 215.388582ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:45:45.143443Z","caller":"traceutil/trace.go:171","msg":"trace[876752851] linearizableReadLoop","detail":"{readStateIndex:2300; appliedIndex:2299; }","duration":"239.147388ms","start":"2026-05-15T13:45:44.904267Z","end":"2026-05-15T13:45:45.143415Z","steps":["trace[876752851] 'read index received' (duration: 97.755786ms)","trace[876752851] 'applied index is now lower than readState.Index' (duration: 141.387942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T13:45:45.143231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T13:45:44.749233Z","time spent":"393.970584ms","remote":"127.0.0.1:37706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T13:45:45.143593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.329192ms","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-05-15T13:45:45.143647Z","caller":"traceutil/trace.go:171","msg":"trace[1770386580] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2219; }","duration":"239.397934ms","start":"2026-05-15T13:45:44.90422Z","end":"2026-05-15T13:45:45.143617Z","steps":["trace[1770386580] 'agreement among raft nodes before linearized reading' (duration: 239.285091ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:46:26.942908Z","caller":"traceutil/trace.go:171","msg":"trace[1957317638] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"142.46865ms","start":"2026-05-15T13:46:26.800412Z","end":"2026-05-15T13:46:26.942881Z","steps":["trace[1957317638] 'process raft request' (duration: 142.315366ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:47:01.36583Z","caller":"traceutil/trace.go:171","msg":"trace[588188282] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"114.746259ms","start":"2026-05-15T13:47:01.251058Z","end":"2026-05-15T13:47:01.365804Z","steps":["trace[588188282] 'process raft request' (duration: 114.562895ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T13:47:01.398159Z","caller":"traceutil/trace.go:171","msg":"trace[2145601423] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"146.17976ms","start":"2026-05-15T13:47:01.251939Z","end":"2026-05-15T13:47:01.398118Z","steps":["trace[2145601423] 'process raft request' (duration: 145.970285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:47:01.736053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.407071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T13:47:01.736162Z","caller":"traceutil/trace.go:171","msg":"trace[1496241339] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"311.326578ms","start":"2026-05-15T13:47:01.424816Z","end":"2026-05-15T13:47:01.736143Z","steps":["trace[1496241339] 'process raft request' (duration: 96.750322ms)","trace[1496241339] 'compare' (duration: 214.137175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T13:47:01.736219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T13:47:01.424785Z","time spent":"311.40668ms","remote":"127.0.0.1:37706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T13:47:01.750026Z","caller":"traceutil/trace.go:171","msg":"trace[490048771] linearizableReadLoop","detail":"{readStateIndex:2934; appliedIndex:2932; }","duration":"236.318724ms","start":"2026-05-15T13:47:01.513683Z","end":"2026-05-15T13:47:01.750002Z","steps":["trace[490048771] 'read index received' (duration: 7.862041ms)","trace[490048771] 'applied index is now lower than readState.Index' (duration: 228.455813ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T13:47:01.750048Z","caller":"traceutil/trace.go:171","msg":"trace[382626345] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"324.561139ms","start":"2026-05-15T13:47:01.425471Z","end":"2026-05-15T13:47:01.750032Z","steps":["trace[382626345] 'process raft request' (duration: 324.426716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:47:01.750164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.487657ms","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-05-15T13:47:01.750186Z","caller":"traceutil/trace.go:171","msg":"trace[884705435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2828; }","duration":"236.559829ms","start":"2026-05-15T13:47:01.51362Z","end":"2026-05-15T13:47:01.75018Z","steps":["trace[884705435] 'agreement among raft nodes before linearized reading' (duration: 236.461376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T13:47:01.750204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T13:47:01.425446Z","time spent":"324.679642ms","remote":"127.0.0.1:37706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}