{"level":"warn","ts":"2026-07-25T08:18:48.525046Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T08:18:48.525144Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-07-25T08:18:48.525211Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T08:18:48.525223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-25T08:18:48.525245Z","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-07-25T08:18:48.525859Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-07-25T08:18:48.525937Z","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.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-07-25T08:18:48.627952Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.029034ms"} {"level":"info","ts":"2026-07-25T08:18:48.636014Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-07-25T08:18:48.63615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T08:18:48.636182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-07-25T08:18:48.636209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T08:18:48.636234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-07-25T08:18:48.636293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-07-25T08:18:48.641468Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T08:18:48.642668Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T08:18:48.643829Z","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-07-25T08:18:48.647629Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T08:18:48.650381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T08:18:48.654926Z","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-07-25T08:18:48.655091Z","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-07-25T08:18:48.655124Z","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-07-25T08:18:48.65599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-07-25T08:18:48.662617Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-25T08:18:48.663709Z","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-07-25T08:18:48.663796Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-25T08:18:48.663891Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-25T08:18:48.664249Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T08:18:48.664319Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T08:18:49.036946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T08:18:49.037026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T08:18:49.037085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-07-25T08:18:49.037113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-07-25T08:18:49.037129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-25T08:18:49.037151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-07-25T08:18:49.037213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-25T08:18:49.038588Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T08:18:49.038641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T08:18:49.038744Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T08:18:49.038928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T08:18:49.039666Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T08:18:49.039764Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T08:18:49.039802Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T08:18:49.039892Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T08:18:49.039971Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T08:18:49.041309Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T08:18:49.043377Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"warn","ts":"2026-07-25T08:19:10.610946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.982705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T08:19:10.611071Z","caller":"traceutil/trace.go:171","msg":"trace[1264135667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:386; }","duration":"134.155441ms","start":"2026-07-25T08:19:10.476891Z","end":"2026-07-25T08:19:10.611047Z","steps":["trace[1264135667] 'range keys from in-memory index tree' (duration: 133.858931ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:19:31.859306Z","caller":"traceutil/trace.go:171","msg":"trace[238554399] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"104.664569ms","start":"2026-07-25T08:19:31.754606Z","end":"2026-07-25T08:19:31.859271Z","steps":["trace[238554399] 'process raft request' (duration: 83.8963ms)","trace[238554399] 'compare' (duration: 20.493345ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:19:39.420023Z","caller":"traceutil/trace.go:171","msg":"trace[321962013] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"162.810093ms","start":"2026-07-25T08:19:39.257169Z","end":"2026-07-25T08:19:39.419979Z","steps":["trace[321962013] 'process raft request' (duration: 162.065485ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:19:39.666686Z","caller":"traceutil/trace.go:171","msg":"trace[152657801] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"181.778642ms","start":"2026-07-25T08:19:39.48486Z","end":"2026-07-25T08:19:39.666639Z","steps":["trace[152657801] 'process raft request' (duration: 181.344759ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:20:25.298088Z","caller":"traceutil/trace.go:171","msg":"trace[2049410749] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"163.185906ms","start":"2026-07-25T08:20:25.134869Z","end":"2026-07-25T08:20:25.298055Z","steps":["trace[2049410749] 'process raft request' (duration: 135.294914ms)","trace[2049410749] 'compare' (duration: 27.430362ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T08:20:25.298448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.031161ms","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-07-25T08:20:25.298544Z","caller":"traceutil/trace.go:171","msg":"trace[1369343575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"134.186812ms","start":"2026-07-25T08:20:25.164338Z","end":"2026-07-25T08:20:25.298525Z","steps":["trace[1369343575] 'agreement among raft nodes before linearized reading' (duration: 133.85674ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:20:25.298159Z","caller":"traceutil/trace.go:171","msg":"trace[114154437] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"133.722671ms","start":"2026-07-25T08:20:25.164399Z","end":"2026-07-25T08:20:25.298121Z","steps":["trace[114154437] 'read index received' (duration: 105.777935ms)","trace[114154437] 'applied index is now lower than readState.Index' (duration: 27.941605ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:20:25.563037Z","caller":"traceutil/trace.go:171","msg":"trace[1256117458] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"258.201272ms","start":"2026-07-25T08:20:25.304772Z","end":"2026-07-25T08:20:25.562974Z","steps":["trace[1256117458] 'process raft request' (duration: 192.959753ms)","trace[1256117458] 'compare' (duration: 64.96091ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T08:20:48.672591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.677422ms","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-07-25T08:20:48.672685Z","caller":"traceutil/trace.go:171","msg":"trace[298917660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1823; }","duration":"295.838583ms","start":"2026-07-25T08:20:48.376827Z","end":"2026-07-25T08:20:48.672665Z","steps":["trace[298917660] 'range keys from in-memory index tree' (duration: 295.394022ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:20:48.673507Z","caller":"traceutil/trace.go:171","msg":"trace[1815045006] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1893; }","duration":"156.755714ms","start":"2026-07-25T08:20:48.51673Z","end":"2026-07-25T08:20:48.673485Z","steps":["trace[1815045006] 'read index received' (duration: 156.576552ms)","trace[1815045006] 'applied index is now lower than readState.Index' (duration: 178.142µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:20:48.673542Z","caller":"traceutil/trace.go:171","msg":"trace[1399559564] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"204.684753ms","start":"2026-07-25T08:20:48.46884Z","end":"2026-07-25T08:20:48.673525Z","steps":["trace[1399559564] 'process raft request' (duration: 204.50304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:20:48.673765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.021763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c60ddf84-757d-4dfd-83f2-ce8f6ffc4a26\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-25T08:20:48.673809Z","caller":"traceutil/trace.go:171","msg":"trace[1747055016] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c60ddf84-757d-4dfd-83f2-ce8f6ffc4a26; range_end:; response_count:1; response_revision:1824; }","duration":"157.120199ms","start":"2026-07-25T08:20:48.516678Z","end":"2026-07-25T08:20:48.673798Z","steps":["trace[1747055016] 'agreement among raft nodes before linearized reading' (duration: 156.962008ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:21:02.85526Z","caller":"traceutil/trace.go:171","msg":"trace[62125844] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"137.288762ms","start":"2026-07-25T08:21:02.717937Z","end":"2026-07-25T08:21:02.855226Z","steps":["trace[62125844] 'process raft request' (duration: 137.075067ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:21:05.906653Z","caller":"traceutil/trace.go:171","msg":"trace[1257585530] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2026; }","duration":"122.890251ms","start":"2026-07-25T08:21:05.78374Z","end":"2026-07-25T08:21:05.906631Z","steps":["trace[1257585530] 'read index received' (duration: 122.654823ms)","trace[1257585530] 'applied index is now lower than readState.Index' (duration: 234.578µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:21:05.906793Z","caller":"traceutil/trace.go:171","msg":"trace[966583728] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"124.219438ms","start":"2026-07-25T08:21:05.782527Z","end":"2026-07-25T08:21:05.906747Z","steps":["trace[966583728] 'process raft request' (duration: 123.913035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:21:05.906875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.114028ms","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-07-25T08:21:05.906918Z","caller":"traceutil/trace.go:171","msg":"trace[205081182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1953; }","duration":"123.186643ms","start":"2026-07-25T08:21:05.783716Z","end":"2026-07-25T08:21:05.906903Z","steps":["trace[205081182] 'agreement among raft nodes before linearized reading' (duration: 123.033832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:21:39.170332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.327422ms","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-07-25T08:21:39.170412Z","caller":"traceutil/trace.go:171","msg":"trace[1613738330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2145; }","duration":"120.41537ms","start":"2026-07-25T08:21:39.049978Z","end":"2026-07-25T08:21:39.170394Z","steps":["trace[1613738330] 'range keys from in-memory index tree' (duration: 120.120137ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:21:39.28413Z","caller":"traceutil/trace.go:171","msg":"trace[1938510962] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"108.769749ms","start":"2026-07-25T08:21:39.175317Z","end":"2026-07-25T08:21:39.284087Z","steps":["trace[1938510962] 'process raft request' (duration: 108.512889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:21:45.346498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.425439ms","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-07-25T08:21:45.346592Z","caller":"traceutil/trace.go:171","msg":"trace[48766689] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2177; }","duration":"131.534937ms","start":"2026-07-25T08:21:45.215035Z","end":"2026-07-25T08:21:45.34657Z","steps":["trace[48766689] 'range keys from in-memory index tree' (duration: 131.06225ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:21:45.474455Z","caller":"traceutil/trace.go:171","msg":"trace[44723942] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2259; }","duration":"108.500478ms","start":"2026-07-25T08:21:45.365936Z","end":"2026-07-25T08:21:45.474436Z","steps":["trace[44723942] 'read index received' (duration: 108.286822ms)","trace[44723942] 'applied index is now lower than readState.Index' (duration: 212.796µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:21:45.474532Z","caller":"traceutil/trace.go:171","msg":"trace[2093719513] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"123.461841ms","start":"2026-07-25T08:21:45.351041Z","end":"2026-07-25T08:21:45.474503Z","steps":["trace[2093719513] 'process raft request' (duration: 123.271777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:21:45.474782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.838293ms","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-07-25T08:21:45.474828Z","caller":"traceutil/trace.go:171","msg":"trace[1804349817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"108.91978ms","start":"2026-07-25T08:21:45.365898Z","end":"2026-07-25T08:21:45.474817Z","steps":["trace[1804349817] 'agreement among raft nodes before linearized reading' (duration: 108.646569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:21:45.719226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.229293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T08:21:45.719382Z","caller":"traceutil/trace.go:171","msg":"trace[1116040405] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"196.134548ms","start":"2026-07-25T08:21:45.52323Z","end":"2026-07-25T08:21:45.719364Z","steps":["trace[1116040405] 'read index received' (duration: 67.869926ms)","trace[1116040405] 'applied index is now lower than readState.Index' (duration: 128.263152ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:21:45.719417Z","caller":"traceutil/trace.go:171","msg":"trace[217269298] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"239.623284ms","start":"2026-07-25T08:21:45.479765Z","end":"2026-07-25T08:21:45.719389Z","steps":["trace[217269298] 'process raft request' (duration: 111.397695ms)","trace[217269298] 'compare' (duration: 127.027848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T08:21:45.71953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.303271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T08:21:45.719566Z","caller":"traceutil/trace.go:171","msg":"trace[1811003040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2179; }","duration":"196.383637ms","start":"2026-07-25T08:21:45.523173Z","end":"2026-07-25T08:21:45.719557Z","steps":["trace[1811003040] 'agreement among raft nodes before linearized reading' (duration: 196.239096ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:21:45.833297Z","caller":"traceutil/trace.go:171","msg":"trace[95012971] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"109.837177ms","start":"2026-07-25T08:21:45.723439Z","end":"2026-07-25T08:21:45.833277Z","steps":["trace[95012971] 'process raft request' (duration: 108.022541ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:22:19.413642Z","caller":"traceutil/trace.go:171","msg":"trace[1773116240] linearizableReadLoop","detail":"{readStateIndex:2565; appliedIndex:2564; }","duration":"272.645313ms","start":"2026-07-25T08:22:19.140971Z","end":"2026-07-25T08:22:19.413616Z","steps":["trace[1773116240] 'read index received' (duration: 272.4828ms)","trace[1773116240] 'applied index is now lower than readState.Index' (duration: 161.293µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:22:19.413738Z","caller":"traceutil/trace.go:171","msg":"trace[1072664051] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"276.371795ms","start":"2026-07-25T08:22:19.137336Z","end":"2026-07-25T08:22:19.413708Z","steps":["trace[1072664051] 'process raft request' (duration: 276.097787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:22:19.413925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.928451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-00c53f4a-f9ed-495a-a56a-5258ae75ebdb\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-07-25T08:22:19.413971Z","caller":"traceutil/trace.go:171","msg":"trace[400966268] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-00c53f4a-f9ed-495a-a56a-5258ae75ebdb; range_end:; response_count:1; response_revision:2475; }","duration":"273.033037ms","start":"2026-07-25T08:22:19.140926Z","end":"2026-07-25T08:22:19.413959Z","steps":["trace[400966268] 'agreement among raft nodes before linearized reading' (duration: 272.851094ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:22:28.300161Z","caller":"traceutil/trace.go:171","msg":"trace[1678390952] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"136.307249ms","start":"2026-07-25T08:22:28.163828Z","end":"2026-07-25T08:22:28.300135Z","steps":["trace[1678390952] 'process raft request' (duration: 106.604478ms)","trace[1678390952] 'compare' (duration: 29.600855ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:22:28.383Z","caller":"traceutil/trace.go:171","msg":"trace[997925891] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"218.771973ms","start":"2026-07-25T08:22:28.164187Z","end":"2026-07-25T08:22:28.382959Z","steps":["trace[997925891] 'process raft request' (duration: 218.037298ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:22:28.383528Z","caller":"traceutil/trace.go:171","msg":"trace[281940047] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"219.219575ms","start":"2026-07-25T08:22:28.164293Z","end":"2026-07-25T08:22:28.383512Z","steps":["trace[281940047] 'process raft request' (duration: 218.142622ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:22:28.383785Z","caller":"traceutil/trace.go:171","msg":"trace[1328905801] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"218.833519ms","start":"2026-07-25T08:22:28.164936Z","end":"2026-07-25T08:22:28.38377Z","steps":["trace[1328905801] 'process raft request' (duration: 217.576343ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:22:28.384073Z","caller":"traceutil/trace.go:171","msg":"trace[585204356] transaction","detail":"{read_only:false; response_revision:2552; number_of_response:1; }","duration":"160.225251ms","start":"2026-07-25T08:22:28.223834Z","end":"2026-07-25T08:22:28.384059Z","steps":["trace[585204356] 'process raft request' (duration: 158.734323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T08:22:28.384811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.101526ms","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-07-25T08:22:28.38492Z","caller":"traceutil/trace.go:171","msg":"trace[889427424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2552; }","duration":"100.291409ms","start":"2026-07-25T08:22:28.284606Z","end":"2026-07-25T08:22:28.384898Z","steps":["trace[889427424] 'agreement among raft nodes before linearized reading' (duration: 99.755198ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:22:28.500549Z","caller":"traceutil/trace.go:171","msg":"trace[1850620955] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"111.604184ms","start":"2026-07-25T08:22:28.388886Z","end":"2026-07-25T08:22:28.50049Z","steps":["trace[1850620955] 'process raft request' (duration: 75.037713ms)","trace[1850620955] 'compare' (duration: 36.305409ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:22:28.500626Z","caller":"traceutil/trace.go:171","msg":"trace[129882935] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"111.099751ms","start":"2026-07-25T08:22:28.389494Z","end":"2026-07-25T08:22:28.500594Z","steps":["trace[129882935] 'process raft request' (duration: 110.907307ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:22:29.523257Z","caller":"traceutil/trace.go:171","msg":"trace[1066287029] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"127.254584ms","start":"2026-07-25T08:22:29.395973Z","end":"2026-07-25T08:22:29.523227Z","steps":["trace[1066287029] 'process raft request' (duration: 127.06812ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T08:25:14.718975Z","caller":"traceutil/trace.go:171","msg":"trace[270117673] transaction","detail":"{read_only:false; response_revision:3576; number_of_response:1; }","duration":"117.736259ms","start":"2026-07-25T08:25:14.601201Z","end":"2026-07-25T08:25:14.718938Z","steps":["trace[270117673] 'process raft request' (duration: 65.987041ms)","trace[270117673] 'compare' (duration: 51.383694ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T08:28:49.068878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3003} {"level":"info","ts":"2026-07-25T08:28:49.147604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3003,"took":"77.643623ms","hash":2312243012,"current-db-size-bytes":38510592,"current-db-size":"38 MB","current-db-size-in-use-bytes":10801152,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-25T08:28:49.147671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2312243012,"revision":3003,"compact-revision":-1}