{"level":"warn","ts":"2026-09-17T17:28:56.250174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-17T17:28:56.250281Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.5: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://162.253.55.5:2380","--initial-cluster=instance=https://162.253.55.5:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.5:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.5: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-09-17T17:28:56.250369Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-17T17:28:56.250383Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-09-17T17:28:56.250408Z","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-09-17T17:28:56.251182Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"]} {"level":"info","ts":"2026-09-17T17:28:56.251275Z","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://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.5: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-09-17T17:28:56.254524Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.956909ms"} {"level":"info","ts":"2026-09-17T17:28:56.260676Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b512f7d8ac93be25","cluster-id":"e1953d9a3e7368e5"} {"level":"info","ts":"2026-09-17T17:28:56.260778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=()"} {"level":"info","ts":"2026-09-17T17:28:56.260806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 0"} {"level":"info","ts":"2026-09-17T17:28:56.260821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b512f7d8ac93be25 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-17T17:28:56.260831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 1"} {"level":"info","ts":"2026-09-17T17:28:56.260873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"warn","ts":"2026-09-17T17:28:56.26649Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-17T17:28:56.268093Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-17T17:28:56.26966Z","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-09-17T17:28:56.272172Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b512f7d8ac93be25","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-17T17:28:56.272507Z","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-09-17T17:28:56.272467Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b512f7d8ac93be25","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-17T17:28:56.272622Z","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-09-17T17:28:56.272666Z","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-09-17T17:28:56.276273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"info","ts":"2026-09-17T17:28:56.277036Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","added-peer-id":"b512f7d8ac93be25","added-peer-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-09-17T17:28:56.278089Z","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-09-17T17:28:56.278312Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b512f7d8ac93be25","initial-advertise-peer-urls":["https://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-17T17:28:56.278339Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-17T17:28:56.278456Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-09-17T17:28:56.278471Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-09-17T17:28:56.561575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 is starting a new election at term 1"} {"level":"info","ts":"2026-09-17T17:28:56.561659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-17T17:28:56.561688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgPreVoteResp from b512f7d8ac93be25 at term 1"} {"level":"info","ts":"2026-09-17T17:28:56.561706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became candidate at term 2"} {"level":"info","ts":"2026-09-17T17:28:56.561714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgVoteResp from b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-09-17T17:28:56.561727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became leader at term 2"} {"level":"info","ts":"2026-09-17T17:28:56.561738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b512f7d8ac93be25 elected leader b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-09-17T17:28:56.562868Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b512f7d8ac93be25","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.5:2379]}","request-path":"/0/members/b512f7d8ac93be25/attributes","cluster-id":"e1953d9a3e7368e5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-17T17:28:56.563124Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T17:28:56.56317Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T17:28:56.563371Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-17T17:28:56.563455Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-17T17:28:56.564184Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T17:28:56.564329Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T17:28:56.564399Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T17:28:56.565997Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-17T17:28:56.563086Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T17:28:56.570786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.5:2379"} {"level":"info","ts":"2026-09-17T17:29:10.918835Z","caller":"traceutil/trace.go:171","msg":"trace[1719974604] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"154.763138ms","start":"2026-09-17T17:29:10.764036Z","end":"2026-09-17T17:29:10.918799Z","steps":["trace[1719974604] 'process raft request' (duration: 154.590883ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:29:45.060824Z","caller":"traceutil/trace.go:171","msg":"trace[564934421] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"140.496302ms","start":"2026-09-17T17:29:44.92029Z","end":"2026-09-17T17:29:45.060786Z","steps":["trace[564934421] 'process raft request' (duration: 82.063913ms)","trace[564934421] 'compare' (duration: 58.260074ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:29:45.486811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.03227ms","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-09-17T17:29:45.486956Z","caller":"traceutil/trace.go:171","msg":"trace[829562527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"222.239316ms","start":"2026-09-17T17:29:45.264697Z","end":"2026-09-17T17:29:45.486936Z","steps":["trace[829562527] 'range keys from in-memory index tree' (duration: 221.754322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:29:45.486936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.443548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f9wwf\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-09-17T17:29:45.487024Z","caller":"traceutil/trace.go:171","msg":"trace[195724519] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f9wwf; range_end:; response_count:1; response_revision:604; }","duration":"324.580463ms","start":"2026-09-17T17:29:45.162421Z","end":"2026-09-17T17:29:45.487002Z","steps":["trace[195724519] 'range keys from in-memory index tree' (duration: 324.266744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:29:45.487065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:29:45.162402Z","time spent":"324.654624ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16981,"request content":"key:\"/registry/pods/kube-system/cilium-f9wwf\" "} {"level":"info","ts":"2026-09-17T17:29:45.97215Z","caller":"traceutil/trace.go:171","msg":"trace[1852299576] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"480.846704ms","start":"2026-09-17T17:29:45.491257Z","end":"2026-09-17T17:29:45.972104Z","steps":["trace[1852299576] 'process raft request' (duration: 479.955909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:29:45.973851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:29:45.491228Z","time spent":"481.200976ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T17:29:45.974237Z","caller":"traceutil/trace.go:171","msg":"trace[807347197] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"465.627402ms","start":"2026-09-17T17:29:45.508569Z","end":"2026-09-17T17:29:45.974196Z","steps":["trace[807347197] 'process raft request' (duration: 462.977123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:29:45.974385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:29:45.508534Z","time spent":"465.755536ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T17:29:46.101566Z","caller":"traceutil/trace.go:171","msg":"trace[834789970] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:631; }","duration":"407.959675ms","start":"2026-09-17T17:29:45.693539Z","end":"2026-09-17T17:29:46.101499Z","steps":["trace[834789970] 'read index received' (duration: 276.587434ms)","trace[834789970] 'applied index is now lower than readState.Index' (duration: 131.371221ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:29:46.101603Z","caller":"traceutil/trace.go:171","msg":"trace[1192536836] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"550.346653ms","start":"2026-09-17T17:29:45.551205Z","end":"2026-09-17T17:29:46.101552Z","steps":["trace[1192536836] 'process raft request' (duration: 549.731974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:29:46.101787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.166731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T17:29:46.101829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:29:45.551166Z","time spent":"550.541379ms","remote":"127.0.0.1:37492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T17:29:46.101882Z","caller":"traceutil/trace.go:171","msg":"trace[1801184623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"408.372247ms","start":"2026-09-17T17:29:45.693475Z","end":"2026-09-17T17:29:46.101848Z","steps":["trace[1801184623] 'agreement among raft nodes before linearized reading' (duration: 408.168721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:29:46.101926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:29:45.693451Z","time spent":"408.46396ms","remote":"127.0.0.1:37376","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-09-17T17:30:02.448946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.782045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dhthn\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-09-17T17:30:02.449057Z","caller":"traceutil/trace.go:171","msg":"trace[1535335117] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dhthn; range_end:; response_count:1; response_revision:771; }","duration":"166.97324ms","start":"2026-09-17T17:30:02.282061Z","end":"2026-09-17T17:30:02.449034Z","steps":["trace[1535335117] 'range keys from in-memory index tree' (duration: 166.402204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:31:21.793033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.148339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T17:31:21.793283Z","caller":"traceutil/trace.go:171","msg":"trace[1131565003] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"339.013571ms","start":"2026-09-17T17:31:21.454248Z","end":"2026-09-17T17:31:21.793262Z","steps":["trace[1131565003] 'read index received' (duration: 43.036676ms)","trace[1131565003] 'applied index is now lower than readState.Index' (duration: 295.975024ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:31:21.793353Z","caller":"traceutil/trace.go:171","msg":"trace[645360251] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"361.867894ms","start":"2026-09-17T17:31:21.431441Z","end":"2026-09-17T17:31:21.793309Z","steps":["trace[645360251] 'process raft request' (duration: 65.873489ms)","trace[645360251] 'compare' (duration: 294.649365ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:31:21.79351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.745286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:31:21.793577Z","caller":"traceutil/trace.go:171","msg":"trace[918938461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"100.852509ms","start":"2026-09-17T17:31:21.692711Z","end":"2026-09-17T17:31:21.793564Z","steps":["trace[918938461] 'agreement among raft nodes before linearized reading' (duration: 100.694875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:31:21.79358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:31:21.431409Z","time spent":"362.048409ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T17:31:21.79351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.245908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b43faae9-478f-4057-b90c-bf79cfd53a71\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-09-17T17:31:21.793769Z","caller":"traceutil/trace.go:171","msg":"trace[1388129469] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b43faae9-478f-4057-b90c-bf79cfd53a71; range_end:; response_count:1; response_revision:1859; }","duration":"339.578868ms","start":"2026-09-17T17:31:21.45417Z","end":"2026-09-17T17:31:21.793749Z","steps":["trace[1388129469] 'agreement among raft nodes before linearized reading' (duration: 339.163206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:31:21.793827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:31:21.454104Z","time spent":"339.704241ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b43faae9-478f-4057-b90c-bf79cfd53a71\" "} {"level":"info","ts":"2026-09-17T17:31:21.935131Z","caller":"traceutil/trace.go:171","msg":"trace[459277330] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"133.18513ms","start":"2026-09-17T17:31:21.801864Z","end":"2026-09-17T17:31:21.935049Z","steps":["trace[459277330] 'process raft request' (duration: 121.492466ms)","trace[459277330] 'compare' (duration: 11.439317ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:31:22.328633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.372081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T17:31:22.328754Z","caller":"traceutil/trace.go:171","msg":"trace[111060904] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"290.040488ms","start":"2026-09-17T17:31:22.03869Z","end":"2026-09-17T17:31:22.328731Z","steps":["trace[111060904] 'process raft request' (duration: 21.39582ms)","trace[111060904] 'compare' (duration: 268.244447ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:31:35.698364Z","caller":"traceutil/trace.go:171","msg":"trace[2036496290] linearizableReadLoop","detail":"{readStateIndex:2047; appliedIndex:2046; }","duration":"237.472939ms","start":"2026-09-17T17:31:35.460858Z","end":"2026-09-17T17:31:35.698331Z","steps":["trace[2036496290] 'read index received' (duration: 237.311095ms)","trace[2036496290] 'applied index is now lower than readState.Index' (duration: 160.584µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:31:35.698434Z","caller":"traceutil/trace.go:171","msg":"trace[2072021001] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"305.138245ms","start":"2026-09-17T17:31:35.393254Z","end":"2026-09-17T17:31:35.698392Z","steps":["trace[2072021001] 'process raft request' (duration: 304.867067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:31:35.698688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.79734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-17T17:31:35.69875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:31:35.393225Z","time spent":"305.353622ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T17:31:35.698803Z","caller":"traceutil/trace.go:171","msg":"trace[180451964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1968; }","duration":"237.955824ms","start":"2026-09-17T17:31:35.460819Z","end":"2026-09-17T17:31:35.698775Z","steps":["trace[180451964] 'agreement among raft nodes before linearized reading' (duration: 237.673496ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:32:05.281376Z","caller":"traceutil/trace.go:171","msg":"trace[1395370053] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"165.888837ms","start":"2026-09-17T17:32:05.115449Z","end":"2026-09-17T17:32:05.281337Z","steps":["trace[1395370053] 'process raft request' (duration: 165.552917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:32:05.461558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.171349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T17:32:05.461721Z","caller":"traceutil/trace.go:171","msg":"trace[251975834] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"115.222546ms","start":"2026-09-17T17:32:05.346474Z","end":"2026-09-17T17:32:05.461696Z","steps":["trace[251975834] 'read index received' (duration: 13.597804ms)","trace[251975834] 'applied index is now lower than readState.Index' (duration: 101.623112ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:32:05.461905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.439051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-17T17:32:05.461896Z","caller":"traceutil/trace.go:171","msg":"trace[2082799525] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"175.851692ms","start":"2026-09-17T17:32:05.285993Z","end":"2026-09-17T17:32:05.461844Z","steps":["trace[2082799525] 'process raft request' (duration: 74.255761ms)","trace[2082799525] 'compare' (duration: 100.997704ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:32:05.46195Z","caller":"traceutil/trace.go:171","msg":"trace[1704898014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2147; }","duration":"115.538995ms","start":"2026-09-17T17:32:05.346398Z","end":"2026-09-17T17:32:05.461937Z","steps":["trace[1704898014] 'agreement among raft nodes before linearized reading' (duration: 115.35743ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:32:11.078519Z","caller":"traceutil/trace.go:171","msg":"trace[2046817327] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"116.184775ms","start":"2026-09-17T17:32:10.962297Z","end":"2026-09-17T17:32:11.078482Z","steps":["trace[2046817327] 'process raft request' (duration: 64.112541ms)","trace[2046817327] 'compare' (duration: 51.863238ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:32:17.016773Z","caller":"traceutil/trace.go:171","msg":"trace[152533921] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"140.590057ms","start":"2026-09-17T17:32:16.876134Z","end":"2026-09-17T17:32:17.016724Z","steps":["trace[152533921] 'process raft request' (duration: 140.335909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:32:17.320817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.543257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T17:32:17.320931Z","caller":"traceutil/trace.go:171","msg":"trace[1507956478] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"299.500108ms","start":"2026-09-17T17:32:17.021413Z","end":"2026-09-17T17:32:17.320913Z","steps":["trace[1507956478] 'process raft request' (duration: 48.765345ms)","trace[1507956478] 'compare' (duration: 250.432914ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:33:09.32435Z","caller":"traceutil/trace.go:171","msg":"trace[335659222] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"109.262621ms","start":"2026-09-17T17:33:09.215056Z","end":"2026-09-17T17:33:09.324318Z","steps":["trace[335659222] 'process raft request' (duration: 109.093705ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:33:42.654026Z","caller":"traceutil/trace.go:171","msg":"trace[899475644] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"176.214034ms","start":"2026-09-17T17:33:42.477769Z","end":"2026-09-17T17:33:42.653983Z","steps":["trace[899475644] 'process raft request' (duration: 144.722742ms)","trace[899475644] 'compare' (duration: 31.340698ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:36:39.90292Z","caller":"traceutil/trace.go:171","msg":"trace[1123160038] linearizableReadLoop","detail":"{readStateIndex:4153; appliedIndex:4152; }","duration":"212.794516ms","start":"2026-09-17T17:36:39.690104Z","end":"2026-09-17T17:36:39.902898Z","steps":["trace[1123160038] 'read index received' (duration: 212.609931ms)","trace[1123160038] 'applied index is now lower than readState.Index' (duration: 183.735µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:36:39.903073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.952321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:36:39.903132Z","caller":"traceutil/trace.go:171","msg":"trace[886910153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3997; }","duration":"213.062064ms","start":"2026-09-17T17:36:39.690056Z","end":"2026-09-17T17:36:39.903118Z","steps":["trace[886910153] 'agreement among raft nodes before linearized reading' (duration: 212.958241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:36:39.903097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.152836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:692"} {"level":"info","ts":"2026-09-17T17:36:39.90312Z","caller":"traceutil/trace.go:171","msg":"trace[1539765398] transaction","detail":"{read_only:false; response_revision:3997; number_of_response:1; }","duration":"344.00373ms","start":"2026-09-17T17:36:39.559078Z","end":"2026-09-17T17:36:39.903081Z","steps":["trace[1539765398] 'process raft request' (duration: 343.689951ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:36:39.903248Z","caller":"traceutil/trace.go:171","msg":"trace[688446715] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:3997; }","duration":"106.29427ms","start":"2026-09-17T17:36:39.7969Z","end":"2026-09-17T17:36:39.903195Z","steps":["trace[688446715] 'agreement among raft nodes before linearized reading' (duration: 106.114915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:36:39.903294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:36:39.559058Z","time spent":"344.147073ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T17:36:40.289383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.26045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.5\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-09-17T17:36:40.289494Z","caller":"traceutil/trace.go:171","msg":"trace[857318846] range","detail":"{range_begin:/registry/masterleases/162.253.55.5; range_end:; response_count:1; response_revision:3997; }","duration":"211.434266ms","start":"2026-09-17T17:36:40.07804Z","end":"2026-09-17T17:36:40.289474Z","steps":["trace[857318846] 'range keys from in-memory index tree' (duration: 211.025194ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:36:40.298596Z","caller":"traceutil/trace.go:171","msg":"trace[2094788398] transaction","detail":"{read_only:false; response_revision:3998; number_of_response:1; }","duration":"203.353205ms","start":"2026-09-17T17:36:40.095215Z","end":"2026-09-17T17:36:40.298568Z","steps":["trace[2094788398] 'process raft request' (duration: 203.19947ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:36:40.306728Z","caller":"traceutil/trace.go:171","msg":"trace[1393549376] linearizableReadLoop","detail":"{readStateIndex:4155; appliedIndex:4153; }","duration":"197.351246ms","start":"2026-09-17T17:36:40.109358Z","end":"2026-09-17T17:36:40.306709Z","steps":["trace[1393549376] 'read index received' (duration: 189.084149ms)","trace[1393549376] 'applied index is now lower than readState.Index' (duration: 8.265637ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:36:40.306784Z","caller":"traceutil/trace.go:171","msg":"trace[334485338] transaction","detail":"{read_only:false; response_revision:3999; number_of_response:1; }","duration":"201.933042ms","start":"2026-09-17T17:36:40.104831Z","end":"2026-09-17T17:36:40.306764Z","steps":["trace[334485338] 'process raft request' (duration: 201.685935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:36:40.30693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.564842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-17T17:36:40.306964Z","caller":"traceutil/trace.go:171","msg":"trace[9364752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3999; }","duration":"197.652815ms","start":"2026-09-17T17:36:40.109303Z","end":"2026-09-17T17:36:40.306956Z","steps":["trace[9364752] 'agreement among raft nodes before linearized reading' (duration: 197.50614ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:05.01275Z","caller":"traceutil/trace.go:171","msg":"trace[1658781700] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5036; }","duration":"137.815617ms","start":"2026-09-17T17:38:04.874889Z","end":"2026-09-17T17:38:05.012705Z","steps":["trace[1658781700] 'process raft request' (duration: 58.451016ms)","trace[1658781700] 'compare' (duration: 79.216406ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:38:05.012809Z","caller":"traceutil/trace.go:171","msg":"trace[2002923721] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"108.530182ms","start":"2026-09-17T17:38:04.904253Z","end":"2026-09-17T17:38:05.012784Z","steps":["trace[2002923721] 'process raft request' (duration: 108.434879ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:05.012821Z","caller":"traceutil/trace.go:171","msg":"trace[1466336723] linearizableReadLoop","detail":"{readStateIndex:5233; appliedIndex:5232; }","duration":"136.696473ms","start":"2026-09-17T17:38:04.876091Z","end":"2026-09-17T17:38:05.012788Z","steps":["trace[1466336723] 'read index received' (duration: 22.625516ms)","trace[1466336723] 'applied index is now lower than readState.Index' (duration: 114.068297ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:38:05.013147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.50459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-17T17:38:05.01318Z","caller":"traceutil/trace.go:171","msg":"trace[470686434] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5037; }","duration":"127.588622ms","start":"2026-09-17T17:38:04.885579Z","end":"2026-09-17T17:38:05.013168Z","steps":["trace[470686434] 'agreement among raft nodes before linearized reading' (duration: 127.431887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:38:05.013263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.170988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5474db62-0019-4538-badc-883a3421d773\" ","response":"range_response_count:1 size:4066"} {"level":"info","ts":"2026-09-17T17:38:05.013361Z","caller":"traceutil/trace.go:171","msg":"trace[1573142148] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5474db62-0019-4538-badc-883a3421d773; range_end:; response_count:1; response_revision:5037; }","duration":"137.294482ms","start":"2026-09-17T17:38:04.876055Z","end":"2026-09-17T17:38:05.013349Z","steps":["trace[1573142148] 'agreement among raft nodes before linearized reading' (duration: 136.834637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:38:05.013175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.956546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-km2dk-tls-b6q9s\" ","response":"range_response_count:1 size:2625"} {"level":"info","ts":"2026-09-17T17:38:05.013404Z","caller":"traceutil/trace.go:171","msg":"trace[146431170] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-km2dk-tls-b6q9s; range_end:; response_count:1; response_revision:5037; }","duration":"109.220693ms","start":"2026-09-17T17:38:04.904166Z","end":"2026-09-17T17:38:05.013387Z","steps":["trace[146431170] 'agreement among raft nodes before linearized reading' (duration: 108.907762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:38:08.472971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.233512ms","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-09-17T17:38:08.473106Z","caller":"traceutil/trace.go:171","msg":"trace[1771353972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5184; }","duration":"128.423437ms","start":"2026-09-17T17:38:08.344662Z","end":"2026-09-17T17:38:08.473086Z","steps":["trace[1771353972] 'range keys from in-memory index tree' (duration: 128.051905ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:08.631283Z","caller":"traceutil/trace.go:171","msg":"trace[535881272] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"153.725223ms","start":"2026-09-17T17:38:08.477526Z","end":"2026-09-17T17:38:08.631251Z","steps":["trace[535881272] 'process raft request' (duration: 153.530646ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:23.773214Z","caller":"traceutil/trace.go:171","msg":"trace[1143107128] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"223.324541ms","start":"2026-09-17T17:38:23.549864Z","end":"2026-09-17T17:38:23.773189Z","steps":["trace[1143107128] 'process raft request' (duration: 223.186667ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:23.773218Z","caller":"traceutil/trace.go:171","msg":"trace[389939370] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5664; }","duration":"222.301381ms","start":"2026-09-17T17:38:23.550888Z","end":"2026-09-17T17:38:23.773189Z","steps":["trace[389939370] 'read index received' (duration: 222.289471ms)","trace[389939370] 'applied index is now lower than readState.Index' (duration: 9.6µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:38:23.773423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.518588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-17T17:38:23.773472Z","caller":"traceutil/trace.go:171","msg":"trace[317221993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5453; }","duration":"222.645932ms","start":"2026-09-17T17:38:23.550816Z","end":"2026-09-17T17:38:23.773462Z","steps":["trace[317221993] 'agreement among raft nodes before linearized reading' (duration: 222.458066ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:23.783426Z","caller":"traceutil/trace.go:171","msg":"trace[2010612976] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"214.675553ms","start":"2026-09-17T17:38:23.568734Z","end":"2026-09-17T17:38:23.78341Z","steps":["trace[2010612976] 'process raft request' (duration: 214.539199ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:23.783679Z","caller":"traceutil/trace.go:171","msg":"trace[1080307446] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"210.317474ms","start":"2026-09-17T17:38:23.573334Z","end":"2026-09-17T17:38:23.783651Z","steps":["trace[1080307446] 'process raft request' (duration: 210.037515ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:23.783777Z","caller":"traceutil/trace.go:171","msg":"trace[858132418] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"129.744916ms","start":"2026-09-17T17:38:23.653991Z","end":"2026-09-17T17:38:23.783735Z","steps":["trace[858132418] 'process raft request' (duration: 129.576882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:38:32.894797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.597783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T17:38:32.894919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.178823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c858d4b3-9222-4256-84e2-e1084bb224a7\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-09-17T17:38:32.894992Z","caller":"traceutil/trace.go:171","msg":"trace[1252968359] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c858d4b3-9222-4256-84e2-e1084bb224a7; range_end:; response_count:1; response_revision:5647; }","duration":"263.257385ms","start":"2026-09-17T17:38:32.631714Z","end":"2026-09-17T17:38:32.894972Z","steps":["trace[1252968359] 'range keys from in-memory index tree' (duration: 262.982637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:38:32.894875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.588941ms","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-09-17T17:38:32.895171Z","caller":"traceutil/trace.go:171","msg":"trace[51913665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5647; }","duration":"123.919171ms","start":"2026-09-17T17:38:32.771243Z","end":"2026-09-17T17:38:32.895162Z","steps":["trace[51913665] 'range keys from in-memory index tree' (duration: 123.400406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:38:32.894803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.0351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:38:32.89528Z","caller":"traceutil/trace.go:171","msg":"trace[605801027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5647; }","duration":"201.810449ms","start":"2026-09-17T17:38:32.693136Z","end":"2026-09-17T17:38:32.894947Z","steps":["trace[605801027] 'range keys from in-memory index tree' (duration: 201.52629ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:32.895353Z","caller":"traceutil/trace.go:171","msg":"trace[1838266951] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:5647; }","duration":"333.564985ms","start":"2026-09-17T17:38:32.561733Z","end":"2026-09-17T17:38:32.895298Z","steps":["trace[1838266951] 'count revisions from in-memory index tree' (duration: 332.948767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:38:32.895487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:38:32.561651Z","time spent":"333.809013ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-09-17T17:38:32.908637Z","caller":"traceutil/trace.go:171","msg":"trace[1279245199] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"101.688328ms","start":"2026-09-17T17:38:32.806923Z","end":"2026-09-17T17:38:32.908611Z","steps":["trace[1279245199] 'process raft request' (duration: 101.441991ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:33.18441Z","caller":"traceutil/trace.go:171","msg":"trace[1344915477] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"202.190851ms","start":"2026-09-17T17:38:32.982192Z","end":"2026-09-17T17:38:33.184383Z","steps":["trace[1344915477] 'process raft request' (duration: 202.054447ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:33.258775Z","caller":"traceutil/trace.go:171","msg":"trace[1592838166] linearizableReadLoop","detail":"{readStateIndex:5868; appliedIndex:5866; }","duration":"251.289588ms","start":"2026-09-17T17:38:33.007459Z","end":"2026-09-17T17:38:33.258749Z","steps":["trace[1592838166] 'read index received' (duration: 176.887495ms)","trace[1592838166] 'applied index is now lower than readState.Index' (duration: 74.400923ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:38:33.258963Z","caller":"traceutil/trace.go:171","msg":"trace[340460538] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"275.8024ms","start":"2026-09-17T17:38:32.983117Z","end":"2026-09-17T17:38:33.25892Z","steps":["trace[340460538] 'process raft request' (duration: 275.376137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:38:33.259181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.71362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-09-17T17:38:33.259297Z","caller":"traceutil/trace.go:171","msg":"trace[439003386] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5651; }","duration":"251.846644ms","start":"2026-09-17T17:38:33.007382Z","end":"2026-09-17T17:38:33.259229Z","steps":["trace[439003386] 'agreement among raft nodes before linearized reading' (duration: 251.596236ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:38:56.696259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"info","ts":"2026-09-17T17:38:56.777094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"79.508176ms","hash":584159372,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":32292864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-17T17:38:56.77716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":584159372,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-09-17T17:39:39.676175Z","caller":"traceutil/trace.go:171","msg":"trace[1362039390] transaction","detail":"{read_only:false; response_revision:6240; number_of_response:1; }","duration":"108.13851ms","start":"2026-09-17T17:39:39.568009Z","end":"2026-09-17T17:39:39.676147Z","steps":["trace[1362039390] 'process raft request' (duration: 94.392365ms)","trace[1362039390] 'compare' (duration: 13.640182ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:43:56.708391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5869} {"level":"info","ts":"2026-09-17T17:43:56.787127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5869,"took":"77.442963ms","hash":1556082568,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":26509312,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-17T17:43:56.787186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1556082568,"revision":5869,"compact-revision":2941} {"level":"warn","ts":"2026-09-17T17:44:53.246404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.164529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-17T17:44:53.246513Z","caller":"traceutil/trace.go:171","msg":"trace[1346580453] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:8533; }","duration":"217.335744ms","start":"2026-09-17T17:44:53.029153Z","end":"2026-09-17T17:44:53.246489Z","steps":["trace[1346580453] 'range keys from in-memory index tree' (duration: 216.958563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:44:53.246509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.092526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:44:53.246633Z","caller":"traceutil/trace.go:171","msg":"trace[156408801] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8533; }","duration":"195.296061ms","start":"2026-09-17T17:44:53.051315Z","end":"2026-09-17T17:44:53.246611Z","steps":["trace[156408801] 'count revisions from in-memory index tree' (duration: 194.968501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:44:53.24677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.121705ms","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-09-17T17:44:53.246875Z","caller":"traceutil/trace.go:171","msg":"trace[67157094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8533; }","duration":"122.342552ms","start":"2026-09-17T17:44:53.124504Z","end":"2026-09-17T17:44:53.246846Z","steps":["trace[67157094] 'range keys from in-memory index tree' (duration: 121.876078ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:44:53.444223Z","caller":"traceutil/trace.go:171","msg":"trace[31147002] linearizableReadLoop","detail":"{readStateIndex:8844; appliedIndex:8843; }","duration":"194.044364ms","start":"2026-09-17T17:44:53.250158Z","end":"2026-09-17T17:44:53.444202Z","steps":["trace[31147002] 'read index received' (duration: 193.992133ms)","trace[31147002] 'applied index is now lower than readState.Index' (duration: 51.671µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:44:53.444348Z","caller":"traceutil/trace.go:171","msg":"trace[502185231] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"197.429015ms","start":"2026-09-17T17:44:53.246889Z","end":"2026-09-17T17:44:53.444318Z","steps":["trace[502185231] 'process raft request' (duration: 197.197278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:44:53.444422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.25596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5474db62-0019-4538-badc-883a3421d773\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-17T17:44:53.444451Z","caller":"traceutil/trace.go:171","msg":"trace[2032344555] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5474db62-0019-4538-badc-883a3421d773; range_end:; response_count:1; response_revision:8534; }","duration":"194.362754ms","start":"2026-09-17T17:44:53.250078Z","end":"2026-09-17T17:44:53.444441Z","steps":["trace[2032344555] 'agreement among raft nodes before linearized reading' (duration: 194.21579ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:44:53.446278Z","caller":"traceutil/trace.go:171","msg":"trace[1565079331] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"195.782477ms","start":"2026-09-17T17:44:53.250465Z","end":"2026-09-17T17:44:53.446248Z","steps":["trace[1565079331] 'process raft request' (duration: 195.530148ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:44:53.446409Z","caller":"traceutil/trace.go:171","msg":"trace[2114077491] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"141.50555ms","start":"2026-09-17T17:44:53.304882Z","end":"2026-09-17T17:44:53.446388Z","steps":["trace[2114077491] 'process raft request' (duration: 141.266963ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:45:04.796506Z","caller":"traceutil/trace.go:171","msg":"trace[417393426] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"109.195713ms","start":"2026-09-17T17:45:04.687281Z","end":"2026-09-17T17:45:04.796477Z","steps":["trace[417393426] 'process raft request' (duration: 89.108469ms)","trace[417393426] 'compare' (duration: 19.853357ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:45:05.911986Z","caller":"traceutil/trace.go:171","msg":"trace[427075704] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"113.074688ms","start":"2026-09-17T17:45:05.798889Z","end":"2026-09-17T17:45:05.911964Z","steps":["trace[427075704] 'process raft request' (duration: 112.81481ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:45:05.929908Z","caller":"traceutil/trace.go:171","msg":"trace[1318887919] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"128.99036ms","start":"2026-09-17T17:45:05.800889Z","end":"2026-09-17T17:45:05.92988Z","steps":["trace[1318887919] 'process raft request' (duration: 128.828054ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:45:09.863524Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022ef.wal"} {"level":"info","ts":"2026-09-17T17:45:34.382443Z","caller":"traceutil/trace.go:171","msg":"trace[466468400] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"327.120503ms","start":"2026-09-17T17:45:34.055298Z","end":"2026-09-17T17:45:34.382418Z","steps":["trace[466468400] 'process raft request' (duration: 326.972189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:45:34.382639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:45:34.05528Z","time spent":"327.278628ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T17:45:34.383188Z","caller":"traceutil/trace.go:171","msg":"trace[376223154] linearizableReadLoop","detail":"{readStateIndex:9129; appliedIndex:9129; }","duration":"317.568809ms","start":"2026-09-17T17:45:34.065606Z","end":"2026-09-17T17:45:34.383174Z","steps":["trace[376223154] 'read index received' (duration: 317.563919ms)","trace[376223154] 'applied index is now lower than readState.Index' (duration: 4.13µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:45:34.38334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.729964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-17T17:45:34.383376Z","caller":"traceutil/trace.go:171","msg":"trace[733557480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8809; }","duration":"317.817787ms","start":"2026-09-17T17:45:34.065548Z","end":"2026-09-17T17:45:34.383366Z","steps":["trace[733557480] 'agreement among raft nodes before linearized reading' (duration: 317.676533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:45:34.383402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:45:34.065526Z","time spent":"317.870368ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-17T17:45:34.58266Z","caller":"traceutil/trace.go:171","msg":"trace[357265539] linearizableReadLoop","detail":"{readStateIndex:9130; appliedIndex:9129; }","duration":"128.959775ms","start":"2026-09-17T17:45:34.453675Z","end":"2026-09-17T17:45:34.582635Z","steps":["trace[357265539] 'read index received' (duration: 128.179652ms)","trace[357265539] 'applied index is now lower than readState.Index' (duration: 778.763µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:45:34.582703Z","caller":"traceutil/trace.go:171","msg":"trace[1739850236] transaction","detail":"{read_only:false; response_revision:8810; number_of_response:1; }","duration":"195.408436ms","start":"2026-09-17T17:45:34.387265Z","end":"2026-09-17T17:45:34.582673Z","steps":["trace[1739850236] 'process raft request' (duration: 194.54534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:45:34.583002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.309645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-17T17:45:34.583046Z","caller":"traceutil/trace.go:171","msg":"trace[810773824] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8810; }","duration":"129.411959ms","start":"2026-09-17T17:45:34.453623Z","end":"2026-09-17T17:45:34.583035Z","steps":["trace[810773824] 'agreement among raft nodes before linearized reading' (duration: 129.11188ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:45:44.872371Z","caller":"traceutil/trace.go:171","msg":"trace[1879964048] linearizableReadLoop","detail":"{readStateIndex:9187; appliedIndex:9186; }","duration":"179.844285ms","start":"2026-09-17T17:45:44.692497Z","end":"2026-09-17T17:45:44.872341Z","steps":["trace[1879964048] 'read index received' (duration: 179.67238ms)","trace[1879964048] 'applied index is now lower than readState.Index' (duration: 170.145µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:45:44.872586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.081672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:45:44.872436Z","caller":"traceutil/trace.go:171","msg":"trace[1636236162] transaction","detail":"{read_only:false; response_revision:8864; number_of_response:1; }","duration":"245.707079ms","start":"2026-09-17T17:45:44.626688Z","end":"2026-09-17T17:45:44.872396Z","steps":["trace[1636236162] 'process raft request' (duration: 245.478282ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:45:44.872638Z","caller":"traceutil/trace.go:171","msg":"trace[1793816970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8864; }","duration":"180.184315ms","start":"2026-09-17T17:45:44.692437Z","end":"2026-09-17T17:45:44.872621Z","steps":["trace[1793816970] 'agreement among raft nodes before linearized reading' (duration: 180.062002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:45:44.87278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.02041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-17T17:45:44.872831Z","caller":"traceutil/trace.go:171","msg":"trace[1500773074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8864; }","duration":"180.091443ms","start":"2026-09-17T17:45:44.692727Z","end":"2026-09-17T17:45:44.872819Z","steps":["trace[1500773074] 'agreement among raft nodes before linearized reading' (duration: 179.944169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:45:45.027338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.318484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:45:45.027445Z","caller":"traceutil/trace.go:171","msg":"trace[709281920] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8866; }","duration":"134.474719ms","start":"2026-09-17T17:45:44.892946Z","end":"2026-09-17T17:45:45.027421Z","steps":["trace[709281920] 'agreement among raft nodes before linearized reading' (duration: 134.285453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:45:47.461652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.777151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T17:45:47.461755Z","caller":"traceutil/trace.go:171","msg":"trace[1227409952] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8886; }","duration":"170.010662ms","start":"2026-09-17T17:45:47.29173Z","end":"2026-09-17T17:45:47.461741Z","steps":["trace[1227409952] 'compare' (duration: 154.657358ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:45:47.479556Z","caller":"traceutil/trace.go:171","msg":"trace[1774370342] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"186.10808ms","start":"2026-09-17T17:45:47.293418Z","end":"2026-09-17T17:45:47.479526Z","steps":["trace[1774370342] 'process raft request' (duration: 185.903734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:47:01.570146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.217109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-17T17:47:01.570237Z","caller":"traceutil/trace.go:171","msg":"trace[852427134] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9649; }","duration":"112.372563ms","start":"2026-09-17T17:47:01.457847Z","end":"2026-09-17T17:47:01.57022Z","steps":["trace[852427134] 'range keys from in-memory index tree' (duration: 111.94513ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:47:03.065937Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-17T17:47:03.073455Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-17T17:47:03.073548Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-17T17:48:56.714854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8036} {"level":"info","ts":"2026-09-17T17:48:56.803566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8036,"took":"86.496623ms","hash":1506195677,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":28123136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-17T17:48:56.803649Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1506195677,"revision":8036,"compact-revision":5869} {"level":"warn","ts":"2026-09-17T17:52:34.90431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.951438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:52:34.90441Z","caller":"traceutil/trace.go:171","msg":"trace[1038532754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12053; }","duration":"213.091842ms","start":"2026-09-17T17:52:34.691289Z","end":"2026-09-17T17:52:34.90438Z","steps":["trace[1038532754] 'range keys from in-memory index tree' (duration: 212.862184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:52:34.904519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.557166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xjfmp\" ","response":"range_response_count:1 size:6437"} {"level":"info","ts":"2026-09-17T17:52:34.904604Z","caller":"traceutil/trace.go:171","msg":"trace[744617555] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xjfmp; range_end:; response_count:1; response_revision:12053; }","duration":"207.67254ms","start":"2026-09-17T17:52:34.696905Z","end":"2026-09-17T17:52:34.904578Z","steps":["trace[744617555] 'range keys from in-memory index tree' (duration: 207.398531ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:52:53.632268Z","caller":"traceutil/trace.go:171","msg":"trace[1820819439] linearizableReadLoop","detail":"{readStateIndex:12583; appliedIndex:12582; }","duration":"136.524353ms","start":"2026-09-17T17:52:53.495705Z","end":"2026-09-17T17:52:53.632229Z","steps":["trace[1820819439] 'read index received' (duration: 127.621939ms)","trace[1820819439] 'applied index is now lower than readState.Index' (duration: 8.900764ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:52:53.632323Z","caller":"traceutil/trace.go:171","msg":"trace[586142437] transaction","detail":"{read_only:false; response_revision:12159; number_of_response:1; }","duration":"215.946714ms","start":"2026-09-17T17:52:53.416341Z","end":"2026-09-17T17:52:53.632288Z","steps":["trace[586142437] 'process raft request' (duration: 207.070272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:52:53.632502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.779849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:52:53.632563Z","caller":"traceutil/trace.go:171","msg":"trace[759524232] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:12159; }","duration":"136.897274ms","start":"2026-09-17T17:52:53.495649Z","end":"2026-09-17T17:52:53.632546Z","steps":["trace[759524232] 'agreement among raft nodes before linearized reading' (duration: 136.779269ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:52:54.757216Z","caller":"traceutil/trace.go:171","msg":"trace[967393939] transaction","detail":"{read_only:false; response_revision:12164; number_of_response:1; }","duration":"111.572614ms","start":"2026-09-17T17:52:54.645538Z","end":"2026-09-17T17:52:54.75711Z","steps":["trace[967393939] 'process raft request' (duration: 80.120782ms)","trace[967393939] 'compare' (duration: 30.807373ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:53:56.7241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10429} {"level":"info","ts":"2026-09-17T17:53:56.820701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10429,"took":"94.029025ms","hash":124136713,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":25313280,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-17T17:53:56.82076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":124136713,"revision":10429,"compact-revision":8036} {"level":"info","ts":"2026-09-17T17:58:56.730143Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12600} {"level":"info","ts":"2026-09-17T17:58:56.806087Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12600,"took":"73.650112ms","hash":3463478120,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":27344896,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-17T17:58:56.806148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3463478120,"revision":12600,"compact-revision":10429} {"level":"info","ts":"2026-09-17T17:59:01.646057Z","caller":"traceutil/trace.go:171","msg":"trace[337285042] transaction","detail":"{read_only:false; response_revision:14649; number_of_response:1; }","duration":"143.170819ms","start":"2026-09-17T17:59:01.502859Z","end":"2026-09-17T17:59:01.64603Z","steps":["trace[337285042] 'process raft request' (duration: 143.023655ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:59:21.357425Z","caller":"traceutil/trace.go:171","msg":"trace[220566285] transaction","detail":"{read_only:false; response_revision:14751; number_of_response:1; }","duration":"168.503551ms","start":"2026-09-17T17:59:21.188902Z","end":"2026-09-17T17:59:21.357406Z","steps":["trace[220566285] 'process raft request' (duration: 168.346125ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:59:30.331402Z","caller":"traceutil/trace.go:171","msg":"trace[1920180872] transaction","detail":"{read_only:false; response_revision:14798; number_of_response:1; }","duration":"107.73854ms","start":"2026-09-17T17:59:30.223634Z","end":"2026-09-17T17:59:30.331372Z","steps":["trace[1920180872] 'process raft request' (duration: 107.663638ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:59:30.331425Z","caller":"traceutil/trace.go:171","msg":"trace[785878022] transaction","detail":"{read_only:false; response_revision:14797; number_of_response:1; }","duration":"108.526864ms","start":"2026-09-17T17:59:30.222856Z","end":"2026-09-17T17:59:30.331382Z","steps":["trace[785878022] 'process raft request' (duration: 106.247346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:59:37.996775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.208923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-17T17:59:37.996856Z","caller":"traceutil/trace.go:171","msg":"trace[99050302] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14847; }","duration":"138.333527ms","start":"2026-09-17T17:59:37.858505Z","end":"2026-09-17T17:59:37.996839Z","steps":["trace[99050302] 'range keys from in-memory index tree' (duration: 137.823921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:59:38.849376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.207017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xjfmp\" ","response":"range_response_count:1 size:6437"} {"level":"info","ts":"2026-09-17T17:59:38.849497Z","caller":"traceutil/trace.go:171","msg":"trace[1547527410] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xjfmp; range_end:; response_count:1; response_revision:14852; }","duration":"358.378002ms","start":"2026-09-17T17:59:38.491093Z","end":"2026-09-17T17:59:38.849471Z","steps":["trace[1547527410] 'range keys from in-memory index tree' (duration: 357.950519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:59:38.849542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:59:38.491067Z","time spent":"358.463805ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6461,"request content":"key:\"/registry/pods/openstack/keepalived-xjfmp\" "} {"level":"warn","ts":"2026-09-17T17:59:38.849829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.537946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T17:59:38.849935Z","caller":"traceutil/trace.go:171","msg":"trace[1072650685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14852; }","duration":"157.717871ms","start":"2026-09-17T17:59:38.692192Z","end":"2026-09-17T17:59:38.84991Z","steps":["trace[1072650685] 'range keys from in-memory index tree' (duration: 157.458303ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:59:38.975078Z","caller":"traceutil/trace.go:171","msg":"trace[884178792] transaction","detail":"{read_only:false; response_revision:14853; number_of_response:1; }","duration":"120.302262ms","start":"2026-09-17T17:59:38.854752Z","end":"2026-09-17T17:59:38.975054Z","steps":["trace[884178792] 'process raft request' (duration: 120.163968ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:59:38.975088Z","caller":"traceutil/trace.go:171","msg":"trace[935994292] linearizableReadLoop","detail":"{readStateIndex:15374; appliedIndex:15373; }","duration":"120.112946ms","start":"2026-09-17T17:59:38.854946Z","end":"2026-09-17T17:59:38.975059Z","steps":["trace[935994292] 'read index received' (duration: 120.087686ms)","trace[935994292] 'applied index is now lower than readState.Index' (duration: 23.14µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T17:59:38.975818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.849689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:590332"} {"level":"info","ts":"2026-09-17T17:59:38.97586Z","caller":"traceutil/trace.go:171","msg":"trace[1250067292] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14853; }","duration":"120.940642ms","start":"2026-09-17T17:59:38.854907Z","end":"2026-09-17T17:59:38.975848Z","steps":["trace[1250067292] 'agreement among raft nodes before linearized reading' (duration: 120.245031ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:59:49.2666Z","caller":"traceutil/trace.go:171","msg":"trace[1838383652] transaction","detail":"{read_only:false; response_revision:15023; number_of_response:1; }","duration":"320.875383ms","start":"2026-09-17T17:59:48.9457Z","end":"2026-09-17T17:59:49.266576Z","steps":["trace[1838383652] 'process raft request' (duration: 320.704927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:59:49.266762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T17:59:48.945682Z","time spent":"320.998486ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3710,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T17:59:49.278163Z","caller":"traceutil/trace.go:171","msg":"trace[417207182] linearizableReadLoop","detail":"{readStateIndex:15548; appliedIndex:15546; }","duration":"240.79831ms","start":"2026-09-17T17:59:49.037338Z","end":"2026-09-17T17:59:49.278136Z","steps":["trace[417207182] 'read index received' (duration: 229.097913ms)","trace[417207182] 'applied index is now lower than readState.Index' (duration: 11.698857ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T17:59:49.278292Z","caller":"traceutil/trace.go:171","msg":"trace[1198973283] transaction","detail":"{read_only:false; response_revision:15024; number_of_response:1; }","duration":"244.083407ms","start":"2026-09-17T17:59:49.034162Z","end":"2026-09-17T17:59:49.278246Z","steps":["trace[1198973283] 'process raft request' (duration: 243.768238ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T17:59:49.278344Z","caller":"traceutil/trace.go:171","msg":"trace[354875804] transaction","detail":"{read_only:false; response_revision:15025; number_of_response:1; }","duration":"239.7882ms","start":"2026-09-17T17:59:49.038518Z","end":"2026-09-17T17:59:49.278306Z","steps":["trace[354875804] 'process raft request' (duration: 239.568654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:59:49.278586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.427088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xjfmp\" ","response":"range_response_count:1 size:6437"} {"level":"info","ts":"2026-09-17T17:59:49.278695Z","caller":"traceutil/trace.go:171","msg":"trace[774997257] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xjfmp; range_end:; response_count:1; response_revision:15025; }","duration":"216.563413ms","start":"2026-09-17T17:59:49.062085Z","end":"2026-09-17T17:59:49.278649Z","steps":["trace[774997257] 'agreement among raft nodes before linearized reading' (duration: 216.320246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:59:49.278884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.877899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-bnqn7.18d62d8d7b458037\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-17T17:59:49.278911Z","caller":"traceutil/trace.go:171","msg":"trace[2102743009] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-bnqn7.18d62d8d7b458037; range_end:; response_count:1; response_revision:15025; }","duration":"147.93888ms","start":"2026-09-17T17:59:49.130961Z","end":"2026-09-17T17:59:49.2789Z","steps":["trace[2102743009] 'agreement among raft nodes before linearized reading' (duration: 147.853478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T17:59:49.278952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.586573ms","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-09-17T17:59:49.279009Z","caller":"traceutil/trace.go:171","msg":"trace[2062853562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15025; }","duration":"241.688086ms","start":"2026-09-17T17:59:49.037305Z","end":"2026-09-17T17:59:49.278993Z","steps":["trace[2062853562] 'agreement among raft nodes before linearized reading' (duration: 241.445229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.683093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.362161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T18:01:20.68332Z","caller":"traceutil/trace.go:171","msg":"trace[413416631] transaction","detail":"{read_only:false; response_revision:16071; number_of_response:1; }","duration":"345.851593ms","start":"2026-09-17T18:01:20.337431Z","end":"2026-09-17T18:01:20.683283Z","steps":["trace[413416631] 'process raft request' (duration: 176.196124ms)","trace[413416631] 'compare' (duration: 168.958369ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:01:20.683428Z","caller":"traceutil/trace.go:171","msg":"trace[1198833058] transaction","detail":"{read_only:false; response_revision:16072; number_of_response:1; }","duration":"306.019385ms","start":"2026-09-17T18:01:20.377386Z","end":"2026-09-17T18:01:20.683405Z","steps":["trace[1198833058] 'process raft request' (duration: 305.880351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.683477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:01:20.337401Z","time spent":"345.990577ms","remote":"127.0.0.1:37410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:01:20.683552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:01:20.377367Z","time spent":"306.111887ms","remote":"127.0.0.1:37650","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-09-17T18:01:20.683821Z","caller":"traceutil/trace.go:171","msg":"trace[108972560] linearizableReadLoop","detail":"{readStateIndex:16627; appliedIndex:16626; }","duration":"342.474334ms","start":"2026-09-17T18:01:20.34133Z","end":"2026-09-17T18:01:20.683805Z","steps":["trace[108972560] 'read index received' (duration: 172.322389ms)","trace[108972560] 'applied index is now lower than readState.Index' (duration: 170.149025ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:01:20.684277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.979669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-09-17T18:01:20.684294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.342019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-09-17T18:01:20.684514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.117042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-17T18:01:20.684524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.378243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T18:01:20.684561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.252148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T18:01:20.68451Z","caller":"traceutil/trace.go:171","msg":"trace[1794897324] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16072; }","duration":"267.090853ms","start":"2026-09-17T18:01:20.417215Z","end":"2026-09-17T18:01:20.684306Z","steps":["trace[1794897324] 'agreement among raft nodes before linearized reading' (duration: 266.894647ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:01:20.684598Z","caller":"traceutil/trace.go:171","msg":"trace[2106501633] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16072; }","duration":"267.31832ms","start":"2026-09-17T18:01:20.417272Z","end":"2026-09-17T18:01:20.68459Z","steps":["trace[2106501633] 'agreement among raft nodes before linearized reading' (duration: 267.261968ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:01:20.684596Z","caller":"traceutil/trace.go:171","msg":"trace[390673885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16072; }","duration":"126.40069ms","start":"2026-09-17T18:01:20.558166Z","end":"2026-09-17T18:01:20.684567Z","steps":["trace[390673885] 'agreement among raft nodes before linearized reading' (duration: 125.921876ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:01:20.68461Z","caller":"traceutil/trace.go:171","msg":"trace[2034018486] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16072; }","duration":"117.485797ms","start":"2026-09-17T18:01:20.567099Z","end":"2026-09-17T18:01:20.684584Z","steps":["trace[2034018486] 'agreement among raft nodes before linearized reading' (duration: 117.099195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.684597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.725733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-09-17T18:01:20.684626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.406637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-09-17T18:01:20.684676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.92825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-17T18:01:20.684712Z","caller":"traceutil/trace.go:171","msg":"trace[418505656] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16072; }","duration":"262.848716ms","start":"2026-09-17T18:01:20.421834Z","end":"2026-09-17T18:01:20.684682Z","steps":["trace[418505656] 'agreement among raft nodes before linearized reading' (duration: 262.324261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.684678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.822821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-17T18:01:20.684735Z","caller":"traceutil/trace.go:171","msg":"trace[1036873639] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16072; }","duration":"311.027932ms","start":"2026-09-17T18:01:20.373687Z","end":"2026-09-17T18:01:20.684715Z","steps":["trace[1036873639] 'agreement among raft nodes before linearized reading' (duration: 310.439685ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:01:20.684767Z","caller":"traceutil/trace.go:171","msg":"trace[1175936827] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16072; }","duration":"255.53117ms","start":"2026-09-17T18:01:20.429163Z","end":"2026-09-17T18:01:20.684694Z","steps":["trace[1175936827] 'agreement among raft nodes before linearized reading' (duration: 255.090927ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:01:20.684563Z","caller":"traceutil/trace.go:171","msg":"trace[90916194] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16072; }","duration":"318.659749ms","start":"2026-09-17T18:01:20.365885Z","end":"2026-09-17T18:01:20.684545Z","steps":["trace[90916194] 'agreement among raft nodes before linearized reading' (duration: 318.227976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.684784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.62011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T18:01:20.684795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:01:20.373669Z","time spent":"311.117936ms","remote":"127.0.0.1:37620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5472,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-09-17T18:01:20.684777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.571955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T18:01:20.684816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:01:20.365869Z","time spent":"318.935826ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-09-17T18:01:20.684812Z","caller":"traceutil/trace.go:171","msg":"trace[1187430430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16072; }","duration":"105.978295ms","start":"2026-09-17T18:01:20.578802Z","end":"2026-09-17T18:01:20.68478Z","steps":["trace[1187430430] 'agreement among raft nodes before linearized reading' (duration: 105.390918ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:01:20.684833Z","caller":"traceutil/trace.go:171","msg":"trace[1166176754] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16072; }","duration":"117.710392ms","start":"2026-09-17T18:01:20.567109Z","end":"2026-09-17T18:01:20.684819Z","steps":["trace[1166176754] 'agreement among raft nodes before linearized reading' (duration: 117.62667ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:01:20.684845Z","caller":"traceutil/trace.go:171","msg":"trace[2063055163] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16072; }","duration":"321.684978ms","start":"2026-09-17T18:01:20.363144Z","end":"2026-09-17T18:01:20.684829Z","steps":["trace[2063055163] 'agreement among raft nodes before linearized reading' (duration: 321.592025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.684875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:01:20.363121Z","time spent":"321.74853ms","remote":"127.0.0.1:37620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-09-17T18:01:20.684881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.264329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T18:01:20.68493Z","caller":"traceutil/trace.go:171","msg":"trace[2074513520] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16072; }","duration":"118.351161ms","start":"2026-09-17T18:01:20.566565Z","end":"2026-09-17T18:01:20.684916Z","steps":["trace[2074513520] 'agreement among raft nodes before linearized reading' (duration: 118.269359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.685413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.860369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-17T18:01:20.685463Z","caller":"traceutil/trace.go:171","msg":"trace[1004746616] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16072; }","duration":"113.908281ms","start":"2026-09-17T18:01:20.571537Z","end":"2026-09-17T18:01:20.685446Z","steps":["trace[1004746616] 'agreement among raft nodes before linearized reading' (duration: 112.55366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.685918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.910897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764037"} {"level":"info","ts":"2026-09-17T18:01:20.685978Z","caller":"traceutil/trace.go:171","msg":"trace[129478038] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16072; }","duration":"342.992289ms","start":"2026-09-17T18:01:20.342967Z","end":"2026-09-17T18:01:20.68596Z","steps":["trace[129478038] 'agreement among raft nodes before linearized reading' (duration: 341.099473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.686012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:01:20.342957Z","time spent":"343.044061ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":764061,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-09-17T18:01:20.686095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.753881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764037"} {"level":"info","ts":"2026-09-17T18:01:20.68615Z","caller":"traceutil/trace.go:171","msg":"trace[1774664848] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16072; }","duration":"344.832473ms","start":"2026-09-17T18:01:20.341302Z","end":"2026-09-17T18:01:20.686134Z","steps":["trace[1774664848] 'agreement among raft nodes before linearized reading' (duration: 342.650828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:01:20.686194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:01:20.341288Z","time spent":"344.893915ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":764061,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-17T18:03:56.738575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14617} {"level":"info","ts":"2026-09-17T18:03:56.831697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14617,"took":"90.394234ms","hash":2282505232,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":32260096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-17T18:03:56.831799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2282505232,"revision":14617,"compact-revision":12600} {"level":"info","ts":"2026-09-17T18:07:11.204784Z","caller":"traceutil/trace.go:171","msg":"trace[1110362776] transaction","detail":"{read_only:false; response_revision:18655; number_of_response:1; }","duration":"232.279918ms","start":"2026-09-17T18:07:10.972479Z","end":"2026-09-17T18:07:11.204758Z","steps":["trace[1110362776] 'process raft request' (duration: 232.101853ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:11.27846Z","caller":"traceutil/trace.go:171","msg":"trace[2131361907] transaction","detail":"{read_only:false; response_revision:18657; number_of_response:1; }","duration":"262.125822ms","start":"2026-09-17T18:07:11.01631Z","end":"2026-09-17T18:07:11.278436Z","steps":["trace[2131361907] 'process raft request' (duration: 262.082541ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:11.278655Z","caller":"traceutil/trace.go:171","msg":"trace[546153380] transaction","detail":"{read_only:false; response_revision:18656; number_of_response:1; }","duration":"265.252334ms","start":"2026-09-17T18:07:11.013391Z","end":"2026-09-17T18:07:11.278644Z","steps":["trace[546153380] 'process raft request' (duration: 259.257957ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:11.32062Z","caller":"traceutil/trace.go:171","msg":"trace[1959835588] transaction","detail":"{read_only:false; response_revision:18658; number_of_response:1; }","duration":"108.125962ms","start":"2026-09-17T18:07:11.212448Z","end":"2026-09-17T18:07:11.320574Z","steps":["trace[1959835588] 'process raft request' (duration: 107.907765ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:11.320882Z","caller":"traceutil/trace.go:171","msg":"trace[542919141] transaction","detail":"{read_only:false; response_revision:18659; number_of_response:1; }","duration":"107.777722ms","start":"2026-09-17T18:07:11.213075Z","end":"2026-09-17T18:07:11.320853Z","steps":["trace[542919141] 'process raft request' (duration: 107.421101ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:11.320973Z","caller":"traceutil/trace.go:171","msg":"trace[1563802671] transaction","detail":"{read_only:false; response_revision:18661; number_of_response:1; }","duration":"103.002969ms","start":"2026-09-17T18:07:11.217945Z","end":"2026-09-17T18:07:11.320948Z","steps":["trace[1563802671] 'process raft request' (duration: 102.923657ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:11.321057Z","caller":"traceutil/trace.go:171","msg":"trace[1905198946] transaction","detail":"{read_only:false; response_revision:18660; number_of_response:1; }","duration":"106.867464ms","start":"2026-09-17T18:07:11.214136Z","end":"2026-09-17T18:07:11.321003Z","steps":["trace[1905198946] 'process raft request' (duration: 106.601356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:11.77178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.147597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T18:07:11.77219Z","caller":"traceutil/trace.go:171","msg":"trace[476148674] linearizableReadLoop","detail":"{readStateIndex:19306; appliedIndex:19305; }","duration":"170.376755ms","start":"2026-09-17T18:07:11.601789Z","end":"2026-09-17T18:07:11.772165Z","steps":["trace[476148674] 'read index received' (duration: 70.612µs)","trace[476148674] 'applied index is now lower than readState.Index' (duration: 170.304013ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:07:11.772261Z","caller":"traceutil/trace.go:171","msg":"trace[291726479] transaction","detail":"{read_only:false; response_revision:18663; number_of_response:1; }","duration":"433.132776ms","start":"2026-09-17T18:07:11.339059Z","end":"2026-09-17T18:07:11.772191Z","steps":["trace[291726479] 'process raft request' (duration: 208.457692ms)","trace[291726479] 'compare' (duration: 223.858659ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:07:11.772444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:11.339039Z","time spent":"433.308461ms","remote":"127.0.0.1:37814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11347,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:07:11.772478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.684534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-17T18:07:11.772546Z","caller":"traceutil/trace.go:171","msg":"trace[1220600947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18663; }","duration":"170.813518ms","start":"2026-09-17T18:07:11.601709Z","end":"2026-09-17T18:07:11.772523Z","steps":["trace[1220600947] 'agreement among raft nodes before linearized reading' (duration: 170.572451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.276019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.561993ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T18:07:12.276136Z","caller":"traceutil/trace.go:171","msg":"trace[378755359] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18663; }","duration":"242.689416ms","start":"2026-09-17T18:07:12.03343Z","end":"2026-09-17T18:07:12.27612Z","steps":["trace[378755359] 'range keys from in-memory index tree' (duration: 242.548351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.276375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.917108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T18:07:12.276506Z","caller":"traceutil/trace.go:171","msg":"trace[1539818436] transaction","detail":"{read_only:false; response_revision:18664; number_of_response:1; }","duration":"498.924684ms","start":"2026-09-17T18:07:11.777556Z","end":"2026-09-17T18:07:12.276481Z","steps":["trace[1539818436] 'process raft request' (duration: 319.81428ms)","trace[1539818436] 'compare' (duration: 178.768073ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:07:12.276912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:11.777536Z","time spent":"499.327256ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T18:07:12.291623Z","caller":"traceutil/trace.go:171","msg":"trace[588789275] transaction","detail":"{read_only:false; response_revision:18665; number_of_response:1; }","duration":"501.202771ms","start":"2026-09-17T18:07:11.790408Z","end":"2026-09-17T18:07:12.29161Z","steps":["trace[588789275] 'process raft request' (duration: 501.030775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.291668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:11.790384Z","time spent":"501.260513ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20770,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T18:07:12.291601Z","caller":"traceutil/trace.go:171","msg":"trace[764215193] linearizableReadLoop","detail":"{readStateIndex:19308; appliedIndex:19306; }","duration":"485.023782ms","start":"2026-09-17T18:07:11.806563Z","end":"2026-09-17T18:07:12.291587Z","steps":["trace[764215193] 'read index received' (duration: 290.914754ms)","trace[764215193] 'applied index is now lower than readState.Index' (duration: 194.107988ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:07:12.291793Z","caller":"traceutil/trace.go:171","msg":"trace[1158375958] transaction","detail":"{read_only:false; response_revision:18666; number_of_response:1; }","duration":"337.320078ms","start":"2026-09-17T18:07:11.954461Z","end":"2026-09-17T18:07:12.291781Z","steps":["trace[1158375958] 'process raft request' (duration: 337.08337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.291864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:11.954432Z","time spent":"337.38822ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:07:12.292005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.440584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cckqz\" ","response":"range_response_count:1 size:9711"} {"level":"info","ts":"2026-09-17T18:07:12.292072Z","caller":"traceutil/trace.go:171","msg":"trace[1244045817] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cckqz; range_end:; response_count:1; response_revision:18666; }","duration":"485.547488ms","start":"2026-09-17T18:07:11.806509Z","end":"2026-09-17T18:07:12.292056Z","steps":["trace[1244045817] 'agreement among raft nodes before linearized reading' (duration: 485.370763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.292119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:11.806488Z","time spent":"485.613979ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9735,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-cckqz\" "} {"level":"info","ts":"2026-09-17T18:07:12.516089Z","caller":"traceutil/trace.go:171","msg":"trace[2009142506] linearizableReadLoop","detail":"{readStateIndex:19311; appliedIndex:19309; }","duration":"224.276341ms","start":"2026-09-17T18:07:12.291788Z","end":"2026-09-17T18:07:12.516064Z","steps":["trace[2009142506] 'read index received' (duration: 176.319621ms)","trace[2009142506] 'applied index is now lower than readState.Index' (duration: 47.95556ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:07:12.516398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.790125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-17T18:07:12.516518Z","caller":"traceutil/trace.go:171","msg":"trace[881034463] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:18668; }","duration":"559.523367ms","start":"2026-09-17T18:07:11.956977Z","end":"2026-09-17T18:07:12.516501Z","steps":["trace[881034463] 'agreement among raft nodes before linearized reading' (duration: 559.329122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.516515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-17T18:07:12.516601Z","caller":"traceutil/trace.go:171","msg":"trace[2034899946] transaction","detail":"{read_only:false; response_revision:18668; number_of_response:1; }","duration":"323.608452ms","start":"2026-09-17T18:07:12.192957Z","end":"2026-09-17T18:07:12.516565Z","steps":["trace[2034899946] 'process raft request' (duration: 323.052536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.51659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:11.956899Z","time spent":"559.677002ms","remote":"127.0.0.1:37526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":13,"response size":32,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-09-17T18:07:12.516643Z","caller":"traceutil/trace.go:171","msg":"trace[826708153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18668; }","duration":"677.311655ms","start":"2026-09-17T18:07:11.839289Z","end":"2026-09-17T18:07:12.5166Z","steps":["trace[826708153] 'agreement among raft nodes before linearized reading' (duration: 676.987656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.516711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:12.192942Z","time spent":"323.699494ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:07:12.516426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.469691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-09-17T18:07:12.516742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:11.839268Z","time spent":"677.4601ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-17T18:07:12.516818Z","caller":"traceutil/trace.go:171","msg":"trace[1005344290] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18668; }","duration":"446.893383ms","start":"2026-09-17T18:07:12.069902Z","end":"2026-09-17T18:07:12.516795Z","steps":["trace[1005344290] 'agreement among raft nodes before linearized reading' (duration: 446.371848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.516879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:12.069883Z","time spent":"446.979905ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":727,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-09-17T18:07:12.516871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.332276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-17T18:07:12.516511Z","caller":"traceutil/trace.go:171","msg":"trace[751917710] transaction","detail":"{read_only:false; response_revision:18667; number_of_response:1; }","duration":"411.256967ms","start":"2026-09-17T18:07:12.105212Z","end":"2026-09-17T18:07:12.516469Z","steps":["trace[751917710] 'process raft request' (duration: 362.885306ms)","trace[751917710] 'compare' (duration: 47.781774ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:07:12.516862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.549904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-09-17T18:07:12.516928Z","caller":"traceutil/trace.go:171","msg":"trace[1422511225] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18668; }","duration":"444.418379ms","start":"2026-09-17T18:07:12.072492Z","end":"2026-09-17T18:07:12.516911Z","steps":["trace[1422511225] 'agreement among raft nodes before linearized reading' (duration: 444.294015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.516976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:12.072478Z","time spent":"444.487001ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":727,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-09-17T18:07:12.516985Z","caller":"traceutil/trace.go:171","msg":"trace[1134138490] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:18668; }","duration":"413.687608ms","start":"2026-09-17T18:07:12.10327Z","end":"2026-09-17T18:07:12.516958Z","steps":["trace[1134138490] 'agreement among raft nodes before linearized reading' (duration: 413.4032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.517065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:12.105197Z","time spent":"411.751822ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:07:12.517049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:07:12.103249Z","time spent":"413.784443ms","remote":"127.0.0.1:37540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1960,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-09-17T18:07:12.517137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.017949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-s4w7s\" ","response":"range_response_count:1 size:20787"} {"level":"info","ts":"2026-09-17T18:07:12.517258Z","caller":"traceutil/trace.go:171","msg":"trace[765255650] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-s4w7s; range_end:; response_count:1; response_revision:18668; }","duration":"211.143563ms","start":"2026-09-17T18:07:12.306066Z","end":"2026-09-17T18:07:12.517209Z","steps":["trace[765255650] 'agreement among raft nodes before linearized reading' (duration: 210.896215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:07:12.518015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.857502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:940872"} {"level":"info","ts":"2026-09-17T18:07:12.518111Z","caller":"traceutil/trace.go:171","msg":"trace[1749652317] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18668; }","duration":"212.977997ms","start":"2026-09-17T18:07:12.305094Z","end":"2026-09-17T18:07:12.518072Z","steps":["trace[1749652317] 'agreement among raft nodes before linearized reading' (duration: 211.316698ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:14.744831Z","caller":"traceutil/trace.go:171","msg":"trace[2081316716] linearizableReadLoop","detail":"{readStateIndex:19335; appliedIndex:19334; }","duration":"113.998036ms","start":"2026-09-17T18:07:14.630803Z","end":"2026-09-17T18:07:14.744801Z","steps":["trace[2081316716] 'read index received' (duration: 98.198807ms)","trace[2081316716] 'applied index is now lower than readState.Index' (duration: 15.797939ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:07:14.744925Z","caller":"traceutil/trace.go:171","msg":"trace[759981326] transaction","detail":"{read_only:false; response_revision:18692; number_of_response:1; }","duration":"127.240778ms","start":"2026-09-17T18:07:14.617654Z","end":"2026-09-17T18:07:14.744895Z","steps":["trace[759981326] 'process raft request' (duration: 111.377117ms)","trace[759981326] 'compare' (duration: 15.646694ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:07:14.74512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.290025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-17T18:07:14.745145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.413938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-17T18:07:14.745185Z","caller":"traceutil/trace.go:171","msg":"trace[1087313058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18692; }","duration":"113.497711ms","start":"2026-09-17T18:07:14.631676Z","end":"2026-09-17T18:07:14.745174Z","steps":["trace[1087313058] 'agreement among raft nodes before linearized reading' (duration: 113.384797ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:14.745178Z","caller":"traceutil/trace.go:171","msg":"trace[415739629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18692; }","duration":"114.403118ms","start":"2026-09-17T18:07:14.630756Z","end":"2026-09-17T18:07:14.745159Z","steps":["trace[415739629] 'agreement among raft nodes before linearized reading' (duration: 114.217043ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:07:26.666047Z","caller":"traceutil/trace.go:171","msg":"trace[543853897] transaction","detail":"{read_only:false; response_revision:18893; number_of_response:1; }","duration":"106.276137ms","start":"2026-09-17T18:07:26.55973Z","end":"2026-09-17T18:07:26.666006Z","steps":["trace[543853897] 'process raft request' (duration: 86.230504ms)","trace[543853897] 'compare' (duration: 19.752325ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:08:07.094267Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-17T18:08:07.101387Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-17T18:08:07.101481Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-17T18:08:56.744512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17176} {"level":"info","ts":"2026-09-17T18:08:56.846093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17176,"took":"98.262559ms","hash":3260175771,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":34037760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-17T18:08:56.846236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3260175771,"revision":17176,"compact-revision":14617} {"level":"info","ts":"2026-09-17T18:13:10.771355Z","caller":"traceutil/trace.go:171","msg":"trace[1901497867] transaction","detail":"{read_only:false; response_revision:21433; number_of_response:1; }","duration":"209.627175ms","start":"2026-09-17T18:13:10.561702Z","end":"2026-09-17T18:13:10.771329Z","steps":["trace[1901497867] 'process raft request' (duration: 156.491304ms)","trace[1901497867] 'compare' (duration: 52.849343ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:13:10.907511Z","caller":"traceutil/trace.go:171","msg":"trace[2034675283] linearizableReadLoop","detail":"{readStateIndex:22166; appliedIndex:22164; }","duration":"316.710512ms","start":"2026-09-17T18:13:10.590772Z","end":"2026-09-17T18:13:10.907483Z","steps":["trace[2034675283] 'read index received' (duration: 127.363625ms)","trace[2034675283] 'applied index is now lower than readState.Index' (duration: 189.345857ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:13:10.907929Z","caller":"traceutil/trace.go:171","msg":"trace[186008693] transaction","detail":"{read_only:false; response_revision:21434; number_of_response:1; }","duration":"339.970739ms","start":"2026-09-17T18:13:10.567928Z","end":"2026-09-17T18:13:10.907899Z","steps":["trace[186008693] 'process raft request' (duration: 312.796346ms)","trace[186008693] 'compare' (duration: 26.582375ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:13:10.90799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.201976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-17T18:13:10.908038Z","caller":"traceutil/trace.go:171","msg":"trace[216857864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21435; }","duration":"317.292939ms","start":"2026-09-17T18:13:10.59073Z","end":"2026-09-17T18:13:10.908023Z","steps":["trace[216857864] 'agreement among raft nodes before linearized reading' (duration: 317.106534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:10.908037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.774388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-17T18:13:10.908043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.655756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-17T18:13:10.908061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:10.567911Z","time spent":"340.087162ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:13:10.908068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:10.590714Z","time spent":"317.346621ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-17T18:13:10.908064Z","caller":"traceutil/trace.go:171","msg":"trace[424723342] transaction","detail":"{read_only:false; response_revision:21435; number_of_response:1; }","duration":"290.647552ms","start":"2026-09-17T18:13:10.617378Z","end":"2026-09-17T18:13:10.908025Z","steps":["trace[424723342] 'process raft request' (duration: 290.040305ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:13:10.908103Z","caller":"traceutil/trace.go:171","msg":"trace[564112722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21435; }","duration":"303.733198ms","start":"2026-09-17T18:13:10.604349Z","end":"2026-09-17T18:13:10.908083Z","steps":["trace[564112722] 'agreement among raft nodes before linearized reading' (duration: 303.561703ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:13:10.908088Z","caller":"traceutil/trace.go:171","msg":"trace[943086743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21435; }","duration":"217.86397ms","start":"2026-09-17T18:13:10.690208Z","end":"2026-09-17T18:13:10.908072Z","steps":["trace[943086743] 'agreement among raft nodes before linearized reading' (duration: 217.773178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:10.90815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:10.604337Z","time spent":"303.80001ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-17T18:13:26.924248Z","caller":"traceutil/trace.go:171","msg":"trace[34012014] transaction","detail":"{read_only:false; response_revision:21513; number_of_response:1; }","duration":"265.722065ms","start":"2026-09-17T18:13:26.658497Z","end":"2026-09-17T18:13:26.924219Z","steps":["trace[34012014] 'process raft request' (duration: 265.537149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.21718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.161369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T18:13:27.217375Z","caller":"traceutil/trace.go:171","msg":"trace[6138885] transaction","detail":"{read_only:false; response_revision:21516; number_of_response:1; }","duration":"253.667768ms","start":"2026-09-17T18:13:26.963694Z","end":"2026-09-17T18:13:27.217361Z","steps":["trace[6138885] 'process raft request' (duration: 253.629407ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:13:27.217442Z","caller":"traceutil/trace.go:171","msg":"trace[1661224906] transaction","detail":"{read_only:false; response_revision:21514; number_of_response:1; }","duration":"551.354736ms","start":"2026-09-17T18:13:26.666057Z","end":"2026-09-17T18:13:27.217411Z","steps":["trace[1661224906] 'process raft request' (duration: 358.876898ms)","trace[1661224906] 'compare' (duration: 192.023785ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:13:27.217486Z","caller":"traceutil/trace.go:171","msg":"trace[1786165339] transaction","detail":"{read_only:false; response_revision:21515; number_of_response:1; }","duration":"376.935881ms","start":"2026-09-17T18:13:26.840514Z","end":"2026-09-17T18:13:27.21745Z","steps":["trace[1786165339] 'process raft request' (duration: 376.756026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.217592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:26.666033Z","time spent":"551.483299ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T18:13:27.217679Z","caller":"traceutil/trace.go:171","msg":"trace[1238677528] linearizableReadLoop","detail":"{readStateIndex:22249; appliedIndex:22248; }","duration":"525.113951ms","start":"2026-09-17T18:13:26.692439Z","end":"2026-09-17T18:13:27.217553Z","steps":["trace[1238677528] 'read index received' (duration: 231.932766ms)","trace[1238677528] 'applied index is now lower than readState.Index' (duration: 293.124294ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:13:27.217755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:26.840494Z","time spent":"377.139027ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:13:27.217804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.348659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T18:13:27.217945Z","caller":"traceutil/trace.go:171","msg":"trace[1693398658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21516; }","duration":"525.541554ms","start":"2026-09-17T18:13:26.692392Z","end":"2026-09-17T18:13:27.217933Z","steps":["trace[1693398658] 'agreement among raft nodes before linearized reading' (duration: 525.301217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.217987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:26.692373Z","time spent":"525.603437ms","remote":"127.0.0.1:37376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T18:13:27.218132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.874063ms","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-09-17T18:13:27.218157Z","caller":"traceutil/trace.go:171","msg":"trace[659182432] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21516; }","duration":"142.928105ms","start":"2026-09-17T18:13:27.07522Z","end":"2026-09-17T18:13:27.218148Z","steps":["trace[659182432] 'agreement among raft nodes before linearized reading' (duration: 142.819581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.218261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.045385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-17T18:13:27.218302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.685136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-17T18:13:27.218369Z","caller":"traceutil/trace.go:171","msg":"trace[793696290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21516; }","duration":"218.21169ms","start":"2026-09-17T18:13:27.000131Z","end":"2026-09-17T18:13:27.218342Z","steps":["trace[793696290] 'agreement among raft nodes before linearized reading' (duration: 217.900261ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:13:27.218374Z","caller":"traceutil/trace.go:171","msg":"trace[1295196435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21516; }","duration":"212.80024ms","start":"2026-09-17T18:13:27.005561Z","end":"2026-09-17T18:13:27.218362Z","steps":["trace[1295196435] 'agreement among raft nodes before linearized reading' (duration: 212.515722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.218407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.069301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-17T18:13:27.21855Z","caller":"traceutil/trace.go:171","msg":"trace[1739775767] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:21516; }","duration":"184.240715ms","start":"2026-09-17T18:13:27.034292Z","end":"2026-09-17T18:13:27.218533Z","steps":["trace[1739775767] 'agreement among raft nodes before linearized reading' (duration: 183.71361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.218638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.437552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-17T18:13:27.218703Z","caller":"traceutil/trace.go:171","msg":"trace[1246586969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21516; }","duration":"205.546955ms","start":"2026-09-17T18:13:27.013146Z","end":"2026-09-17T18:13:27.218693Z","steps":["trace[1246586969] 'agreement among raft nodes before linearized reading' (duration: 205.388471ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:13:27.58307Z","caller":"traceutil/trace.go:171","msg":"trace[539788302] transaction","detail":"{read_only:false; response_revision:21518; number_of_response:1; }","duration":"360.159026ms","start":"2026-09-17T18:13:27.222886Z","end":"2026-09-17T18:13:27.583045Z","steps":["trace[539788302] 'process raft request' (duration: 359.939509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.583247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:27.22287Z","time spent":"360.271109ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T18:13:27.583444Z","caller":"traceutil/trace.go:171","msg":"trace[2009393260] linearizableReadLoop","detail":"{readStateIndex:22254; appliedIndex:22251; }","duration":"360.171865ms","start":"2026-09-17T18:13:27.223244Z","end":"2026-09-17T18:13:27.583416Z","steps":["trace[2009393260] 'read index received' (duration: 78.565302ms)","trace[2009393260] 'applied index is now lower than readState.Index' (duration: 281.605333ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:13:27.583541Z","caller":"traceutil/trace.go:171","msg":"trace[1159127353] transaction","detail":"{read_only:false; response_revision:21519; number_of_response:1; }","duration":"360.332962ms","start":"2026-09-17T18:13:27.223196Z","end":"2026-09-17T18:13:27.583529Z","steps":["trace[1159127353] 'process raft request' (duration: 359.793385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.583613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:27.223153Z","time spent":"360.426494ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:13:27.583683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.424924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2c933164-a253-4eb6-872a-24e904739858\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-09-17T18:13:27.583772Z","caller":"traceutil/trace.go:171","msg":"trace[393149898] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2c933164-a253-4eb6-872a-24e904739858; range_end:; response_count:1; response_revision:21519; }","duration":"360.527007ms","start":"2026-09-17T18:13:27.223199Z","end":"2026-09-17T18:13:27.583726Z","steps":["trace[393149898] 'agreement among raft nodes before linearized reading' (duration: 360.352572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.583839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:27.223161Z","time spent":"360.667091ms","remote":"127.0.0.1:37526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1343,"request content":"key:\"/registry/persistentvolumes/pvc-2c933164-a253-4eb6-872a-24e904739858\" "} {"level":"warn","ts":"2026-09-17T18:13:27.583844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.478094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19410"} {"level":"info","ts":"2026-09-17T18:13:27.58397Z","caller":"traceutil/trace.go:171","msg":"trace[1993211171] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21519; }","duration":"360.628379ms","start":"2026-09-17T18:13:27.223326Z","end":"2026-09-17T18:13:27.583955Z","steps":["trace[1993211171] 'agreement among raft nodes before linearized reading' (duration: 360.226877ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:27.584016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:27.223311Z","time spent":"360.6923ms","remote":"127.0.0.1:37564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19434,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-17T18:13:27.777611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.784099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T18:13:27.777681Z","caller":"traceutil/trace.go:171","msg":"trace[2343494] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21519; }","duration":"122.908352ms","start":"2026-09-17T18:13:27.654756Z","end":"2026-09-17T18:13:27.777665Z","steps":["trace[2343494] 'range keys from in-memory index tree' (duration: 122.665925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:28.155844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.214624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-17T18:13:28.155966Z","caller":"traceutil/trace.go:171","msg":"trace[457878931] linearizableReadLoop","detail":"{readStateIndex:22256; appliedIndex:22254; }","duration":"375.219801ms","start":"2026-09-17T18:13:27.780729Z","end":"2026-09-17T18:13:28.155949Z","steps":["trace[457878931] 'read index received' (duration: 13.752236ms)","trace[457878931] 'applied index is now lower than readState.Index' (duration: 361.465485ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T18:13:28.156018Z","caller":"traceutil/trace.go:171","msg":"trace[1872032156] transaction","detail":"{read_only:false; response_revision:21521; number_of_response:1; }","duration":"398.238662ms","start":"2026-09-17T18:13:27.757757Z","end":"2026-09-17T18:13:28.155996Z","steps":["trace[1872032156] 'process raft request' (duration: 189.76912ms)","trace[1872032156] 'compare' (duration: 207.972986ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T18:13:28.156075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.338865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-17T18:13:28.156106Z","caller":"traceutil/trace.go:171","msg":"trace[458788584] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21521; }","duration":"375.406276ms","start":"2026-09-17T18:13:27.780688Z","end":"2026-09-17T18:13:28.156094Z","steps":["trace[458788584] 'agreement among raft nodes before linearized reading' (duration: 375.329224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T18:13:28.156119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:27.757733Z","time spent":"398.328834ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T18:13:28.156151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:13:27.780669Z","time spent":"375.473638ms","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-17T18:13:28.159518Z","caller":"traceutil/trace.go:171","msg":"trace[992309841] transaction","detail":"{read_only:false; response_revision:21522; number_of_response:1; }","duration":"180.087092ms","start":"2026-09-17T18:13:27.979421Z","end":"2026-09-17T18:13:28.159508Z","steps":["trace[992309841] 'process raft request' (duration: 179.866446ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:13:56.753918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19707} {"level":"info","ts":"2026-09-17T18:13:56.883873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19707,"took":"126.988563ms","hash":824838036,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":29089792,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-17T18:13:56.883943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":824838036,"revision":19707,"compact-revision":17176} {"level":"info","ts":"2026-09-17T18:18:40.554081Z","caller":"traceutil/trace.go:171","msg":"trace[1061405402] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"115.418287ms","start":"2026-09-17T18:18:40.438635Z","end":"2026-09-17T18:18:40.554053Z","steps":["trace[1061405402] 'process raft request' (duration: 115.292432ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:18:40.585338Z","caller":"traceutil/trace.go:171","msg":"trace[591436173] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"146.635561ms","start":"2026-09-17T18:18:40.438672Z","end":"2026-09-17T18:18:40.585308Z","steps":["trace[591436173] 'process raft request' (duration: 146.374613ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:18:40.585564Z","caller":"traceutil/trace.go:171","msg":"trace[1666613989] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"119.088065ms","start":"2026-09-17T18:18:40.46646Z","end":"2026-09-17T18:18:40.585548Z","steps":["trace[1666613989] 'process raft request' (duration: 118.794866ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T18:18:56.760842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21700} {"level":"info","ts":"2026-09-17T18:18:56.8836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21700,"took":"117.648682ms","hash":2344334340,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":43556864,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-17T18:18:56.88369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2344334340,"revision":21700,"compact-revision":19707} {"level":"info","ts":"2026-09-17T18:23:56.770924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24963} {"level":"info","ts":"2026-09-17T18:23:56.918793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24963,"took":"143.302131ms","hash":2491623370,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":31268864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-17T18:23:56.918854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2491623370,"revision":24963,"compact-revision":21700} {"level":"info","ts":"2026-09-17T18:25:10.740408Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000700a.wal"}