{"level":"warn","ts":"2026-08-22T14:31:17.154298Z","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-22T14:31:17.154404Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-22T14:31:17.154483Z","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-22T14:31:17.154497Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-22T14:31:17.154523Z","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-22T14:31:17.155157Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-08-22T14:31:17.155228Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-22T14:31:17.159454Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.020647ms"} {"level":"info","ts":"2026-08-22T14:31:17.168697Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-08-22T14:31:17.168788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:31:17.168818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-08-22T14:31:17.168851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:31:17.168875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-08-22T14:31:17.168943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-08-22T14:31:17.174625Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:31:17.176176Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:31:17.177193Z","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-22T14:31:17.180291Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:31:17.180468Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:31:17.180607Z","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-22T14:31:17.18069Z","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-22T14:31:17.180703Z","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-22T14:31:17.181223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-08-22T14:31:17.181324Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-22T14:31:17.185015Z","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-22T14:31:17.185237Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:31:17.185263Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:31:17.185357Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-22T14:31:17.185458Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-22T14:31:18.069825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:31:18.069917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:31:18.069955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-08-22T14:31:18.069976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:31:18.069989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-22T14:31:18.070005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-08-22T14:31:18.070019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-22T14:31:18.070942Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:31:18.07168Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:31:18.071843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:31:18.071913Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:31:18.072246Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:31:18.072375Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:31:18.072418Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:31:18.075581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-08-22T14:31:18.075752Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:31:18.075785Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:31:18.076118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:31:52.918418Z","caller":"traceutil/trace.go:171","msg":"trace[1490229284] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"104.398253ms","start":"2026-08-22T14:31:52.813991Z","end":"2026-08-22T14:31:52.91839Z","steps":["trace[1490229284] 'process raft request' (duration: 104.242738ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:52.928569Z","caller":"traceutil/trace.go:171","msg":"trace[1921024296] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"111.117308ms","start":"2026-08-22T14:31:52.817372Z","end":"2026-08-22T14:31:52.928489Z","steps":["trace[1921024296] 'process raft request' (duration: 111.003104ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:52.92891Z","caller":"traceutil/trace.go:171","msg":"trace[1866360425] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"106.343395ms","start":"2026-08-22T14:31:52.822555Z","end":"2026-08-22T14:31:52.928899Z","steps":["trace[1866360425] 'process raft request' (duration: 105.930592ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:52.92958Z","caller":"traceutil/trace.go:171","msg":"trace[680711586] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"107.199932ms","start":"2026-08-22T14:31:52.822354Z","end":"2026-08-22T14:31:52.929554Z","steps":["trace[680711586] 'process raft request' (duration: 106.091966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:53.971041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.982598ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:53.971223Z","caller":"traceutil/trace.go:171","msg":"trace[196330250] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:546; }","duration":"132.192655ms","start":"2026-08-22T14:31:53.83901Z","end":"2026-08-22T14:31:53.971203Z","steps":["trace[196330250] 'range keys from in-memory index tree' (duration: 131.962708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:53.971922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.816582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:31:53.972058Z","caller":"traceutil/trace.go:171","msg":"trace[1974913188] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"273.487466ms","start":"2026-08-22T14:31:53.69855Z","end":"2026-08-22T14:31:53.972038Z","steps":["trace[1974913188] 'process raft request' (duration: 112.909209ms)","trace[1974913188] 'compare' (duration: 159.649197ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:53.975121Z","caller":"traceutil/trace.go:171","msg":"trace[998385517] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"262.369015ms","start":"2026-08-22T14:31:53.712716Z","end":"2026-08-22T14:31:53.975085Z","steps":["trace[998385517] 'process raft request' (duration: 261.510567ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:59.170609Z","caller":"traceutil/trace.go:171","msg":"trace[492373124] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"147.612094ms","start":"2026-08-22T14:31:59.022973Z","end":"2026-08-22T14:31:59.170585Z","steps":["trace[492373124] 'process raft request' (duration: 147.439758ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:59.171032Z","caller":"traceutil/trace.go:171","msg":"trace[1320602909] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:603; }","duration":"126.938409ms","start":"2026-08-22T14:31:59.044058Z","end":"2026-08-22T14:31:59.170997Z","steps":["trace[1320602909] 'read index received' (duration: 126.902747ms)","trace[1320602909] 'applied index is now lower than readState.Index' (duration: 33.702µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:59.171207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.119965ms","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-22T14:31:59.171251Z","caller":"traceutil/trace.go:171","msg":"trace[30706330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"127.196027ms","start":"2026-08-22T14:31:59.044043Z","end":"2026-08-22T14:31:59.171239Z","steps":["trace[30706330] 'agreement among raft nodes before linearized reading' (duration: 127.064173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:59.642655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.961444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:31:59.642733Z","caller":"traceutil/trace.go:171","msg":"trace[1887601980] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"375.347416ms","start":"2026-08-22T14:31:59.267373Z","end":"2026-08-22T14:31:59.642721Z","steps":["trace[1887601980] 'process raft request' (duration: 219.267457ms)","trace[1887601980] 'compare' (duration: 155.83786ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:59.642765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:59.267338Z","time spent":"375.415579ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:33:34.866723Z","caller":"traceutil/trace.go:171","msg":"trace[548641640] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"401.395811ms","start":"2026-08-22T14:33:34.46528Z","end":"2026-08-22T14:33:34.866675Z","steps":["trace[548641640] 'process raft request' (duration: 401.160859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:34.866957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:34.465258Z","time spent":"401.578221ms","remote":"127.0.0.1:45758","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-08-22T14:33:34.896023Z","caller":"traceutil/trace.go:171","msg":"trace[571669973] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1886; }","duration":"401.002161ms","start":"2026-08-22T14:33:34.494993Z","end":"2026-08-22T14:33:34.895995Z","steps":["trace[571669973] 'read index received' (duration: 371.393754ms)","trace[571669973] 'applied index is now lower than readState.Index' (duration: 29.606947ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:34.896064Z","caller":"traceutil/trace.go:171","msg":"trace[565707614] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"427.873526ms","start":"2026-08-22T14:33:34.468159Z","end":"2026-08-22T14:33:34.896032Z","steps":["trace[565707614] 'process raft request' (duration: 427.634123ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:34.896119Z","caller":"traceutil/trace.go:171","msg":"trace[1938651347] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"202.294058ms","start":"2026-08-22T14:33:34.693802Z","end":"2026-08-22T14:33:34.896096Z","steps":["trace[1938651347] 'process raft request' (duration: 202.14858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:34.896302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:34.468134Z","time spent":"428.002862ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:33:34.896399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.37858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2abe46ef-24cc-46e2-9a28-2814433e2da0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T14:33:34.896455Z","caller":"traceutil/trace.go:171","msg":"trace[1862535531] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2abe46ef-24cc-46e2-9a28-2814433e2da0; range_end:; response_count:1; response_revision:1822; }","duration":"401.471984ms","start":"2026-08-22T14:33:34.494966Z","end":"2026-08-22T14:33:34.896438Z","steps":["trace[1862535531] 'agreement among raft nodes before linearized reading' (duration: 401.150498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:34.896546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:34.494951Z","time spent":"401.544138ms","remote":"127.0.0.1:45702","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-2abe46ef-24cc-46e2-9a28-2814433e2da0\" "} {"level":"info","ts":"2026-08-22T14:33:35.046585Z","caller":"traceutil/trace.go:171","msg":"trace[1809696146] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"105.317722ms","start":"2026-08-22T14:33:34.941244Z","end":"2026-08-22T14:33:35.046562Z","steps":["trace[1809696146] 'read index received' (duration: 105.105321ms)","trace[1809696146] 'applied index is now lower than readState.Index' (duration: 211.141µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:35.046636Z","caller":"traceutil/trace.go:171","msg":"trace[877174474] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"141.262135ms","start":"2026-08-22T14:33:34.905348Z","end":"2026-08-22T14:33:35.04661Z","steps":["trace[877174474] 'process raft request' (duration: 141.050824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:35.046841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.579646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-22T14:33:35.046946Z","caller":"traceutil/trace.go:171","msg":"trace[888247616] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1823; }","duration":"105.737404ms","start":"2026-08-22T14:33:34.941193Z","end":"2026-08-22T14:33:35.046931Z","steps":["trace[888247616] 'agreement among raft nodes before linearized reading' (duration: 105.491212ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:35.702877Z","caller":"traceutil/trace.go:171","msg":"trace[727119122] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"358.56388ms","start":"2026-08-22T14:33:35.344286Z","end":"2026-08-22T14:33:35.70285Z","steps":["trace[727119122] 'process raft request' (duration: 358.411863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:35.70303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:35.344265Z","time spent":"358.685647ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:33:35.764912Z","caller":"traceutil/trace.go:171","msg":"trace[1355902958] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"419.860915ms","start":"2026-08-22T14:33:35.345027Z","end":"2026-08-22T14:33:35.764888Z","steps":["trace[1355902958] 'process raft request' (duration: 415.273428ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:35.764953Z","caller":"traceutil/trace.go:171","msg":"trace[335931896] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"116.724216ms","start":"2026-08-22T14:33:35.648182Z","end":"2026-08-22T14:33:35.764906Z","steps":["trace[335931896] 'process raft request' (duration: 116.575518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:35.765061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:35.345011Z","time spent":"419.968951ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:33:35.765337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.22203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:33:35.766128Z","caller":"traceutil/trace.go:171","msg":"trace[791905112] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1830; }","duration":"216.046993ms","start":"2026-08-22T14:33:35.550048Z","end":"2026-08-22T14:33:35.766095Z","steps":["trace[791905112] 'agreement among raft nodes before linearized reading' (duration: 215.234671ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:35.765132Z","caller":"traceutil/trace.go:171","msg":"trace[80321991] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"214.813459ms","start":"2026-08-22T14:33:35.550119Z","end":"2026-08-22T14:33:35.764932Z","steps":["trace[80321991] 'read index received' (duration: 152.719464ms)","trace[80321991] 'applied index is now lower than readState.Index' (duration: 62.092985ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:34:10.122014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.216931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:34:10.122132Z","caller":"traceutil/trace.go:171","msg":"trace[652035545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2072; }","duration":"112.37515ms","start":"2026-08-22T14:34:10.009722Z","end":"2026-08-22T14:34:10.122098Z","steps":["trace[652035545] 'range keys from in-memory index tree' (duration: 112.110176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:10.457705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.484199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-22T14:34:10.457794Z","caller":"traceutil/trace.go:171","msg":"trace[615699940] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2073; }","duration":"113.616027ms","start":"2026-08-22T14:34:10.344156Z","end":"2026-08-22T14:34:10.457772Z","steps":["trace[615699940] 'range keys from in-memory index tree' (duration: 113.29933ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:34:10.72057Z","caller":"traceutil/trace.go:171","msg":"trace[1377627167] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"126.697558ms","start":"2026-08-22T14:34:10.593844Z","end":"2026-08-22T14:34:10.720541Z","steps":["trace[1377627167] 'process raft request' (duration: 126.553881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:51.909877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.944948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:35:51.910539Z","caller":"traceutil/trace.go:171","msg":"trace[2055280511] linearizableReadLoop","detail":"{readStateIndex:2824; appliedIndex:2823; }","duration":"182.674685ms","start":"2026-08-22T14:35:51.727843Z","end":"2026-08-22T14:35:51.910518Z","steps":["trace[2055280511] 'read index received' (duration: 26.910762ms)","trace[2055280511] 'applied index is now lower than readState.Index' (duration: 155.762553ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:35:51.910659Z","caller":"traceutil/trace.go:171","msg":"trace[38196] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"279.506249ms","start":"2026-08-22T14:35:51.631136Z","end":"2026-08-22T14:35:51.910642Z","steps":["trace[38196] 'process raft request' (duration: 123.703585ms)","trace[38196] 'compare' (duration: 154.774885ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:35:51.911169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.327427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T14:35:51.911348Z","caller":"traceutil/trace.go:171","msg":"trace[1017381989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2725; }","duration":"183.439679ms","start":"2026-08-22T14:35:51.727783Z","end":"2026-08-22T14:35:51.911223Z","steps":["trace[1017381989] 'agreement among raft nodes before linearized reading' (duration: 183.217145ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:34.523547Z","caller":"traceutil/trace.go:171","msg":"trace[708474337] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4535; }","duration":"130.846524ms","start":"2026-08-22T14:39:34.39266Z","end":"2026-08-22T14:39:34.523506Z","steps":["trace[708474337] 'process raft request' (duration: 84.004748ms)","trace[708474337] 'compare' (duration: 46.667368ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:39:34.523785Z","caller":"traceutil/trace.go:171","msg":"trace[1787631091] linearizableReadLoop","detail":"{readStateIndex:4706; appliedIndex:4703; }","duration":"121.588657ms","start":"2026-08-22T14:39:34.402182Z","end":"2026-08-22T14:39:34.52377Z","steps":["trace[1787631091] 'read index received' (duration: 15.308542ms)","trace[1787631091] 'applied index is now lower than readState.Index' (duration: 106.278425ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:39:34.523873Z","caller":"traceutil/trace.go:171","msg":"trace[1555063969] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"126.416449ms","start":"2026-08-22T14:39:34.397447Z","end":"2026-08-22T14:39:34.523863Z","steps":["trace[1555063969] 'process raft request' (duration: 125.96565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:34.524129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.928282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-08-22T14:39:34.52417Z","caller":"traceutil/trace.go:171","msg":"trace[1412913084] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4537; }","duration":"122.000495ms","start":"2026-08-22T14:39:34.402158Z","end":"2026-08-22T14:39:34.524158Z","steps":["trace[1412913084] 'agreement among raft nodes before linearized reading' (duration: 121.807807ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:41.023788Z","caller":"traceutil/trace.go:171","msg":"trace[1917391394] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4856; }","duration":"168.883625ms","start":"2026-08-22T14:39:40.854877Z","end":"2026-08-22T14:39:41.023761Z","steps":["trace[1917391394] 'read index received' (duration: 168.339801ms)","trace[1917391394] 'applied index is now lower than readState.Index' (duration: 543.024µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:39:41.023936Z","caller":"traceutil/trace.go:171","msg":"trace[2143216616] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"192.598052ms","start":"2026-08-22T14:39:40.831326Z","end":"2026-08-22T14:39:41.023924Z","steps":["trace[2143216616] 'process raft request' (duration: 192.020467ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:41.024185Z","caller":"traceutil/trace.go:171","msg":"trace[618451571] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"182.60099ms","start":"2026-08-22T14:39:40.841572Z","end":"2026-08-22T14:39:41.024173Z","steps":["trace[618451571] 'process raft request' (duration: 182.112808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:41.024367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.81508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-08-22T14:39:41.024513Z","caller":"traceutil/trace.go:171","msg":"trace[270708424] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4685; }","duration":"159.008419ms","start":"2026-08-22T14:39:40.865487Z","end":"2026-08-22T14:39:41.024496Z","steps":["trace[270708424] 'agreement among raft nodes before linearized reading' (duration: 158.719847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:41.024815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.076683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-22T14:39:41.024871Z","caller":"traceutil/trace.go:171","msg":"trace[349062852] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4685; }","duration":"176.157696ms","start":"2026-08-22T14:39:40.8487Z","end":"2026-08-22T14:39:41.024857Z","steps":["trace[349062852] 'agreement among raft nodes before linearized reading' (duration: 175.612442ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:41.024973Z","caller":"traceutil/trace.go:171","msg":"trace[888187136] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"192.068119ms","start":"2026-08-22T14:39:40.832052Z","end":"2026-08-22T14:39:41.02412Z","steps":["trace[888187136] 'process raft request' (duration: 191.460972ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:41.025361Z","caller":"traceutil/trace.go:171","msg":"trace[594188096] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"185.255058ms","start":"2026-08-22T14:39:40.840071Z","end":"2026-08-22T14:39:41.025326Z","steps":["trace[594188096] 'process raft request' (duration: 183.48864ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:41.133171Z","caller":"traceutil/trace.go:171","msg":"trace[434899287] linearizableReadLoop","detail":"{readStateIndex:4861; appliedIndex:4860; }","duration":"101.244743ms","start":"2026-08-22T14:39:41.031888Z","end":"2026-08-22T14:39:41.133133Z","steps":["trace[434899287] 'read index received' (duration: 99.241274ms)","trace[434899287] 'applied index is now lower than readState.Index' (duration: 2.002439ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:39:41.133378Z","caller":"traceutil/trace.go:171","msg":"trace[219321024] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"101.617ms","start":"2026-08-22T14:39:41.031745Z","end":"2026-08-22T14:39:41.133362Z","steps":["trace[219321024] 'process raft request' (duration: 99.328548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:41.133709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.802827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b29bdebf-b422-4a69-af14-d53b79339d54\" ","response":"range_response_count:1 size:2580"} {"level":"info","ts":"2026-08-22T14:39:41.133748Z","caller":"traceutil/trace.go:171","msg":"trace[1433291454] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b29bdebf-b422-4a69-af14-d53b79339d54; range_end:; response_count:1; response_revision:4686; }","duration":"101.85432ms","start":"2026-08-22T14:39:41.031881Z","end":"2026-08-22T14:39:41.133735Z","steps":["trace[1433291454] 'agreement among raft nodes before linearized reading' (duration: 101.661402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:41.133907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.077465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61272"} {"level":"info","ts":"2026-08-22T14:39:41.133993Z","caller":"traceutil/trace.go:171","msg":"trace[1958434130] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4686; }","duration":"101.195911ms","start":"2026-08-22T14:39:41.032775Z","end":"2026-08-22T14:39:41.13397Z","steps":["trace[1958434130] 'agreement among raft nodes before linearized reading' (duration: 100.894747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:41.752247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.771895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7582"} {"level":"info","ts":"2026-08-22T14:39:41.752332Z","caller":"traceutil/trace.go:171","msg":"trace[879848541] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4710; }","duration":"115.89255ms","start":"2026-08-22T14:39:41.63642Z","end":"2026-08-22T14:39:41.752313Z","steps":["trace[879848541] 'range keys from in-memory index tree' (duration: 115.612478ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:53.532475Z","caller":"traceutil/trace.go:171","msg":"trace[947421998] linearizableReadLoop","detail":"{readStateIndex:5054; appliedIndex:5053; }","duration":"104.004632ms","start":"2026-08-22T14:39:53.42844Z","end":"2026-08-22T14:39:53.532445Z","steps":["trace[947421998] 'read index received' (duration: 89.878951ms)","trace[947421998] 'applied index is now lower than readState.Index' (duration: 14.124261ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:39:53.532717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.246093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-22T14:39:53.532759Z","caller":"traceutil/trace.go:171","msg":"trace[1584583592] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4873; }","duration":"104.323966ms","start":"2026-08-22T14:39:53.428424Z","end":"2026-08-22T14:39:53.532748Z","steps":["trace[1584583592] 'agreement among raft nodes before linearized reading' (duration: 104.127827ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:53.532759Z","caller":"traceutil/trace.go:171","msg":"trace[1105215812] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"104.761986ms","start":"2026-08-22T14:39:53.427928Z","end":"2026-08-22T14:39:53.53269Z","steps":["trace[1105215812] 'process raft request' (duration: 90.496269ms)","trace[1105215812] 'compare' (duration: 13.736254ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:41:18.095465Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-08-22T14:41:18.188096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"90.968153ms","hash":3174245161,"current-db-size-bytes":54128640,"current-db-size":"54 MB","current-db-size-in-use-bytes":29396992,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-22T14:41:18.188157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3174245161,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-08-22T14:43:43.136735Z","caller":"traceutil/trace.go:171","msg":"trace[172540296] transaction","detail":"{read_only:false; response_revision:6580; number_of_response:1; }","duration":"163.044041ms","start":"2026-08-22T14:43:42.973661Z","end":"2026-08-22T14:43:43.136705Z","steps":["trace[172540296] 'process raft request' (duration: 162.863672ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:46:18.100479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5573} {"level":"info","ts":"2026-08-22T14:46:18.176496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5573,"took":"74.483609ms","hash":1998499036,"current-db-size-bytes":54128640,"current-db-size":"54 MB","current-db-size-in-use-bytes":18190336,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-22T14:46:18.176552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1998499036,"revision":5573,"compact-revision":2862}