{"level":"warn","ts":"2026-09-09T06:07:50.115485Z","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-09T06:07:50.115576Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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-09T06:07:50.115653Z","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-09T06:07:50.115666Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-09T06:07:50.115688Z","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-09T06:07:50.116539Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-09-09T06:07:50.116646Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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-09T06:07:50.12237Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.476367ms"} {"level":"info","ts":"2026-09-09T06:07:50.128831Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-09-09T06:07:50.128889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:07:50.128905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-09-09T06:07:50.128918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:07:50.128927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-09-09T06:07:50.128968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-09-09T06:07:50.136097Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:07:50.138587Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:07:50.13994Z","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-09T06:07:50.141864Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:07:50.142137Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T06:07:50.142423Z","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-09T06:07:50.142498Z","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-09T06:07:50.142509Z","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-09T06:07:50.144312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-09-09T06:07:50.144563Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-09T06:07:50.14556Z","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-09T06:07:50.14571Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-09T06:07:50.145754Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-09T06:07:50.145855Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:07:50.145888Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:07:50.42957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:07:50.429705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:07:50.429853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-09-09T06:07:50.429885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:07:50.429945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-09T06:07:50.429971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-09-09T06:07:50.429985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-09T06:07:50.432049Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:07:50.432187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:07:50.432244Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:07:50.432692Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:07:50.435573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T06:07:50.435658Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-09-09T06:07:50.436331Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:07:50.436421Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:07:50.441063Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:07:50.441213Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:07:50.44131Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:08:33.945007Z","caller":"traceutil/trace.go:171","msg":"trace[1595339915] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"279.060139ms","start":"2026-09-09T06:08:33.665917Z","end":"2026-09-09T06:08:33.944977Z","steps":["trace[1595339915] 'process raft request' (duration: 278.857835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:33.945128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.306995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:08:33.945011Z","caller":"traceutil/trace.go:171","msg":"trace[1443460407] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:641; }","duration":"159.205462ms","start":"2026-09-09T06:08:33.785782Z","end":"2026-09-09T06:08:33.944987Z","steps":["trace[1443460407] 'read index received' (duration: 159.196592ms)","trace[1443460407] 'applied index is now lower than readState.Index' (duration: 7.2µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:08:33.945244Z","caller":"traceutil/trace.go:171","msg":"trace[135332603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"159.501549ms","start":"2026-09-09T06:08:33.785727Z","end":"2026-09-09T06:08:33.945229Z","steps":["trace[135332603] 'agreement among raft nodes before linearized reading' (duration: 159.288494ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:34.242169Z","caller":"traceutil/trace.go:171","msg":"trace[1655673280] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"296.858022ms","start":"2026-09-09T06:08:33.945276Z","end":"2026-09-09T06:08:34.242135Z","steps":["trace[1655673280] 'read index received' (duration: 231.062962ms)","trace[1655673280] 'applied index is now lower than readState.Index' (duration: 65.79389ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:08:34.242273Z","caller":"traceutil/trace.go:171","msg":"trace[1399971595] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"346.806876ms","start":"2026-09-09T06:08:33.895402Z","end":"2026-09-09T06:08:34.242208Z","steps":["trace[1399971595] 'process raft request' (duration: 281.936348ms)","trace[1399971595] 'compare' (duration: 63.779321ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:08:34.242615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.108694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:08:34.242649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.449809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-09T06:08:34.2427Z","caller":"traceutil/trace.go:171","msg":"trace[1675077997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"296.268917ms","start":"2026-09-09T06:08:33.946403Z","end":"2026-09-09T06:08:34.242672Z","steps":["trace[1675077997] 'agreement among raft nodes before linearized reading' (duration: 296.093072ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:34.242699Z","caller":"traceutil/trace.go:171","msg":"trace[1495530712] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:623; }","duration":"322.587983ms","start":"2026-09-09T06:08:33.920097Z","end":"2026-09-09T06:08:34.242685Z","steps":["trace[1495530712] 'agreement among raft nodes before linearized reading' (duration: 322.451569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:34.243014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:33.895366Z","time spent":"346.98993ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:08:34.243068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:33.920069Z","time spent":"322.846329ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.93\" "} {"level":"warn","ts":"2026-09-09T06:08:34.615969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.461218ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-09-09T06:08:34.61629Z","caller":"traceutil/trace.go:171","msg":"trace[2025382469] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"190.459693ms","start":"2026-09-09T06:08:34.425811Z","end":"2026-09-09T06:08:34.616271Z","steps":["trace[2025382469] 'read index received' (duration: 82.132748ms)","trace[2025382469] 'applied index is now lower than readState.Index' (duration: 108.325535ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:08:34.616363Z","caller":"traceutil/trace.go:171","msg":"trace[435544272] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"294.694738ms","start":"2026-09-09T06:08:34.321612Z","end":"2026-09-09T06:08:34.616307Z","steps":["trace[435544272] 'process raft request' (duration: 294.508184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:34.616436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:34.244302Z","time spent":"372.125572ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-09T06:08:34.616793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.981875ms","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-09T06:08:34.616842Z","caller":"traceutil/trace.go:171","msg":"trace[1580732896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"191.075127ms","start":"2026-09-09T06:08:34.425754Z","end":"2026-09-09T06:08:34.616829Z","steps":["trace[1580732896] 'agreement among raft nodes before linearized reading' (duration: 190.692718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:34.616949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.312594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-09T06:08:34.616949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.347868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-09T06:08:34.617057Z","caller":"traceutil/trace.go:171","msg":"trace[1751314252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"131.479389ms","start":"2026-09-09T06:08:34.485545Z","end":"2026-09-09T06:08:34.617025Z","steps":["trace[1751314252] 'agreement among raft nodes before linearized reading' (duration: 131.123019ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:34.617059Z","caller":"traceutil/trace.go:171","msg":"trace[609488421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"141.531203ms","start":"2026-09-09T06:08:34.475505Z","end":"2026-09-09T06:08:34.617036Z","steps":["trace[609488421] 'agreement among raft nodes before linearized reading' (duration: 141.082661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:35.186751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.799155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:08:35.187154Z","caller":"traceutil/trace.go:171","msg":"trace[1605211344] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"565.163013ms","start":"2026-09-09T06:08:34.621966Z","end":"2026-09-09T06:08:35.187129Z","steps":["trace[1605211344] 'process raft request' (duration: 564.975539ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:35.187143Z","caller":"traceutil/trace.go:171","msg":"trace[850391869] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"569.384616ms","start":"2026-09-09T06:08:34.617703Z","end":"2026-09-09T06:08:35.187088Z","steps":["trace[850391869] 'process raft request' (duration: 210.120951ms)","trace[850391869] 'compare' (duration: 358.641361ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:08:35.18733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:34.621934Z","time spent":"565.272775ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:08:35.187411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:34.617679Z","time spent":"569.59046ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:08:35.187558Z","caller":"traceutil/trace.go:171","msg":"trace[571590715] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:644; }","duration":"558.226194ms","start":"2026-09-09T06:08:34.629299Z","end":"2026-09-09T06:08:35.187526Z","steps":["trace[571590715] 'read index received' (duration: 198.543228ms)","trace[571590715] 'applied index is now lower than readState.Index' (duration: 359.681946ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:08:35.187657Z","caller":"traceutil/trace.go:171","msg":"trace[1370068435] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"564.352273ms","start":"2026-09-09T06:08:34.623279Z","end":"2026-09-09T06:08:35.187631Z","steps":["trace[1370068435] 'process raft request' (duration: 563.80394ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:35.187758Z","caller":"traceutil/trace.go:171","msg":"trace[888781183] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"562.034027ms","start":"2026-09-09T06:08:34.625708Z","end":"2026-09-09T06:08:35.187742Z","steps":["trace[888781183] 'process raft request' (duration: 561.657098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:35.187806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:34.623263Z","time spent":"564.466176ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:08:35.187875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.786189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:08:35.187934Z","caller":"traceutil/trace.go:171","msg":"trace[1177602540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"401.84832ms","start":"2026-09-09T06:08:34.786071Z","end":"2026-09-09T06:08:35.18792Z","steps":["trace[1177602540] 'agreement among raft nodes before linearized reading' (duration: 401.747088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:35.187962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.660024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:08:35.187974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:34.786007Z","time spent":"401.960002ms","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:08:35.188Z","caller":"traceutil/trace.go:171","msg":"trace[2116158285] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:628; }","duration":"558.719546ms","start":"2026-09-09T06:08:34.629266Z","end":"2026-09-09T06:08:35.187986Z","steps":["trace[2116158285] 'agreement among raft nodes before linearized reading' (duration: 558.663274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:35.187908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:34.625688Z","time spent":"562.129109ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:08:35.18803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:34.629252Z","time spent":"558.771426ms","remote":"127.0.0.1:48048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-09-09T06:08:35.188156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.345441ms","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-09T06:08:35.188187Z","caller":"traceutil/trace.go:171","msg":"trace[1602312166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"395.406793ms","start":"2026-09-09T06:08:34.792771Z","end":"2026-09-09T06:08:35.188178Z","steps":["trace[1602312166] 'agreement among raft nodes before linearized reading' (duration: 395.299071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:35.188208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:34.792762Z","time spent":"395.441054ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T06:08:51.598687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.870545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-09T06:08:51.598721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.606462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-09T06:08:51.598762Z","caller":"traceutil/trace.go:171","msg":"trace[48330497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"325.974036ms","start":"2026-09-09T06:08:51.272772Z","end":"2026-09-09T06:08:51.598746Z","steps":["trace[48330497] 'range keys from in-memory index tree' (duration: 325.695921ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:51.598781Z","caller":"traceutil/trace.go:171","msg":"trace[53928885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"340.670394ms","start":"2026-09-09T06:08:51.258095Z","end":"2026-09-09T06:08:51.598765Z","steps":["trace[53928885] 'range keys from in-memory index tree' (duration: 340.471099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:51.598795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:51.272761Z","time spent":"326.025397ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T06:08:51.598821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:51.258021Z","time spent":"340.789837ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T06:08:51.598983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.84914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-09-09T06:08:51.599021Z","caller":"traceutil/trace.go:171","msg":"trace[1883109251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"328.911051ms","start":"2026-09-09T06:08:51.270097Z","end":"2026-09-09T06:08:51.599008Z","steps":["trace[1883109251] 'range keys from in-memory index tree' (duration: 328.744077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:51.599053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:51.270087Z","time spent":"328.957691ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T06:08:51.5999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.731378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-09T06:08:51.600008Z","caller":"traceutil/trace.go:171","msg":"trace[1717794691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"336.74284ms","start":"2026-09-09T06:08:51.263243Z","end":"2026-09-09T06:08:51.599985Z","steps":["trace[1717794691] 'range keys from in-memory index tree' (duration: 335.589155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:08:51.600066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:08:51.263232Z","time spent":"336.819521ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T06:10:13.653898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.881699ms","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-09T06:10:13.654146Z","caller":"traceutil/trace.go:171","msg":"trace[1802756750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1905; }","duration":"322.173102ms","start":"2026-09-09T06:10:13.331954Z","end":"2026-09-09T06:10:13.654127Z","steps":["trace[1802756750] 'range keys from in-memory index tree' (duration: 321.706556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:13.654268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:10:13.331936Z","time spent":"322.316865ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-09T06:10:13.653915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.36425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-911755fd-bc81-4f72-94c6-1898e0fa57aa\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-09T06:10:13.654497Z","caller":"traceutil/trace.go:171","msg":"trace[59782703] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-911755fd-bc81-4f72-94c6-1898e0fa57aa; range_end:; response_count:1; response_revision:1905; }","duration":"317.99838ms","start":"2026-09-09T06:10:13.336469Z","end":"2026-09-09T06:10:13.654467Z","steps":["trace[59782703] 'range keys from in-memory index tree' (duration: 317.170887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:13.653942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.731218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d3922fddc9b747\" ","response":"range_response_count:1 size:982"} {"level":"warn","ts":"2026-09-09T06:10:13.654594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:10:13.336445Z","time spent":"318.130011ms","remote":"127.0.0.1:47898","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-911755fd-bc81-4f72-94c6-1898e0fa57aa\" "} {"level":"info","ts":"2026-09-09T06:10:13.654771Z","caller":"traceutil/trace.go:171","msg":"trace[21470565] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d3922fddc9b747; range_end:; response_count:1; response_revision:1905; }","duration":"221.53123ms","start":"2026-09-09T06:10:13.433139Z","end":"2026-09-09T06:10:13.65467Z","steps":["trace[21470565] 'range keys from in-memory index tree' (duration: 220.18025ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:10:13.699449Z","caller":"traceutil/trace.go:171","msg":"trace[811017930] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"176.5591ms","start":"2026-09-09T06:10:13.52287Z","end":"2026-09-09T06:10:13.699429Z","steps":["trace[811017930] 'read index received' (duration: 176.485699ms)","trace[811017930] 'applied index is now lower than readState.Index' (duration: 72.411µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:10:13.699518Z","caller":"traceutil/trace.go:171","msg":"trace[4701580] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"230.07192ms","start":"2026-09-09T06:10:13.46939Z","end":"2026-09-09T06:10:13.699462Z","steps":["trace[4701580] 'process raft request' (duration: 229.918858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:13.69973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.851064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-09T06:10:13.699781Z","caller":"traceutil/trace.go:171","msg":"trace[1614228371] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1906; }","duration":"176.953095ms","start":"2026-09-09T06:10:13.522813Z","end":"2026-09-09T06:10:13.699766Z","steps":["trace[1614228371] 'agreement among raft nodes before linearized reading' (duration: 176.696312ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:10:25.519432Z","caller":"traceutil/trace.go:171","msg":"trace[149674306] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"344.609926ms","start":"2026-09-09T06:10:25.174792Z","end":"2026-09-09T06:10:25.519402Z","steps":["trace[149674306] 'process raft request' (duration: 344.466184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:25.519651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:10:25.174766Z","time spent":"344.779049ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:10:46.614664Z","caller":"traceutil/trace.go:171","msg":"trace[854451283] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"106.988282ms","start":"2026-09-09T06:10:46.507648Z","end":"2026-09-09T06:10:46.614637Z","steps":["trace[854451283] 'process raft request' (duration: 94.314683ms)","trace[854451283] 'compare' (duration: 12.467857ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:10:47.77941Z","caller":"traceutil/trace.go:171","msg":"trace[749209497] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"186.562051ms","start":"2026-09-09T06:10:47.592818Z","end":"2026-09-09T06:10:47.77938Z","steps":["trace[749209497] 'process raft request' (duration: 186.41494ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:10:48.017807Z","caller":"traceutil/trace.go:171","msg":"trace[968063188] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"112.646729ms","start":"2026-09-09T06:10:47.905133Z","end":"2026-09-09T06:10:48.01778Z","steps":["trace[968063188] 'read index received' (duration: 112.392225ms)","trace[968063188] 'applied index is now lower than readState.Index' (duration: 253.434µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:10:48.017867Z","caller":"traceutil/trace.go:171","msg":"trace[553545625] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"154.204375ms","start":"2026-09-09T06:10:47.863637Z","end":"2026-09-09T06:10:48.017841Z","steps":["trace[553545625] 'process raft request' (duration: 154.002502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:48.018047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.894002ms","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-09T06:10:48.0182Z","caller":"traceutil/trace.go:171","msg":"trace[1798068389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2146; }","duration":"113.061065ms","start":"2026-09-09T06:10:47.905086Z","end":"2026-09-09T06:10:48.018147Z","steps":["trace[1798068389] 'agreement among raft nodes before linearized reading' (duration: 112.810432ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:10:48.489901Z","caller":"traceutil/trace.go:171","msg":"trace[1003248561] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"121.785915ms","start":"2026-09-09T06:10:48.368081Z","end":"2026-09-09T06:10:48.489867Z","steps":["trace[1003248561] 'process raft request' (duration: 121.619693ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:35.373309Z","caller":"traceutil/trace.go:171","msg":"trace[1149187455] linearizableReadLoop","detail":"{readStateIndex:2926; appliedIndex:2925; }","duration":"116.474511ms","start":"2026-09-09T06:12:35.256819Z","end":"2026-09-09T06:12:35.373293Z","steps":["trace[1149187455] 'read index received' (duration: 59.793742ms)","trace[1149187455] 'applied index is now lower than readState.Index' (duration: 56.679909ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:12:35.373453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.629136ms","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-09T06:12:35.373475Z","caller":"traceutil/trace.go:171","msg":"trace[187244781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2827; }","duration":"116.690726ms","start":"2026-09-09T06:12:35.256777Z","end":"2026-09-09T06:12:35.373467Z","steps":["trace[187244781] 'agreement among raft nodes before linearized reading' (duration: 116.610675ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:35.373442Z","caller":"traceutil/trace.go:171","msg":"trace[959211576] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"161.228029ms","start":"2026-09-09T06:12:35.212153Z","end":"2026-09-09T06:12:35.373381Z","steps":["trace[959211576] 'process raft request' (duration: 104.487699ms)","trace[959211576] 'compare' (duration: 56.443644ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:12:40.8265Z","caller":"traceutil/trace.go:171","msg":"trace[216322557] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"109.511606ms","start":"2026-09-09T06:12:40.71696Z","end":"2026-09-09T06:12:40.826472Z","steps":["trace[216322557] 'process raft request' (duration: 109.374722ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:15.461108Z","caller":"traceutil/trace.go:171","msg":"trace[495134491] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5193; }","duration":"196.974335ms","start":"2026-09-09T06:17:15.264111Z","end":"2026-09-09T06:17:15.461085Z","steps":["trace[495134491] 'read index received' (duration: 15.48994ms)","trace[495134491] 'applied index is now lower than readState.Index' (duration: 181.483515ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:15.46118Z","caller":"traceutil/trace.go:171","msg":"trace[592583335] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"197.962391ms","start":"2026-09-09T06:17:15.263191Z","end":"2026-09-09T06:17:15.461153Z","steps":["trace[592583335] 'process raft request' (duration: 197.736027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:15.461234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.105408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:17:15.461264Z","caller":"traceutil/trace.go:171","msg":"trace[221241279] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5009; }","duration":"197.18822ms","start":"2026-09-09T06:17:15.264067Z","end":"2026-09-09T06:17:15.461255Z","steps":["trace[221241279] 'agreement among raft nodes before linearized reading' (duration: 197.120089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:15.475914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.328858ms","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:1935"} {"level":"info","ts":"2026-09-09T06:17:15.475972Z","caller":"traceutil/trace.go:171","msg":"trace[550344209] 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:5010; }","duration":"157.395479ms","start":"2026-09-09T06:17:15.318563Z","end":"2026-09-09T06:17:15.475958Z","steps":["trace[550344209] 'agreement among raft nodes before linearized reading' (duration: 157.258587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:15.476274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.980469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:17:15.476558Z","caller":"traceutil/trace.go:171","msg":"trace[426323575] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:5010; }","duration":"185.340824ms","start":"2026-09-09T06:17:15.291187Z","end":"2026-09-09T06:17:15.476528Z","steps":["trace[426323575] 'agreement among raft nodes before linearized reading' (duration: 184.765405ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:15.675946Z","caller":"traceutil/trace.go:171","msg":"trace[1294540150] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"206.999994ms","start":"2026-09-09T06:17:15.468918Z","end":"2026-09-09T06:17:15.675918Z","steps":["trace[1294540150] 'process raft request' (duration: 206.947864ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:15.676198Z","caller":"traceutil/trace.go:171","msg":"trace[516736764] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5200; }","duration":"200.66397ms","start":"2026-09-09T06:17:15.475509Z","end":"2026-09-09T06:17:15.676172Z","steps":["trace[516736764] 'read index received' (duration: 200.65429ms)","trace[516736764] 'applied index is now lower than readState.Index' (duration: 7.94µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:17:15.676483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.12657ms","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-09T06:17:15.676497Z","caller":"traceutil/trace.go:171","msg":"trace[1538938956] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"209.628502ms","start":"2026-09-09T06:17:15.466853Z","end":"2026-09-09T06:17:15.676481Z","steps":["trace[1538938956] 'process raft request' (duration: 208.673168ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:15.67652Z","caller":"traceutil/trace.go:171","msg":"trace[373829173] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5014; }","duration":"212.21195ms","start":"2026-09-09T06:17:15.464298Z","end":"2026-09-09T06:17:15.67651Z","steps":["trace[373829173] 'agreement among raft nodes before linearized reading' (duration: 211.987647ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:15.676843Z","caller":"traceutil/trace.go:171","msg":"trace[292270787] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"209.266029ms","start":"2026-09-09T06:17:15.467562Z","end":"2026-09-09T06:17:15.676828Z","steps":["trace[292270787] 'process raft request' (duration: 208.165412ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:15.676932Z","caller":"traceutil/trace.go:171","msg":"trace[139912727] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"208.11982ms","start":"2026-09-09T06:17:15.468799Z","end":"2026-09-09T06:17:15.676919Z","steps":["trace[139912727] 'process raft request' (duration: 207.000223ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:15.695663Z","caller":"traceutil/trace.go:171","msg":"trace[1563859841] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"176.391671ms","start":"2026-09-09T06:17:15.51926Z","end":"2026-09-09T06:17:15.695652Z","steps":["trace[1563859841] 'process raft request' (duration: 176.27001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:15.695972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.896126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-73e5aa2a-8a88-44cf-8f0e-91804017e524\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-09T06:17:15.695999Z","caller":"traceutil/trace.go:171","msg":"trace[1723711458] range","detail":"{range_begin:/registry/persistentvolumes/pvc-73e5aa2a-8a88-44cf-8f0e-91804017e524; range_end:; response_count:1; response_revision:5016; }","duration":"215.943776ms","start":"2026-09-09T06:17:15.480046Z","end":"2026-09-09T06:17:15.69599Z","steps":["trace[1723711458] 'agreement among raft nodes before linearized reading' (duration: 215.832624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:15.696095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.871421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:17:15.696107Z","caller":"traceutil/trace.go:171","msg":"trace[1291760887] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:5016; }","duration":"210.901002ms","start":"2026-09-09T06:17:15.485202Z","end":"2026-09-09T06:17:15.696103Z","steps":["trace[1291760887] 'agreement among raft nodes before linearized reading' (duration: 210.873651ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:16.002913Z","caller":"traceutil/trace.go:171","msg":"trace[2080047111] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"160.914733ms","start":"2026-09-09T06:17:15.841974Z","end":"2026-09-09T06:17:16.002889Z","steps":["trace[2080047111] 'process raft request' (duration: 160.768201ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:16.009272Z","caller":"traceutil/trace.go:171","msg":"trace[743860383] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"153.957329ms","start":"2026-09-09T06:17:15.855291Z","end":"2026-09-09T06:17:16.009248Z","steps":["trace[743860383] 'process raft request' (duration: 153.646194ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:16.166075Z","caller":"traceutil/trace.go:171","msg":"trace[1430751135] linearizableReadLoop","detail":"{readStateIndex:5210; appliedIndex:5207; }","duration":"105.297778ms","start":"2026-09-09T06:17:16.060758Z","end":"2026-09-09T06:17:16.166056Z","steps":["trace[1430751135] 'read index received' (duration: 20.325321ms)","trace[1430751135] 'applied index is now lower than readState.Index' (duration: 84.971547ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:16.166122Z","caller":"traceutil/trace.go:171","msg":"trace[1933150954] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"105.351449ms","start":"2026-09-09T06:17:16.060749Z","end":"2026-09-09T06:17:16.1661Z","steps":["trace[1933150954] 'process raft request' (duration: 105.203566ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:16.16618Z","caller":"traceutil/trace.go:171","msg":"trace[988941243] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"105.552132ms","start":"2026-09-09T06:17:16.060617Z","end":"2026-09-09T06:17:16.166169Z","steps":["trace[988941243] 'process raft request' (duration: 105.069304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:16.166737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.951147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127304"} {"level":"info","ts":"2026-09-09T06:17:16.166809Z","caller":"traceutil/trace.go:171","msg":"trace[607431774] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5025; }","duration":"106.066788ms","start":"2026-09-09T06:17:16.060724Z","end":"2026-09-09T06:17:16.16679Z","steps":["trace[607431774] 'agreement among raft nodes before linearized reading' (duration: 105.757484ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:28.474051Z","caller":"traceutil/trace.go:171","msg":"trace[908386330] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"114.842782ms","start":"2026-09-09T06:17:28.359177Z","end":"2026-09-09T06:17:28.47402Z","steps":["trace[908386330] 'process raft request' (duration: 114.661829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:28.842274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.178386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:17:28.842359Z","caller":"traceutil/trace.go:171","msg":"trace[892106127] linearizableReadLoop","detail":"{readStateIndex:5413; appliedIndex:5412; }","duration":"357.897604ms","start":"2026-09-09T06:17:28.484447Z","end":"2026-09-09T06:17:28.842345Z","steps":["trace[892106127] 'read index received' (duration: 120.630538ms)","trace[892106127] 'applied index is now lower than readState.Index' (duration: 237.265946ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:28.842414Z","caller":"traceutil/trace.go:171","msg":"trace[645580937] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"389.273386ms","start":"2026-09-09T06:17:28.45313Z","end":"2026-09-09T06:17:28.842404Z","steps":["trace[645580937] 'process raft request' (duration: 151.874537ms)","trace[645580937] 'compare' (duration: 237.084284ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:17:28.842468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:28.453102Z","time spent":"389.336407ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:17:28.842746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.248049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-09T06:17:28.842843Z","caller":"traceutil/trace.go:171","msg":"trace[17587354] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5222; }","duration":"358.412631ms","start":"2026-09-09T06:17:28.484411Z","end":"2026-09-09T06:17:28.842823Z","steps":["trace[17587354] 'agreement among raft nodes before linearized reading' (duration: 358.074146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:28.842881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:28.484395Z","time spent":"358.476962ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-09T06:17:28.886672Z","caller":"traceutil/trace.go:171","msg":"trace[485844461] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"399.78685ms","start":"2026-09-09T06:17:28.486835Z","end":"2026-09-09T06:17:28.886622Z","steps":["trace[485844461] 'process raft request' (duration: 399.609698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:28.886879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:28.486809Z","time spent":"399.973313ms","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:17:28.898091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.350895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-09T06:17:28.898131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.895279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T06:17:28.89811Z","caller":"traceutil/trace.go:171","msg":"trace[1159418315] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"101.720549ms","start":"2026-09-09T06:17:28.796352Z","end":"2026-09-09T06:17:28.898073Z","steps":["trace[1159418315] 'process raft request' (duration: 101.404455ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:28.898168Z","caller":"traceutil/trace.go:171","msg":"trace[235977063] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5224; }","duration":"114.492946ms","start":"2026-09-09T06:17:28.783658Z","end":"2026-09-09T06:17:28.898151Z","steps":["trace[235977063] 'agreement among raft nodes before linearized reading' (duration: 114.333014ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:28.898185Z","caller":"traceutil/trace.go:171","msg":"trace[1548060883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5224; }","duration":"244.98297ms","start":"2026-09-09T06:17:28.653184Z","end":"2026-09-09T06:17:28.898167Z","steps":["trace[1548060883] 'agreement among raft nodes before linearized reading' (duration: 244.827467ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:29.0148Z","caller":"traceutil/trace.go:171","msg":"trace[1939659506] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"121.549021ms","start":"2026-09-09T06:17:28.893217Z","end":"2026-09-09T06:17:29.014766Z","steps":["trace[1939659506] 'process raft request' (duration: 64.835136ms)","trace[1939659506] 'compare' (duration: 56.461672ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:29.014862Z","caller":"traceutil/trace.go:171","msg":"trace[109632689] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"120.495535ms","start":"2026-09-09T06:17:28.894342Z","end":"2026-09-09T06:17:29.014837Z","steps":["trace[109632689] 'process raft request' (duration: 120.414174ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:29.015193Z","caller":"traceutil/trace.go:171","msg":"trace[608896396] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"121.170366ms","start":"2026-09-09T06:17:28.893994Z","end":"2026-09-09T06:17:29.015164Z","steps":["trace[608896396] 'process raft request' (duration: 120.714929ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:29.015438Z","caller":"traceutil/trace.go:171","msg":"trace[685134592] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"120.985822ms","start":"2026-09-09T06:17:28.894438Z","end":"2026-09-09T06:17:29.015424Z","steps":["trace[685134592] 'process raft request' (duration: 120.361593ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:29.015462Z","caller":"traceutil/trace.go:171","msg":"trace[167926823] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"113.275139ms","start":"2026-09-09T06:17:28.902167Z","end":"2026-09-09T06:17:29.015442Z","steps":["trace[167926823] 'process raft request' (duration: 113.074616ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:29.015774Z","caller":"traceutil/trace.go:171","msg":"trace[581813987] linearizableReadLoop","detail":"{readStateIndex:5419; appliedIndex:5415; }","duration":"117.807465ms","start":"2026-09-09T06:17:28.8979Z","end":"2026-09-09T06:17:29.015708Z","steps":["trace[581813987] 'read index received' (duration: 60.172417ms)","trace[581813987] 'applied index is now lower than readState.Index' (duration: 57.633678ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:29.015848Z","caller":"traceutil/trace.go:171","msg":"trace[1958739563] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"114.124513ms","start":"2026-09-09T06:17:28.901685Z","end":"2026-09-09T06:17:29.015809Z","steps":["trace[1958739563] 'process raft request' (duration: 113.455152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:29.016074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.889253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b819989e-2148-4955-8fcd-edffa1355bf9\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-09T06:17:29.016122Z","caller":"traceutil/trace.go:171","msg":"trace[932433186] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b819989e-2148-4955-8fcd-edffa1355bf9; range_end:; response_count:1; response_revision:5230; }","duration":"169.987415ms","start":"2026-09-09T06:17:28.84612Z","end":"2026-09-09T06:17:29.016107Z","steps":["trace[932433186] 'agreement among raft nodes before linearized reading' (duration: 169.733391ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:50.678121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2909} {"level":"info","ts":"2026-09-09T06:17:50.757242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2909,"took":"77.82913ms","hash":2688980710,"current-db-size-bytes":53215232,"current-db-size":"53 MB","current-db-size-in-use-bytes":27709440,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:17:50.757305Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2688980710,"revision":2909,"compact-revision":-1} {"level":"info","ts":"2026-09-09T06:18:05.40517Z","caller":"traceutil/trace.go:171","msg":"trace[1201081080] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"116.923694ms","start":"2026-09-09T06:18:05.288222Z","end":"2026-09-09T06:18:05.405145Z","steps":["trace[1201081080] 'process raft request' (duration: 116.761052ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:13.851065Z","caller":"traceutil/trace.go:171","msg":"trace[1617216030] transaction","detail":"{read_only:false; response_revision:6871; number_of_response:1; }","duration":"142.697248ms","start":"2026-09-09T06:21:13.70832Z","end":"2026-09-09T06:21:13.851017Z","steps":["trace[1617216030] 'process raft request' (duration: 115.018175ms)","trace[1617216030] 'compare' (duration: 27.418835ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:22:50.686772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5394} {"level":"info","ts":"2026-09-09T06:22:50.773391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5394,"took":"85.354698ms","hash":1640587172,"current-db-size-bytes":53215232,"current-db-size":"53 MB","current-db-size-in-use-bytes":18882560,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-09T06:22:50.773462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1640587172,"revision":5394,"compact-revision":2909}