{"level":"warn","ts":"2026-05-20T00:16:53.267266Z","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-20T00:16:53.267368Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-20T00:16:53.267447Z","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-20T00:16:53.267456Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-20T00:16:53.267479Z","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-20T00:16:53.268291Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-20T00:16:53.268373Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-20T00:16:53.294772Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"26.06497ms"} {"level":"info","ts":"2026-05-20T00:16:53.371729Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-20T00:16:53.371899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T00:16:53.371972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-20T00:16:53.371997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T00:16:53.372044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-20T00:16:53.372131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-20T00:16:53.382954Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T00:16:53.384604Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T00:16:53.385898Z","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-20T00:16:53.38881Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T00:16:53.389083Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T00:16:53.389129Z","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-20T00:16:53.389259Z","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-20T00:16:53.389312Z","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-20T00:16:53.389832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-20T00:16:53.389973Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-20T00:16:53.397474Z","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-20T00:16:53.397581Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-20T00:16:53.397687Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-20T00:16:53.397723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T00:16:53.397772Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T00:16:53.873366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T00:16:53.873411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T00:16:53.873439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-20T00:16:53.873452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-20T00:16:53.873458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-20T00:16:53.873467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-20T00:16:53.873475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-20T00:16:53.874351Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T00:16:53.875053Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T00:16:53.875266Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T00:16:53.875399Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T00:16:53.875461Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T00:16:53.875479Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T00:16:53.875811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T00:16:53.877425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T00:16:53.878613Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-20T00:16:53.882379Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T00:16:53.882419Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T00:17:07.448426Z","caller":"traceutil/trace.go:171","msg":"trace[1071526001] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"102.626876ms","start":"2026-05-20T00:17:07.345775Z","end":"2026-05-20T00:17:07.448402Z","steps":["trace[1071526001] 'read index received' (duration: 102.48486ms)","trace[1071526001] 'applied index is now lower than readState.Index' (duration: 140.776µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T00:17:07.448734Z","caller":"traceutil/trace.go:171","msg":"trace[707663894] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"109.22191ms","start":"2026-05-20T00:17:07.339274Z","end":"2026-05-20T00:17:07.448496Z","steps":["trace[707663894] 'process raft request' (duration: 108.996361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:17:07.448738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.929448ms","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-20T00:17:07.449029Z","caller":"traceutil/trace.go:171","msg":"trace[667378567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"103.274392ms","start":"2026-05-20T00:17:07.345738Z","end":"2026-05-20T00:17:07.449013Z","steps":["trace[667378567] 'agreement among raft nodes before linearized reading' (duration: 102.819573ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:17:30.902675Z","caller":"traceutil/trace.go:171","msg":"trace[1795218073] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"145.653065ms","start":"2026-05-20T00:17:30.756993Z","end":"2026-05-20T00:17:30.902646Z","steps":["trace[1795218073] 'process raft request' (duration: 145.054275ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:17:31.404675Z","caller":"traceutil/trace.go:171","msg":"trace[1248464782] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"220.172248ms","start":"2026-05-20T00:17:31.184455Z","end":"2026-05-20T00:17:31.404627Z","steps":["trace[1248464782] 'read index received' (duration: 214.090822ms)","trace[1248464782] 'applied index is now lower than readState.Index' (duration: 6.080016ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T00:17:31.40477Z","caller":"traceutil/trace.go:171","msg":"trace[979947056] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"419.883507ms","start":"2026-05-20T00:17:30.984858Z","end":"2026-05-20T00:17:31.404742Z","steps":["trace[979947056] 'process raft request' (duration: 413.746209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:17:31.404823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.357967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T00:17:31.404869Z","caller":"traceutil/trace.go:171","msg":"trace[271808211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"220.470602ms","start":"2026-05-20T00:17:31.184386Z","end":"2026-05-20T00:17:31.404857Z","steps":["trace[271808211] 'agreement among raft nodes before linearized reading' (duration: 220.358257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:17:31.40529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T00:17:30.984843Z","time spent":"419.974951ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T00:17:46.594187Z","caller":"traceutil/trace.go:171","msg":"trace[831629230] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"160.887702ms","start":"2026-05-20T00:17:46.433273Z","end":"2026-05-20T00:17:46.594161Z","steps":["trace[831629230] 'process raft request' (duration: 160.718212ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:18:34.039454Z","caller":"traceutil/trace.go:171","msg":"trace[2132848917] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"109.10707ms","start":"2026-05-20T00:18:33.930304Z","end":"2026-05-20T00:18:34.039411Z","steps":["trace[2132848917] 'process raft request' (duration: 108.926265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:18:58.084275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.254767ms","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-05-20T00:18:58.084415Z","caller":"traceutil/trace.go:171","msg":"trace[1138393832] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1786; }","duration":"281.422626ms","start":"2026-05-20T00:18:57.802972Z","end":"2026-05-20T00:18:58.084394Z","steps":["trace[1138393832] 'range keys from in-memory index tree' (duration: 281.214987ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:18:58.191914Z","caller":"traceutil/trace.go:171","msg":"trace[1263430217] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"506.386837ms","start":"2026-05-20T00:18:57.685502Z","end":"2026-05-20T00:18:58.191889Z","steps":["trace[1263430217] 'process raft request' (duration: 506.239797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:18:58.192255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T00:18:57.685463Z","time spent":"506.525777ms","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T00:18:58.192409Z","caller":"traceutil/trace.go:171","msg":"trace[1927726638] linearizableReadLoop","detail":"{readStateIndex:1858; appliedIndex:1858; }","duration":"211.743783ms","start":"2026-05-20T00:18:57.980635Z","end":"2026-05-20T00:18:58.192378Z","steps":["trace[1927726638] 'read index received' (duration: 211.731433ms)","trace[1927726638] 'applied index is now lower than readState.Index' (duration: 9.81µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T00:18:58.19268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.022453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40ec9f6b-4e54-4735-b04b-ccb4dc010370\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-20T00:18:58.192725Z","caller":"traceutil/trace.go:171","msg":"trace[246200433] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40ec9f6b-4e54-4735-b04b-ccb4dc010370; range_end:; response_count:1; response_revision:1787; }","duration":"212.105503ms","start":"2026-05-20T00:18:57.980606Z","end":"2026-05-20T00:18:58.192711Z","steps":["trace[246200433] 'agreement among raft nodes before linearized reading' (duration: 211.915103ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:18:58.538664Z","caller":"traceutil/trace.go:171","msg":"trace[2684262] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"346.141815ms","start":"2026-05-20T00:18:58.192487Z","end":"2026-05-20T00:18:58.538629Z","steps":["trace[2684262] 'read index received' (duration: 323.585053ms)","trace[2684262] 'applied index is now lower than readState.Index' (duration: 22.555682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T00:18:58.538837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.418434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T00:18:58.538901Z","caller":"traceutil/trace.go:171","msg":"trace[501310487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1788; }","duration":"355.501264ms","start":"2026-05-20T00:18:58.183389Z","end":"2026-05-20T00:18:58.53889Z","steps":["trace[501310487] 'agreement among raft nodes before linearized reading' (duration: 355.346244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:18:58.538929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.734879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T00:18:58.538978Z","caller":"traceutil/trace.go:171","msg":"trace[1366070422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1788; }","duration":"367.843979ms","start":"2026-05-20T00:18:58.171124Z","end":"2026-05-20T00:18:58.538968Z","steps":["trace[1366070422] 'agreement among raft nodes before linearized reading' (duration: 367.668069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:18:58.539019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T00:18:58.1711Z","time spent":"367.910839ms","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T00:18:58.539056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.247421ms","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-05-20T00:18:58.539085Z","caller":"traceutil/trace.go:171","msg":"trace[580546260] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1788; }","duration":"195.358021ms","start":"2026-05-20T00:18:58.343721Z","end":"2026-05-20T00:18:58.539079Z","steps":["trace[580546260] 'agreement among raft nodes before linearized reading' (duration: 195.252091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:18:58.538938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T00:18:58.183329Z","time spent":"355.603584ms","remote":"127.0.0.1:59638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T00:18:58.539173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.095946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40ec9f6b-4e54-4735-b04b-ccb4dc010370\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-20T00:18:58.539244Z","caller":"traceutil/trace.go:171","msg":"trace[2052412065] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40ec9f6b-4e54-4735-b04b-ccb4dc010370; range_end:; response_count:1; response_revision:1788; }","duration":"544.162186ms","start":"2026-05-20T00:18:57.995062Z","end":"2026-05-20T00:18:58.539224Z","steps":["trace[2052412065] 'agreement among raft nodes before linearized reading' (duration: 544.020136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:18:58.539235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.947893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-20T00:18:58.539269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.852361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-20T00:18:58.53931Z","caller":"traceutil/trace.go:171","msg":"trace[1624728212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1788; }","duration":"291.067602ms","start":"2026-05-20T00:18:58.248223Z","end":"2026-05-20T00:18:58.539291Z","steps":["trace[1624728212] 'agreement among raft nodes before linearized reading' (duration: 290.862833ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:18:58.539357Z","caller":"traceutil/trace.go:171","msg":"trace[687195110] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"402.789215ms","start":"2026-05-20T00:18:58.135898Z","end":"2026-05-20T00:18:58.538688Z","steps":["trace[687195110] 'process raft request' (duration: 380.213583ms)","trace[687195110] 'compare' (duration: 22.377083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T00:18:58.539158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.633504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-20T00:18:58.539561Z","caller":"traceutil/trace.go:171","msg":"trace[206920078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1788; }","duration":"266.065343ms","start":"2026-05-20T00:18:58.273488Z","end":"2026-05-20T00:18:58.539553Z","steps":["trace[206920078] 'agreement among raft nodes before linearized reading' (duration: 265.572654ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:18:58.539365Z","caller":"traceutil/trace.go:171","msg":"trace[1156592150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1788; }","duration":"291.967851ms","start":"2026-05-20T00:18:58.247384Z","end":"2026-05-20T00:18:58.539352Z","steps":["trace[1156592150] 'agreement among raft nodes before linearized reading' (duration: 291.525192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:18:58.539303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T00:18:57.995016Z","time spent":"544.274175ms","remote":"127.0.0.1:59806","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-40ec9f6b-4e54-4735-b04b-ccb4dc010370\" "} {"level":"warn","ts":"2026-05-20T00:18:58.539519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T00:18:58.135872Z","time spent":"403.557824ms","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T00:19:32.433422Z","caller":"traceutil/trace.go:171","msg":"trace[1219938601] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"113.341593ms","start":"2026-05-20T00:19:32.320004Z","end":"2026-05-20T00:19:32.433346Z","steps":["trace[1219938601] 'process raft request' (duration: 113.19071ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:21:09.43867Z","caller":"traceutil/trace.go:171","msg":"trace[121702557] linearizableReadLoop","detail":"{readStateIndex:2760; appliedIndex:2759; }","duration":"129.890212ms","start":"2026-05-20T00:21:09.308757Z","end":"2026-05-20T00:21:09.438647Z","steps":["trace[121702557] 'read index received' (duration: 129.737575ms)","trace[121702557] 'applied index is now lower than readState.Index' (duration: 151.707µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T00:21:09.438726Z","caller":"traceutil/trace.go:171","msg":"trace[643616546] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"130.525259ms","start":"2026-05-20T00:21:09.308165Z","end":"2026-05-20T00:21:09.438691Z","steps":["trace[643616546] 'process raft request' (duration: 130.290729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:21:09.438917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.138973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-20T00:21:09.43895Z","caller":"traceutil/trace.go:171","msg":"trace[1057250706] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2658; }","duration":"130.190835ms","start":"2026-05-20T00:21:09.308751Z","end":"2026-05-20T00:21:09.438942Z","steps":["trace[1057250706] 'agreement among raft nodes before linearized reading' (duration: 129.979926ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:21:09.618439Z","caller":"traceutil/trace.go:171","msg":"trace[2055257205] linearizableReadLoop","detail":"{readStateIndex:2761; appliedIndex:2760; }","duration":"177.694994ms","start":"2026-05-20T00:21:09.440467Z","end":"2026-05-20T00:21:09.618162Z","steps":["trace[2055257205] 'read index received' (duration: 137.40884ms)","trace[2055257205] 'applied index is now lower than readState.Index' (duration: 40.285434ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T00:21:09.618462Z","caller":"traceutil/trace.go:171","msg":"trace[974564573] transaction","detail":"{read_only:false; response_revision:2659; number_of_response:1; }","duration":"308.700383ms","start":"2026-05-20T00:21:09.309738Z","end":"2026-05-20T00:21:09.618438Z","steps":["trace[974564573] 'process raft request' (duration: 268.146928ms)","trace[974564573] 'compare' (duration: 40.018592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T00:21:09.619004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.088859ms","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-05-20T00:21:09.619056Z","caller":"traceutil/trace.go:171","msg":"trace[1549695168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2659; }","duration":"112.157462ms","start":"2026-05-20T00:21:09.506888Z","end":"2026-05-20T00:21:09.619045Z","steps":["trace[1549695168] 'agreement among raft nodes before linearized reading' (duration: 111.966334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:21:09.619088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.5149ms","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-05-20T00:21:09.619134Z","caller":"traceutil/trace.go:171","msg":"trace[2056758268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2659; }","duration":"118.568823ms","start":"2026-05-20T00:21:09.500548Z","end":"2026-05-20T00:21:09.619117Z","steps":["trace[2056758268] 'agreement among raft nodes before linearized reading' (duration: 118.484719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:21:09.618987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.190702ms","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-20T00:21:09.619243Z","caller":"traceutil/trace.go:171","msg":"trace[1073922134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2659; }","duration":"129.493615ms","start":"2026-05-20T00:21:09.489737Z","end":"2026-05-20T00:21:09.619231Z","steps":["trace[1073922134] 'agreement among raft nodes before linearized reading' (duration: 129.081957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:21:09.619301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T00:21:09.309717Z","time spent":"309.208254ms","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T00:21:09.619672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.184286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9280"} {"level":"info","ts":"2026-05-20T00:21:09.619885Z","caller":"traceutil/trace.go:171","msg":"trace[1359458785] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2659; }","duration":"179.437466ms","start":"2026-05-20T00:21:09.440433Z","end":"2026-05-20T00:21:09.61987Z","steps":["trace[1359458785] 'agreement among raft nodes before linearized reading' (duration: 178.045118ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:01.034973Z","caller":"traceutil/trace.go:171","msg":"trace[1541994284] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"108.434054ms","start":"2026-05-20T00:25:00.926519Z","end":"2026-05-20T00:25:01.034953Z","steps":["trace[1541994284] 'process raft request' (duration: 108.26338ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:01.053362Z","caller":"traceutil/trace.go:171","msg":"trace[1119915589] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"126.785661ms","start":"2026-05-20T00:25:00.926545Z","end":"2026-05-20T00:25:01.05333Z","steps":["trace[1119915589] 'process raft request' (duration: 126.511855ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:01.053418Z","caller":"traceutil/trace.go:171","msg":"trace[925552148] linearizableReadLoop","detail":"{readStateIndex:4753; appliedIndex:4749; }","duration":"106.835748ms","start":"2026-05-20T00:25:00.946554Z","end":"2026-05-20T00:25:01.05339Z","steps":["trace[925552148] 'read index received' (duration: 88.345587ms)","trace[925552148] 'applied index is now lower than readState.Index' (duration: 18.488561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T00:25:01.053653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.066303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-20T00:25:01.053718Z","caller":"traceutil/trace.go:171","msg":"trace[1032321492] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4575; }","duration":"107.177356ms","start":"2026-05-20T00:25:00.946524Z","end":"2026-05-20T00:25:01.053701Z","steps":["trace[1032321492] 'agreement among raft nodes before linearized reading' (duration: 106.971521ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:01.05378Z","caller":"traceutil/trace.go:171","msg":"trace[1198400533] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"124.5872ms","start":"2026-05-20T00:25:00.929181Z","end":"2026-05-20T00:25:01.053769Z","steps":["trace[1198400533] 'process raft request' (duration: 124.019186ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:01.053772Z","caller":"traceutil/trace.go:171","msg":"trace[264261740] transaction","detail":"{read_only:false; response_revision:4575; number_of_response:1; }","duration":"124.465306ms","start":"2026-05-20T00:25:00.929287Z","end":"2026-05-20T00:25:01.053752Z","steps":["trace[264261740] 'process raft request' (duration: 124.000955ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:01.584741Z","caller":"traceutil/trace.go:171","msg":"trace[1439707710] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"101.145261ms","start":"2026-05-20T00:25:01.483568Z","end":"2026-05-20T00:25:01.584713Z","steps":["trace[1439707710] 'process raft request' (duration: 100.968527ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:06.77309Z","caller":"traceutil/trace.go:171","msg":"trace[875689475] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"150.763585ms","start":"2026-05-20T00:25:06.622301Z","end":"2026-05-20T00:25:06.773064Z","steps":["trace[875689475] 'process raft request' (duration: 150.52484ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:06.969235Z","caller":"traceutil/trace.go:171","msg":"trace[453143300] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"312.833908ms","start":"2026-05-20T00:25:06.656379Z","end":"2026-05-20T00:25:06.969213Z","steps":["trace[453143300] 'process raft request' (duration: 269.23764ms)","trace[453143300] 'compare' (duration: 43.357633ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T00:25:06.969322Z","caller":"traceutil/trace.go:171","msg":"trace[34027062] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"289.170219ms","start":"2026-05-20T00:25:06.680112Z","end":"2026-05-20T00:25:06.969282Z","steps":["trace[34027062] 'process raft request' (duration: 289.015035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:25:06.969373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T00:25:06.656359Z","time spent":"312.945232ms","remote":"127.0.0.1:59892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T00:25:06.969244Z","caller":"traceutil/trace.go:171","msg":"trace[1174909356] linearizableReadLoop","detail":"{readStateIndex:4866; appliedIndex:4864; }","duration":"152.675713ms","start":"2026-05-20T00:25:06.816544Z","end":"2026-05-20T00:25:06.96922Z","steps":["trace[1174909356] 'read index received' (duration: 109.049523ms)","trace[1174909356] 'applied index is now lower than readState.Index' (duration: 43.62529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T00:25:06.969996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.441782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6b5699964f-n5d55.18b11e5b6d02e34a\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-20T00:25:06.970078Z","caller":"traceutil/trace.go:171","msg":"trace[751606994] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6b5699964f-n5d55.18b11e5b6d02e34a; range_end:; response_count:1; response_revision:4687; }","duration":"153.519494ms","start":"2026-05-20T00:25:06.816512Z","end":"2026-05-20T00:25:06.970032Z","steps":["trace[751606994] 'agreement among raft nodes before linearized reading' (duration: 153.34018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:25:06.970719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.916654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"warn","ts":"2026-05-20T00:25:06.970946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.215348ms","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-05-20T00:25:06.971032Z","caller":"traceutil/trace.go:171","msg":"trace[20000555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4687; }","duration":"120.344941ms","start":"2026-05-20T00:25:06.85067Z","end":"2026-05-20T00:25:06.971015Z","steps":["trace[20000555] 'agreement among raft nodes before linearized reading' (duration: 120.077464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:25:06.97073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.281113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-20T00:25:06.97144Z","caller":"traceutil/trace.go:171","msg":"trace[1919190198] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:4687; }","duration":"113.001211ms","start":"2026-05-20T00:25:06.858415Z","end":"2026-05-20T00:25:06.971416Z","steps":["trace[1919190198] 'agreement among raft nodes before linearized reading' (duration: 112.239932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:25:06.97168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.268275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2352047"} {"level":"info","ts":"2026-05-20T00:25:06.971747Z","caller":"traceutil/trace.go:171","msg":"trace[754438045] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4687; }","duration":"126.349597ms","start":"2026-05-20T00:25:06.845383Z","end":"2026-05-20T00:25:06.971733Z","steps":["trace[754438045] 'agreement among raft nodes before linearized reading' (duration: 125.360653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:25:06.971819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.450009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-20T00:25:06.971006Z","caller":"traceutil/trace.go:171","msg":"trace[1124972609] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4687; }","duration":"154.240171ms","start":"2026-05-20T00:25:06.816746Z","end":"2026-05-20T00:25:06.970986Z","steps":["trace[1124972609] 'agreement among raft nodes before linearized reading' (duration: 153.838672ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:06.97227Z","caller":"traceutil/trace.go:171","msg":"trace[424272046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4687; }","duration":"137.972293ms","start":"2026-05-20T00:25:06.833894Z","end":"2026-05-20T00:25:06.971866Z","steps":["trace[424272046] 'agreement among raft nodes before linearized reading' (duration: 136.926427ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:07.27129Z","caller":"traceutil/trace.go:171","msg":"trace[1806711252] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"104.510046ms","start":"2026-05-20T00:25:07.166753Z","end":"2026-05-20T00:25:07.271263Z","steps":["trace[1806711252] 'process raft request' (duration: 104.214188ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:07.271359Z","caller":"traceutil/trace.go:171","msg":"trace[544462046] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"103.381708ms","start":"2026-05-20T00:25:07.167934Z","end":"2026-05-20T00:25:07.271316Z","steps":["trace[544462046] 'process raft request' (duration: 103.138002ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:07.500081Z","caller":"traceutil/trace.go:171","msg":"trace[356705775] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"156.627541ms","start":"2026-05-20T00:25:07.343355Z","end":"2026-05-20T00:25:07.499983Z","steps":["trace[356705775] 'process raft request' (duration: 156.431516ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:25:07.557273Z","caller":"traceutil/trace.go:171","msg":"trace[1395047707] linearizableReadLoop","detail":"{readStateIndex:4886; appliedIndex:4884; }","duration":"193.825008ms","start":"2026-05-20T00:25:07.363422Z","end":"2026-05-20T00:25:07.557247Z","steps":["trace[1395047707] 'read index received' (duration: 136.373343ms)","trace[1395047707] 'applied index is now lower than readState.Index' (duration: 57.450865ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T00:25:07.557315Z","caller":"traceutil/trace.go:171","msg":"trace[409819073] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"205.798762ms","start":"2026-05-20T00:25:07.351498Z","end":"2026-05-20T00:25:07.557297Z","steps":["trace[409819073] 'process raft request' (duration: 205.650369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T00:25:07.557516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.079714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87ee9417-f44a-4087-966f-73a4ad4d3c24\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-20T00:25:07.557591Z","caller":"traceutil/trace.go:171","msg":"trace[1382474984] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87ee9417-f44a-4087-966f-73a4ad4d3c24; range_end:; response_count:1; response_revision:4706; }","duration":"194.194057ms","start":"2026-05-20T00:25:07.363377Z","end":"2026-05-20T00:25:07.557571Z","steps":["trace[1382474984] 'agreement among raft nodes before linearized reading' (duration: 193.971721ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T00:26:53.973796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-05-20T00:26:54.06658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"91.192227ms","hash":595097081,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":29880320,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-20T00:26:54.066664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":595097081,"revision":2896,"compact-revision":-1} {"level":"info","ts":"2026-05-20T00:31:53.979729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5632} {"level":"info","ts":"2026-05-20T00:31:54.054751Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5632,"took":"73.061036ms","hash":1071311109,"current-db-size-bytes":54804480,"current-db-size":"55 MB","current-db-size-in-use-bytes":18350080,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-20T00:31:54.054805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1071311109,"revision":5632,"compact-revision":2896}