{"level":"warn","ts":"2026-08-24T15:36:25.72263Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:36:25.722775Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.129:2380","--initial-cluster=instance=https://199.19.213.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-24T15:36:25.723084Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:36:25.723102Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.129:2380"]} {"level":"info","ts":"2026-08-24T15:36:25.72315Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:36:25.724853Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"]} {"level":"info","ts":"2026-08-24T15:36:25.724973Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.129:2380"],"listen-peer-urls":["https://199.19.213.129:2380"],"advertise-client-urls":["https://199.19.213.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-24T15:36:25.740231Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.94214ms"} {"level":"info","ts":"2026-08-24T15:36:25.75337Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d319cd7bf0a1887b","cluster-id":"2ab213cc48e2c85a"} {"level":"info","ts":"2026-08-24T15:36:25.753522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:36:25.753563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became follower at term 0"} {"level":"info","ts":"2026-08-24T15:36:25.753626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d319cd7bf0a1887b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:36:25.753675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became follower at term 1"} {"level":"info","ts":"2026-08-24T15:36:25.753788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=(15211415148622350459)"} {"level":"warn","ts":"2026-08-24T15:36:25.764737Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:36:25.766567Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:36:25.771401Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-24T15:36:25.782701Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d319cd7bf0a1887b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:36:25.783045Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d319cd7bf0a1887b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:36:25.783378Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:36:25.783458Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:36:25.783473Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:36:25.788208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=(15211415148622350459)"} {"level":"info","ts":"2026-08-24T15:36:25.788446Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","added-peer-id":"d319cd7bf0a1887b","added-peer-peer-urls":["https://199.19.213.129:2380"]} {"level":"info","ts":"2026-08-24T15:36:25.788469Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:36:25.78863Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.129:2380"} {"level":"info","ts":"2026-08-24T15:36:25.789004Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d319cd7bf0a1887b","initial-advertise-peer-urls":["https://199.19.213.129:2380"],"listen-peer-urls":["https://199.19.213.129:2380"],"advertise-client-urls":["https://199.19.213.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:36:25.789097Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:36:25.789337Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.129:2380"} {"level":"info","ts":"2026-08-24T15:36:26.354386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:36:26.354478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:36:26.354521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b received MsgPreVoteResp from d319cd7bf0a1887b at term 1"} {"level":"info","ts":"2026-08-24T15:36:26.354541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:36:26.354558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b received MsgVoteResp from d319cd7bf0a1887b at term 2"} {"level":"info","ts":"2026-08-24T15:36:26.354589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became leader at term 2"} {"level":"info","ts":"2026-08-24T15:36:26.354618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d319cd7bf0a1887b elected leader d319cd7bf0a1887b at term 2"} {"level":"info","ts":"2026-08-24T15:36:26.358043Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:36:26.358216Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d319cd7bf0a1887b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.129:2379]}","request-path":"/0/members/d319cd7bf0a1887b/attributes","cluster-id":"2ab213cc48e2c85a","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:36:26.358457Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:36:26.3585Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:36:26.358886Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:36:26.359123Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:36:26.35986Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:36:26.359965Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:36:26.359994Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:36:26.365992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.129:2379"} {"level":"info","ts":"2026-08-24T15:36:26.366687Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:37:01.199422Z","caller":"traceutil/trace.go:171","msg":"trace[1927854715] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"119.408857ms","start":"2026-08-24T15:37:01.079987Z","end":"2026-08-24T15:37:01.199396Z","steps":["trace[1927854715] 'process raft request' (duration: 119.195623ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:11.163412Z","caller":"traceutil/trace.go:171","msg":"trace[1221738637] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"113.532137ms","start":"2026-08-24T15:37:11.049858Z","end":"2026-08-24T15:37:11.16339Z","steps":["trace[1221738637] 'read index received' (duration: 112.887612ms)","trace[1221738637] 'applied index is now lower than readState.Index' (duration: 643.895µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:37:11.163518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.647721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:37:11.163571Z","caller":"traceutil/trace.go:171","msg":"trace[1066572494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"113.746592ms","start":"2026-08-24T15:37:11.049815Z","end":"2026-08-24T15:37:11.163561Z","steps":["trace[1066572494] 'agreement among raft nodes before linearized reading' (duration: 113.65788ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:11.163552Z","caller":"traceutil/trace.go:171","msg":"trace[1753452775] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"202.980966ms","start":"2026-08-24T15:37:10.96049Z","end":"2026-08-24T15:37:11.16347Z","steps":["trace[1753452775] 'process raft request' (duration: 202.483334ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:11.470512Z","caller":"traceutil/trace.go:171","msg":"trace[1206807433] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"210.257576ms","start":"2026-08-24T15:37:11.260235Z","end":"2026-08-24T15:37:11.470493Z","steps":["trace[1206807433] 'read index received' (duration: 210.01341ms)","trace[1206807433] 'applied index is now lower than readState.Index' (duration: 243.336µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:37:11.470612Z","caller":"traceutil/trace.go:171","msg":"trace[337450521] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"221.482543ms","start":"2026-08-24T15:37:11.249103Z","end":"2026-08-24T15:37:11.470586Z","steps":["trace[337450521] 'process raft request' (duration: 221.216547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:11.470813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.524032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-24T15:37:11.470847Z","caller":"traceutil/trace.go:171","msg":"trace[216829577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"210.679235ms","start":"2026-08-24T15:37:11.260159Z","end":"2026-08-24T15:37:11.470839Z","steps":["trace[216829577] 'agreement among raft nodes before linearized reading' (duration: 210.492101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:11.470864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.44036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fnjk2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-24T15:37:11.47093Z","caller":"traceutil/trace.go:171","msg":"trace[87694065] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fnjk2; range_end:; response_count:1; response_revision:597; }","duration":"135.551733ms","start":"2026-08-24T15:37:11.335359Z","end":"2026-08-24T15:37:11.470911Z","steps":["trace[87694065] 'agreement among raft nodes before linearized reading' (duration: 135.342418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:11.615414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.711237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:37:11.615516Z","caller":"traceutil/trace.go:171","msg":"trace[1403570879] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"138.175677ms","start":"2026-08-24T15:37:11.477325Z","end":"2026-08-24T15:37:11.615501Z","steps":["trace[1403570879] 'read index received' (duration: 27.757633ms)","trace[1403570879] 'applied index is now lower than readState.Index' (duration: 110.416454ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:37:11.615527Z","caller":"traceutil/trace.go:171","msg":"trace[1421151351] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"140.743538ms","start":"2026-08-24T15:37:11.47477Z","end":"2026-08-24T15:37:11.615514Z","steps":["trace[1421151351] 'process raft request' (duration: 30.277693ms)","trace[1421151351] 'compare' (duration: 109.572673ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:37:11.61558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.254299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-24T15:37:11.615611Z","caller":"traceutil/trace.go:171","msg":"trace[122923392] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:598; }","duration":"138.29876ms","start":"2026-08-24T15:37:11.477304Z","end":"2026-08-24T15:37:11.615603Z","steps":["trace[122923392] 'agreement among raft nodes before linearized reading' (duration: 138.237608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:12.132316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.201374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:37:12.132464Z","caller":"traceutil/trace.go:171","msg":"trace[2097260073] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"506.734441ms","start":"2026-08-24T15:37:11.625711Z","end":"2026-08-24T15:37:12.132445Z","steps":["trace[2097260073] 'read index received' (duration: 398.334523ms)","trace[2097260073] 'applied index is now lower than readState.Index' (duration: 108.398668ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:37:12.132474Z","caller":"traceutil/trace.go:171","msg":"trace[2136126669] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"647.554329ms","start":"2026-08-24T15:37:11.484894Z","end":"2026-08-24T15:37:12.132448Z","steps":["trace[2136126669] 'process raft request' (duration: 539.140641ms)","trace[2136126669] 'compare' (duration: 108.07785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:37:12.132591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.861354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:37:12.132679Z","caller":"traceutil/trace.go:171","msg":"trace[783575581] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:599; }","duration":"506.958906ms","start":"2026-08-24T15:37:11.625698Z","end":"2026-08-24T15:37:12.132657Z","steps":["trace[783575581] 'agreement among raft nodes before linearized reading' (duration: 506.820583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:12.132725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:37:11.625652Z","time spent":"507.059748ms","remote":"127.0.0.1:53680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-08-24T15:37:12.132593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:37:11.484881Z","time spent":"647.646442ms","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:37:12.321705Z","caller":"traceutil/trace.go:171","msg":"trace[135564296] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"186.460976ms","start":"2026-08-24T15:37:12.135227Z","end":"2026-08-24T15:37:12.321688Z","steps":["trace[135564296] 'process raft request' (duration: 186.367183ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:12.324283Z","caller":"traceutil/trace.go:171","msg":"trace[1287569613] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"100.73272ms","start":"2026-08-24T15:37:12.223526Z","end":"2026-08-24T15:37:12.324258Z","steps":["trace[1287569613] 'process raft request' (duration: 100.685258ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:12.324635Z","caller":"traceutil/trace.go:171","msg":"trace[541884941] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"186.018275ms","start":"2026-08-24T15:37:12.138602Z","end":"2026-08-24T15:37:12.32462Z","steps":["trace[541884941] 'process raft request' (duration: 185.492001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:28.706463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.961328ms","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-08-24T15:37:28.706595Z","caller":"traceutil/trace.go:171","msg":"trace[57151514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"195.123503ms","start":"2026-08-24T15:37:28.511441Z","end":"2026-08-24T15:37:28.706564Z","steps":["trace[57151514] 'range keys from in-memory index tree' (duration: 194.748501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:28.706631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.27229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-24T15:37:28.706631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.385462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-crrpj\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-24T15:37:28.706713Z","caller":"traceutil/trace.go:171","msg":"trace[379430497] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-crrpj; range_end:; response_count:1; response_revision:760; }","duration":"271.523426ms","start":"2026-08-24T15:37:28.435173Z","end":"2026-08-24T15:37:28.706696Z","steps":["trace[379430497] 'range keys from in-memory index tree' (duration: 271.184556ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:28.706713Z","caller":"traceutil/trace.go:171","msg":"trace[1913145846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"311.406804ms","start":"2026-08-24T15:37:28.395284Z","end":"2026-08-24T15:37:28.706691Z","steps":["trace[1913145846] 'range keys from in-memory index tree' (duration: 311.063833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:28.706757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:37:28.395256Z","time spent":"311.491568ms","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:37:59.855963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.692942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:37:59.856064Z","caller":"traceutil/trace.go:171","msg":"trace[406019214] range","detail":"{range_begin:/registry/masterleases/199.19.213.129; range_end:; response_count:1; response_revision:1169; }","duration":"209.802116ms","start":"2026-08-24T15:37:59.646247Z","end":"2026-08-24T15:37:59.856049Z","steps":["trace[406019214] 'range keys from in-memory index tree' (duration: 209.577447ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:59.879409Z","caller":"traceutil/trace.go:171","msg":"trace[196654728] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"136.304747ms","start":"2026-08-24T15:37:59.743083Z","end":"2026-08-24T15:37:59.879387Z","steps":["trace[196654728] 'process raft request' (duration: 136.183221ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:38:15.041826Z","caller":"traceutil/trace.go:171","msg":"trace[1737587797] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"106.451107ms","start":"2026-08-24T15:38:14.935349Z","end":"2026-08-24T15:38:15.0418Z","steps":["trace[1737587797] 'read index received' (duration: 97.182839ms)","trace[1737587797] 'applied index is now lower than readState.Index' (duration: 9.266598ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:38:15.042016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.641416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:38:15.042108Z","caller":"traceutil/trace.go:171","msg":"trace[837045664] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1305; }","duration":"106.789973ms","start":"2026-08-24T15:38:14.935298Z","end":"2026-08-24T15:38:15.042088Z","steps":["trace[837045664] 'agreement among raft nodes before linearized reading' (duration: 106.618095ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:38:39.389423Z","caller":"traceutil/trace.go:171","msg":"trace[1834341500] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"106.579488ms","start":"2026-08-24T15:38:39.282822Z","end":"2026-08-24T15:38:39.389401Z","steps":["trace[1834341500] 'process raft request' (duration: 106.42094ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:38:39.38972Z","caller":"traceutil/trace.go:171","msg":"trace[1714270822] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"106.186888ms","start":"2026-08-24T15:38:39.283521Z","end":"2026-08-24T15:38:39.389707Z","steps":["trace[1714270822] 'process raft request' (duration: 105.770106ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:38:39.391075Z","caller":"traceutil/trace.go:171","msg":"trace[1759065735] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"108.650905ms","start":"2026-08-24T15:38:39.282411Z","end":"2026-08-24T15:38:39.391062Z","steps":["trace[1759065735] 'process raft request' (duration: 87.463594ms)","trace[1759065735] 'compare' (duration: 19.279703ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:38:51.102574Z","caller":"traceutil/trace.go:171","msg":"trace[828352997] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"251.557904ms","start":"2026-08-24T15:38:50.850986Z","end":"2026-08-24T15:38:51.102544Z","steps":["trace[828352997] 'read index received' (duration: 251.517582ms)","trace[828352997] 'applied index is now lower than readState.Index' (duration: 38.872µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:38:51.102703Z","caller":"traceutil/trace.go:171","msg":"trace[213982981] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"279.273592ms","start":"2026-08-24T15:38:50.823387Z","end":"2026-08-24T15:38:51.102661Z","steps":["trace[213982981] 'process raft request' (duration: 278.973575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:38:51.102773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.740734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:38:51.102818Z","caller":"traceutil/trace.go:171","msg":"trace[682739124] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1860; }","duration":"251.861991ms","start":"2026-08-24T15:38:50.850943Z","end":"2026-08-24T15:38:51.102805Z","steps":["trace[682739124] 'agreement among raft nodes before linearized reading' (duration: 251.741964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:38:51.332568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.888566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:38:51.332957Z","caller":"traceutil/trace.go:171","msg":"trace[577290686] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"315.16463ms","start":"2026-08-24T15:38:51.017751Z","end":"2026-08-24T15:38:51.332916Z","steps":["trace[577290686] 'process raft request' (duration: 210.855523ms)","trace[577290686] 'compare' (duration: 103.736807ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:38:51.33328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:38:51.017731Z","time spent":"315.444095ms","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:38:51.695807Z","caller":"traceutil/trace.go:171","msg":"trace[702970399] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"122.936046ms","start":"2026-08-24T15:38:51.57285Z","end":"2026-08-24T15:38:51.695786Z","steps":["trace[702970399] 'process raft request' (duration: 122.790198ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:38:52.008268Z","caller":"traceutil/trace.go:171","msg":"trace[1105551383] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"272.905276ms","start":"2026-08-24T15:38:51.735328Z","end":"2026-08-24T15:38:52.008233Z","steps":["trace[1105551383] 'process raft request' (duration: 258.632208ms)","trace[1105551383] 'compare' (duration: 14.167033ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:38:52.008602Z","caller":"traceutil/trace.go:171","msg":"trace[102253021] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"237.209842ms","start":"2026-08-24T15:38:51.771355Z","end":"2026-08-24T15:38:52.008565Z","steps":["trace[102253021] 'process raft request' (duration: 236.887325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:38:52.0087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.8948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-24T15:38:52.008743Z","caller":"traceutil/trace.go:171","msg":"trace[2051003712] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"257.987124ms","start":"2026-08-24T15:38:51.750747Z","end":"2026-08-24T15:38:52.008734Z","steps":["trace[2051003712] 'agreement among raft nodes before linearized reading' (duration: 257.587883ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:38:52.008288Z","caller":"traceutil/trace.go:171","msg":"trace[1781942684] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"257.477127ms","start":"2026-08-24T15:38:51.750791Z","end":"2026-08-24T15:38:52.008268Z","steps":["trace[1781942684] 'read index received' (duration: 243.334196ms)","trace[1781942684] 'applied index is now lower than readState.Index' (duration: 14.142171ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:38:52.009691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.351952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-24T15:38:52.009776Z","caller":"traceutil/trace.go:171","msg":"trace[1015112169] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1867; }","duration":"176.489509ms","start":"2026-08-24T15:38:51.833265Z","end":"2026-08-24T15:38:52.009755Z","steps":["trace[1015112169] 'agreement among raft nodes before linearized reading' (duration: 175.720468ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:04.199494Z","caller":"traceutil/trace.go:171","msg":"trace[208156772] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"151.610572ms","start":"2026-08-24T15:39:04.047847Z","end":"2026-08-24T15:39:04.199457Z","steps":["trace[208156772] 'process raft request' (duration: 151.480264ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:04.220557Z","caller":"traceutil/trace.go:171","msg":"trace[288227704] linearizableReadLoop","detail":"{readStateIndex:2053; appliedIndex:2051; }","duration":"143.079557ms","start":"2026-08-24T15:39:04.07745Z","end":"2026-08-24T15:39:04.22053Z","steps":["trace[288227704] 'read index received' (duration: 121.829759ms)","trace[288227704] 'applied index is now lower than readState.Index' (duration: 21.248568ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:04.220627Z","caller":"traceutil/trace.go:171","msg":"trace[1983307408] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"150.930235ms","start":"2026-08-24T15:39:04.06967Z","end":"2026-08-24T15:39:04.2206Z","steps":["trace[1983307408] 'process raft request' (duration: 150.714303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:04.220812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.343691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-24T15:39:04.220852Z","caller":"traceutil/trace.go:171","msg":"trace[2027106864] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1975; }","duration":"143.417305ms","start":"2026-08-24T15:39:04.077421Z","end":"2026-08-24T15:39:04.220839Z","steps":["trace[2027106864] 'agreement among raft nodes before linearized reading' (duration: 143.270767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:04.221096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.159082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-24T15:39:04.22119Z","caller":"traceutil/trace.go:171","msg":"trace[1286266571] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1975; }","duration":"130.29639ms","start":"2026-08-24T15:39:04.09088Z","end":"2026-08-24T15:39:04.221177Z","steps":["trace[1286266571] 'agreement among raft nodes before linearized reading' (duration: 130.039646ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:04.588455Z","caller":"traceutil/trace.go:171","msg":"trace[764983153] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"218.486936ms","start":"2026-08-24T15:39:04.369943Z","end":"2026-08-24T15:39:04.58843Z","steps":["trace[764983153] 'process raft request' (duration: 192.479789ms)","trace[764983153] 'compare' (duration: 25.727922ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:04.588474Z","caller":"traceutil/trace.go:171","msg":"trace[1783659225] linearizableReadLoop","detail":"{readStateIndex:2056; appliedIndex:2055; }","duration":"102.417691ms","start":"2026-08-24T15:39:04.486027Z","end":"2026-08-24T15:39:04.588444Z","steps":["trace[1783659225] 'read index received' (duration: 76.451436ms)","trace[1783659225] 'applied index is now lower than readState.Index' (duration: 25.942813ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:04.588724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.668084ms","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-08-24T15:39:04.588789Z","caller":"traceutil/trace.go:171","msg":"trace[1271800590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1978; }","duration":"102.794911ms","start":"2026-08-24T15:39:04.48598Z","end":"2026-08-24T15:39:04.588775Z","steps":["trace[1271800590] 'agreement among raft nodes before linearized reading' (duration: 102.575809ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:28.858899Z","caller":"traceutil/trace.go:171","msg":"trace[365339171] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"130.699649ms","start":"2026-08-24T15:39:28.728163Z","end":"2026-08-24T15:39:28.858863Z","steps":["trace[365339171] 'process raft request' (duration: 99.53614ms)","trace[365339171] 'compare' (duration: 31.043992ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:31.015533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.483961ms","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-08-24T15:39:31.015655Z","caller":"traceutil/trace.go:171","msg":"trace[745260384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2125; }","duration":"216.659971ms","start":"2026-08-24T15:39:30.798971Z","end":"2026-08-24T15:39:31.015631Z","steps":["trace[745260384] 'range keys from in-memory index tree' (duration: 216.263719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:31.015677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.449027ms","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-08-24T15:39:31.015739Z","caller":"traceutil/trace.go:171","msg":"trace[364187822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2125; }","duration":"152.549903ms","start":"2026-08-24T15:39:30.863175Z","end":"2026-08-24T15:39:31.015725Z","steps":["trace[364187822] 'range keys from in-memory index tree' (duration: 152.252426ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:31.17999Z","caller":"traceutil/trace.go:171","msg":"trace[571547936] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"159.136434ms","start":"2026-08-24T15:39:31.020821Z","end":"2026-08-24T15:39:31.179957Z","steps":["trace[571547936] 'process raft request' (duration: 158.89863ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:31.333221Z","caller":"traceutil/trace.go:171","msg":"trace[1689055983] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2209; }","duration":"282.005476ms","start":"2026-08-24T15:39:31.051191Z","end":"2026-08-24T15:39:31.333196Z","steps":["trace[1689055983] 'read index received' (duration: 128.529091ms)","trace[1689055983] 'applied index is now lower than readState.Index' (duration: 153.475245ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:31.333373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.169104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:39:31.33341Z","caller":"traceutil/trace.go:171","msg":"trace[130404245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2127; }","duration":"282.26223ms","start":"2026-08-24T15:39:31.051138Z","end":"2026-08-24T15:39:31.333401Z","steps":["trace[130404245] 'agreement among raft nodes before linearized reading' (duration: 282.156194ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:31.333821Z","caller":"traceutil/trace.go:171","msg":"trace[712592965] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"312.314742ms","start":"2026-08-24T15:39:31.021466Z","end":"2026-08-24T15:39:31.333781Z","steps":["trace[712592965] 'process raft request' (duration: 311.592601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:31.333924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:31.021446Z","time spent":"312.415807ms","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:39:59.096434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:39:59.597016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:00.098003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:00.598853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:01.099493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:01.599747Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:02.052401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000324498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:40:02.052604Z","caller":"traceutil/trace.go:171","msg":"trace[1364652504] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000583444s","start":"2026-08-24T15:40:00.051999Z","end":"2026-08-24T15:40:02.052582Z","steps":["trace[1364652504] 'agreement among raft nodes before linearized reading' (duration: 2.0003608s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:02.052752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:00.051976Z","time spent":"2.000757134s","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:40:02.100679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:02.602113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:03.102696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:03.507009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.50765Z","time spent":"4.999351426s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/24 15:40:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:03.603309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:04.053966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000661395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:40:04.054051Z","caller":"traceutil/trace.go:171","msg":"trace[763300037] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000798833s","start":"2026-08-24T15:40:02.053235Z","end":"2026-08-24T15:40:04.054034Z","steps":["trace[763300037] 'agreement among raft nodes before linearized reading' (duration: 2.000689877s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:04.054104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:02.053218Z","time spent":"2.000876198s","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:40:04.103415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:04.322495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99934914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:04.322594Z","caller":"traceutil/trace.go:171","msg":"trace[395087111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999494058s","start":"2026-08-24T15:39:59.323075Z","end":"2026-08-24T15:40:04.322569Z","steps":["trace[395087111] 'agreement among raft nodes before linearized reading' (duration: 4.999381982s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:04.322659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:59.323056Z","time spent":"4.999591864s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/24 15:40:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:04.523955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000553976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:40:04.524048Z","caller":"traceutil/trace.go:171","msg":"trace[1898075611] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000718885s","start":"2026-08-24T15:40:02.52331Z","end":"2026-08-24T15:40:04.524029Z","steps":["trace[1898075611] 'agreement among raft nodes before linearized reading' (duration: 2.000579607s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:04.524153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:02.523291Z","time spent":"2.000842762s","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:40:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:04.603693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:04.755328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99997069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:04.755592Z","caller":"traceutil/trace.go:171","msg":"trace[396448158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000269988s","start":"2026-08-24T15:39:59.755296Z","end":"2026-08-24T15:40:04.755566Z","steps":["trace[396448158] 'agreement among raft nodes before linearized reading' (duration: 4.999991682s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:04.755844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:59.755281Z","time spent":"5.000543015s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/24 15:40:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:04.872933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999093783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:40:04.873021Z","caller":"traceutil/trace.go:171","msg":"trace[1932921021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999241882s","start":"2026-08-24T15:39:59.873759Z","end":"2026-08-24T15:40:04.873001Z","steps":["trace[1932921021] 'agreement among raft nodes before linearized reading' (duration: 4.999117435s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:04.87307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:59.87374Z","time spent":"4.999321007s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:40:05.104906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:05.552043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:00.553392Z","time spent":"4.998643561s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-24T15:40:05.596482Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-08-24T15:40:05.596627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.001216369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-24T15:40:05.596734Z","caller":"traceutil/trace.go:171","msg":"trace[946615452] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; }","duration":"7.001377217s","start":"2026-08-24T15:39:58.59534Z","end":"2026-08-24T15:40:05.596717Z","steps":["trace[946615452] 'agreement among raft nodes before linearized reading' (duration: 7.001238339s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:05.596781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.595323Z","time spent":"7.001446181s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":0,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:06.060075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000327399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:06.060538Z","caller":"traceutil/trace.go:171","msg":"trace[1264569253] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000497389s","start":"2026-08-24T15:40:04.059689Z","end":"2026-08-24T15:40:06.060187Z","steps":["trace[1264569253] 'agreement among raft nodes before linearized reading' (duration: 2.0003436s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:06.06063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:04.059677Z","time spent":"2.000933975s","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:40:06 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:06.096793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:06.597826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:07.098638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:07.599443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:08.060678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000144818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:08.06076Z","caller":"traceutil/trace.go:171","msg":"trace[783153110] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000303187s","start":"2026-08-24T15:40:06.060439Z","end":"2026-08-24T15:40:08.060742Z","steps":["trace[783153110] 'agreement among raft nodes before linearized reading' (duration: 2.000196091s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:08.060808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:06.060418Z","time spent":"2.000380171s","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:40:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:08.100232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:08.506749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998462361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:08.506845Z","caller":"traceutil/trace.go:171","msg":"trace[1942383567] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; }","duration":"4.998605969s","start":"2026-08-24T15:40:03.508216Z","end":"2026-08-24T15:40:08.506822Z","steps":["trace[1942383567] 'agreement among raft nodes before linearized reading' (duration: 4.998490343s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:08.506906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:03.50818Z","time spent":"4.998711045s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} 2026/08/24 15:40:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:08.529682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.503474518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:08.529752Z","caller":"traceutil/trace.go:171","msg":"trace[1278887015] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; }","duration":"9.503608415s","start":"2026-08-24T15:39:59.026129Z","end":"2026-08-24T15:40:08.529737Z","steps":["trace[1278887015] 'agreement among raft nodes before linearized reading' (duration: 9.50351494s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:08.52979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:59.026105Z","time spent":"9.50367798s","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":0,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} 2026/08/24 15:40:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:08.601699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:08.602193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.005233955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:08.602238Z","caller":"traceutil/trace.go:171","msg":"trace[91995697] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; }","duration":"10.00532334s","start":"2026-08-24T15:39:58.596901Z","end":"2026-08-24T15:40:08.602224Z","steps":["trace[91995697] 'agreement among raft nodes before linearized reading' (duration: 10.005258476s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:08.602278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.596885Z","time spent":"10.005380613s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} 2026/08/24 15:40:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:08.812696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.99959604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:08.812775Z","caller":"traceutil/trace.go:171","msg":"trace[286854492] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; }","duration":"9.999728187s","start":"2026-08-24T15:39:58.813032Z","end":"2026-08-24T15:40:08.812761Z","steps":["trace[286854492] 'agreement among raft nodes before linearized reading' (duration: 9.999628892s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:08.812823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.813012Z","time spent":"9.999803612s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} 2026/08/24 15:40:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:09.102422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:09.322539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.996637625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:09.322623Z","caller":"traceutil/trace.go:171","msg":"trace[202424189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.996778024s","start":"2026-08-24T15:40:06.325826Z","end":"2026-08-24T15:40:09.322604Z","steps":["trace[202424189] 'agreement among raft nodes before linearized reading' (duration: 2.996653957s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:09.322661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:06.325815Z","time spent":"2.996838777s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/24 15:40:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:09.466402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999802862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:09.466502Z","caller":"traceutil/trace.go:171","msg":"trace[216136013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.00029152s","start":"2026-08-24T15:39:59.466193Z","end":"2026-08-24T15:40:09.466484Z","steps":["trace[216136013] 'agreement among raft nodes before linearized reading' (duration: 10.000142652s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:09.466557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:59.466166Z","time spent":"10.000382096s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/08/24 15:40:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:09.602816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:09.755879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997538344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:09.755975Z","caller":"traceutil/trace.go:171","msg":"trace[1513720261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.997697733s","start":"2026-08-24T15:40:06.758257Z","end":"2026-08-24T15:40:09.755954Z","steps":["trace[1513720261] 'agreement among raft nodes before linearized reading' (duration: 2.997544785s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:09.75608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:06.758223Z","time spent":"2.9978061s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:40:09.759294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999842775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:09.759388Z","caller":"traceutil/trace.go:171","msg":"trace[1201320416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.000020827s","start":"2026-08-24T15:39:59.759347Z","end":"2026-08-24T15:40:09.759368Z","steps":["trace[1201320416] 'agreement among raft nodes before linearized reading' (duration: 9.999896828s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:09.760534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:59.759328Z","time spent":"10.001160853s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/08/24 15:40:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:09.873629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998144602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:09.873695Z","caller":"traceutil/trace.go:171","msg":"trace[2019068220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.998257959s","start":"2026-08-24T15:40:06.875423Z","end":"2026-08-24T15:40:09.873681Z","steps":["trace[2019068220] 'agreement among raft nodes before linearized reading' (duration: 2.998172964s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:09.87373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:06.875405Z","time spent":"2.998319482s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/08/24 15:40:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:10.026842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:03.02605Z","time spent":"7.000784828s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-24T15:40:10.067944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00028607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:40:10.068035Z","caller":"traceutil/trace.go:171","msg":"trace[1608414115] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000428519s","start":"2026-08-24T15:40:08.067589Z","end":"2026-08-24T15:40:10.068017Z","steps":["trace[1608414115] 'agreement among raft nodes before linearized reading' (duration: 2.000307692s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:10.068088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:08.067572Z","time spent":"2.000506283s","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:40:10.10318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:10.384309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:03.384139Z","time spent":"7.000163845s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-24T15:40:10.553121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998975126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:10.5533Z","caller":"traceutil/trace.go:171","msg":"trace[1045182664] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.999168017s","start":"2026-08-24T15:40:05.55411Z","end":"2026-08-24T15:40:10.553278Z","steps":["trace[1045182664] 'agreement among raft nodes before linearized reading' (duration: 4.998973076s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:10.553582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:05.554049Z","time spent":"4.999516657s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/08/24 15:40:10 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:10.603541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:11.104186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:11.604365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:12.028699Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"087ba0346a6fab93","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-24T15:40:12.069408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000480411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:40:12.069507Z","caller":"traceutil/trace.go:171","msg":"trace[1627609649] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000640541s","start":"2026-08-24T15:40:10.068842Z","end":"2026-08-24T15:40:12.069482Z","steps":["trace[1627609649] 'agreement among raft nodes before linearized reading' (duration: 2.000513233s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.069586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:10.068821Z","time spent":"2.000749648s","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:40:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:12.105101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:12.597367Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-08-24T15:40:12.597554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.940474383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.129\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-24T15:40:12.597573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.472496578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-24T15:40:12.597609Z","caller":"traceutil/trace.go:171","msg":"trace[743829549] range","detail":"{range_begin:/registry/masterleases/199.19.213.129; range_end:; }","duration":"12.940576079s","start":"2026-08-24T15:39:59.657011Z","end":"2026-08-24T15:40:12.597587Z","steps":["trace[743829549] 'agreement among raft nodes before linearized reading' (duration: 12.940498384s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.597588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.945779172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18cec7f5553dbc6f\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-24T15:40:12.597663Z","caller":"traceutil/trace.go:171","msg":"trace[668706416] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; }","duration":"11.472650568s","start":"2026-08-24T15:40:01.124989Z","end":"2026-08-24T15:40:12.597639Z","steps":["trace[668706416] 'agreement among raft nodes before linearized reading' (duration: 11.47252549s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.597676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:59.656985Z","time spent":"12.940673175s","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/199.19.213.129\" "} {"level":"info","ts":"2026-08-24T15:40:12.597674Z","caller":"traceutil/trace.go:171","msg":"trace[16355039] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18cec7f5553dbc6f; range_end:; }","duration":"13.94591649s","start":"2026-08-24T15:39:58.651736Z","end":"2026-08-24T15:40:12.597653Z","steps":["trace[16355039] 'agreement among raft nodes before linearized reading' (duration: 13.945801504s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.597729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:01.124971Z","time spent":"11.472743273s","remote":"127.0.0.1:53698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:12.597734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.651715Z","time spent":"13.946007026s","remote":"127.0.0.1:53622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18cec7f5553dbc6f\" "} {"level":"warn","ts":"2026-08-24T15:40:12.59774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.172289842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-24T15:40:12.597816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.99364967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-24T15:40:12.597848Z","caller":"traceutil/trace.go:171","msg":"trace[457493475] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; }","duration":"10.993684902s","start":"2026-08-24T15:40:01.604154Z","end":"2026-08-24T15:40:12.597839Z","steps":["trace[457493475] 'agreement among raft nodes before linearized reading' (duration: 10.99364952s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:12.597831Z","caller":"traceutil/trace.go:171","msg":"trace[1510760586] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; }","duration":"8.17242469s","start":"2026-08-24T15:40:04.425381Z","end":"2026-08-24T15:40:12.597806Z","steps":["trace[1510760586] 'agreement among raft nodes before linearized reading' (duration: 8.172313394s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.597881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:01.604105Z","time spent":"10.993768607s","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:12.597843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.987278542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-24T15:40:12.597993Z","caller":"traceutil/trace.go:171","msg":"trace[1417967607] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; }","duration":"13.987555588s","start":"2026-08-24T15:39:58.610401Z","end":"2026-08-24T15:40:12.597957Z","steps":["trace[1417967607] 'agreement among raft nodes before linearized reading' (duration: 13.987297003s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.598031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.022625803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-24T15:40:12.598058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.610385Z","time spent":"13.987642592s","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":0,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-24T15:40:12.598072Z","caller":"traceutil/trace.go:171","msg":"trace[1945163972] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; }","duration":"7.022703917s","start":"2026-08-24T15:40:05.575358Z","end":"2026-08-24T15:40:12.598062Z","steps":["trace[1945163972] 'agreement among raft nodes before linearized reading' (duration: 7.022662115s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.598103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:05.575338Z","time spent":"7.02275761s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":0,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-24T15:40:12.598204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.986686908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-24T15:40:12.598936Z","caller":"traceutil/trace.go:171","msg":"trace[35403400] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; }","duration":"12.987459283s","start":"2026-08-24T15:39:59.611464Z","end":"2026-08-24T15:40:12.598923Z","steps":["trace[35403400] 'agreement among raft nodes before linearized reading' (duration: 12.986709109s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.598991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:59.611448Z","time spent":"12.987520986s","remote":"127.0.0.1:45562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":0,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:12.597785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.827714232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-24T15:40:12.599033Z","caller":"traceutil/trace.go:171","msg":"trace[396108303] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; }","duration":"8.828997026s","start":"2026-08-24T15:40:03.770028Z","end":"2026-08-24T15:40:12.599025Z","steps":["trace[396108303] 'agreement among raft nodes before linearized reading' (duration: 8.827748104s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.599061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:03.770011Z","time spent":"8.829040009s","remote":"127.0.0.1:53796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":0,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:12.597908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:04.425366Z","time spent":"8.172528847s","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":0,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:13.097969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056999,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:13.318699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:06.318072Z","time spent":"7.000621367s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-24T15:40:13.598495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056999,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:14.075389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000287425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:40:14.075466Z","caller":"traceutil/trace.go:171","msg":"trace[261269805] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000416613s","start":"2026-08-24T15:40:12.075034Z","end":"2026-08-24T15:40:14.075451Z","steps":["trace[261269805] 'agreement among raft nodes before linearized reading' (duration: 2.000313857s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:14.075511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:12.075018Z","time spent":"2.000482937s","remote":"127.0.0.1:53558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:40:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:14.099557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056999,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:14.323958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.32461Z","time spent":"4.999340047s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/24 15:40:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:14.600091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056999,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:14.874286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.875479Z","time spent":"4.99879878s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/24 15:40:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:40:15.100432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834630358278056999,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:15.383226Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"16.834557897s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T15:40:15.409187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:01.718834Z","time spent":"13.690345136s","remote":"127.0.0.1:38944","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-24T15:40:15.410066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.221711361s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:15.410386Z","caller":"traceutil/trace.go:171","msg":"trace[1503759157] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2276; }","duration":"7.22208397s","start":"2026-08-24T15:40:08.188274Z","end":"2026-08-24T15:40:15.410358Z","steps":["trace[1503759157] 'range keys from in-memory index tree' (duration: 7.221685931s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.553454Z","caller":"traceutil/trace.go:171","msg":"trace[1007641461] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"6.084211649s","start":"2026-08-24T15:40:09.469201Z","end":"2026-08-24T15:40:15.553413Z","steps":["trace[1007641461] 'process raft request' (duration: 6.084076743s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.553551Z","caller":"traceutil/trace.go:171","msg":"trace[367641096] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"4.997759616s","start":"2026-08-24T15:40:10.555778Z","end":"2026-08-24T15:40:15.553537Z","steps":["trace[367641096] 'process raft request' (duration: 4.997586171s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.553544Z","caller":"traceutil/trace.go:171","msg":"trace[262736416] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"5.791073117s","start":"2026-08-24T15:40:09.762448Z","end":"2026-08-24T15:40:15.553521Z","steps":["trace[262736416] 'process raft request' (duration: 5.790860953s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.553632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:10.555754Z","time spent":"4.997830826s","remote":"127.0.0.1:53622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:40:15.553491Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"69.508µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-08-24T15:40:15.553664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.762427Z","time spent":"5.791166787s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:40:15.55367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.469136Z","time spent":"6.084433409s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:40:15.553727Z","caller":"traceutil/trace.go:171","msg":"trace[1702763274] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2286; }","duration":"5.5227499s","start":"2026-08-24T15:40:10.030968Z","end":"2026-08-24T15:40:15.553718Z","steps":["trace[1702763274] 'process raft request' (duration: 5.52237396s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.553785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:10.030945Z","time spent":"5.52280529s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":720,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:40:15.553799Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"087ba0346a6fab93","error":"lease not found"} {"level":"info","ts":"2026-08-24T15:40:15.55386Z","caller":"traceutil/trace.go:171","msg":"trace[422367223] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"2.949383939s","start":"2026-08-24T15:40:12.604469Z","end":"2026-08-24T15:40:15.553853Z","steps":["trace[422367223] 'process raft request' (duration: 2.949262982s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.553896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:12.604437Z","time spent":"2.94944002s","remote":"127.0.0.1:53622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:40:15.553883Z","caller":"traceutil/trace.go:171","msg":"trace[493684967] linearizableReadLoop","detail":"{readStateIndex:2381; appliedIndex:2366; }","duration":"2.956397419s","start":"2026-08-24T15:40:12.597461Z","end":"2026-08-24T15:40:15.553858Z","steps":["trace[493684967] 'read index received' (duration: 2.786090895s)","trace[493684967] 'applied index is now lower than readState.Index' (duration: 170.305424ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:40:15.553913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.659102481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:15.553922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.118345901s","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":"info","ts":"2026-08-24T15:40:15.553944Z","caller":"traceutil/trace.go:171","msg":"trace[1015270143] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2288; }","duration":"6.659164172s","start":"2026-08-24T15:40:08.894768Z","end":"2026-08-24T15:40:15.553933Z","steps":["trace[1015270143] 'agreement among raft nodes before linearized reading' (duration: 6.659098684s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.553949Z","caller":"traceutil/trace.go:171","msg":"trace[1186845773] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2288; }","duration":"8.118413762s","start":"2026-08-24T15:40:07.435528Z","end":"2026-08-24T15:40:15.553941Z","steps":["trace[1186845773] 'agreement among raft nodes before linearized reading' (duration: 8.118352754s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.553965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:08.894753Z","time spent":"6.659206242s","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.55397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:07.43551Z","time spent":"8.118456493s","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.553983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.026947625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:40:15.554003Z","caller":"traceutil/trace.go:171","msg":"trace[1639753742] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2288; }","duration":"9.026996796s","start":"2026-08-24T15:40:06.527Z","end":"2026-08-24T15:40:15.553997Z","steps":["trace[1639753742] 'agreement among raft nodes before linearized reading' (duration: 9.026951177s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.43548166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:15.554021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:06.526961Z","time spent":"9.027054418s","remote":"127.0.0.1:53730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:40:15.55404Z","caller":"traceutil/trace.go:171","msg":"trace[1652890669] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2288; }","duration":"9.435570221s","start":"2026-08-24T15:40:06.11846Z","end":"2026-08-24T15:40:15.554031Z","steps":["trace[1652890669] 'agreement among raft nodes before linearized reading' (duration: 9.435501663s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:06.118436Z","time spent":"9.435622032s","remote":"127.0.0.1:45408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:40:15.554073Z","caller":"traceutil/trace.go:171","msg":"trace[1535277893] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2288; }","duration":"2.231045929s","start":"2026-08-24T15:40:13.323018Z","end":"2026-08-24T15:40:15.554064Z","steps":["trace[1535277893] 'process raft request' (duration: 2.230801856s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.40954169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-08-24T15:40:15.554117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:13.322991Z","time spent":"2.231102599s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:40:15.554132Z","caller":"traceutil/trace.go:171","msg":"trace[989302954] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2288; }","duration":"7.409603882s","start":"2026-08-24T15:40:08.144523Z","end":"2026-08-24T15:40:15.554127Z","steps":["trace[989302954] 'agreement among raft nodes before linearized reading' (duration: 7.409558034s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.337840232s","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-08-24T15:40:15.554146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:08.144502Z","time spent":"7.409640083s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-24T15:40:15.554154Z","caller":"traceutil/trace.go:171","msg":"trace[1996256670] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2288; }","duration":"8.337901854s","start":"2026-08-24T15:40:07.216245Z","end":"2026-08-24T15:40:15.554147Z","steps":["trace[1996256670] 'agreement among raft nodes before linearized reading' (duration: 8.337860425s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:07.216223Z","time spent":"8.337943964s","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.414147852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:15.554208Z","caller":"traceutil/trace.go:171","msg":"trace[1968649187] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2288; }","duration":"7.414198414s","start":"2026-08-24T15:40:08.140005Z","end":"2026-08-24T15:40:15.554204Z","steps":["trace[1968649187] 'agreement among raft nodes before linearized reading' (duration: 7.414177105s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.954525892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.129\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:15.554223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:08.139982Z","time spent":"7.414235226s","remote":"127.0.0.1:53592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.55422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.096881047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:15.554234Z","caller":"traceutil/trace.go:171","msg":"trace[1432773450] range","detail":"{range_begin:/registry/masterleases/199.19.213.129; range_end:; response_count:0; response_revision:2288; }","duration":"2.954592892s","start":"2026-08-24T15:40:12.59963Z","end":"2026-08-24T15:40:15.554223Z","steps":["trace[1432773450] 'agreement among raft nodes before linearized reading' (duration: 2.954521414s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.554246Z","caller":"traceutil/trace.go:171","msg":"trace[934555692] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2288; }","duration":"3.096943949s","start":"2026-08-24T15:40:12.457293Z","end":"2026-08-24T15:40:15.554237Z","steps":["trace[934555692] 'agreement among raft nodes before linearized reading' (duration: 3.09690714s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:12.457275Z","time spent":"3.096986839s","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:12.599615Z","time spent":"2.954647091s","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.129\" "} {"level":"warn","ts":"2026-08-24T15:40:15.554318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.0139884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T15:40:15.554329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.257566031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:40:15.554341Z","caller":"traceutil/trace.go:171","msg":"trace[1226298461] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2288; }","duration":"9.014041751s","start":"2026-08-24T15:40:06.540293Z","end":"2026-08-24T15:40:15.554335Z","steps":["trace[1226298461] 'agreement among raft nodes before linearized reading' (duration: 9.013970163s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.554352Z","caller":"traceutil/trace.go:171","msg":"trace[1716832956] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2288; }","duration":"5.257627422s","start":"2026-08-24T15:40:10.296718Z","end":"2026-08-24T15:40:15.554346Z","steps":["trace[1716832956] 'agreement among raft nodes before linearized reading' (duration: 5.257588633s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:06.540276Z","time spent":"9.014079382s","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":67,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:10.296698Z","time spent":"5.257668083s","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.912967633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11628"} {"level":"info","ts":"2026-08-24T15:40:15.554412Z","caller":"traceutil/trace.go:171","msg":"trace[1800320566] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:2288; }","duration":"6.913332595s","start":"2026-08-24T15:40:08.641072Z","end":"2026-08-24T15:40:15.554405Z","steps":["trace[1800320566] 'agreement among raft nodes before linearized reading' (duration: 6.912928277s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:08.641053Z","time spent":"6.913376296s","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":11651,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-08-24T15:40:15.554445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.684174813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:15.554486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.90274568s","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":"info","ts":"2026-08-24T15:40:15.554486Z","caller":"traceutil/trace.go:171","msg":"trace[1329683338] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2288; }","duration":"9.684215982s","start":"2026-08-24T15:40:05.870258Z","end":"2026-08-24T15:40:15.554474Z","steps":["trace[1329683338] 'agreement among raft nodes before linearized reading' (duration: 9.684137114s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.554507Z","caller":"traceutil/trace.go:171","msg":"trace[1468461604] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2288; }","duration":"3.902799272s","start":"2026-08-24T15:40:11.651701Z","end":"2026-08-24T15:40:15.5545Z","steps":["trace[1468461604] 'agreement among raft nodes before linearized reading' (duration: 3.902763613s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:05.870194Z","time spent":"9.684315535s","remote":"127.0.0.1:58530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:11.651684Z","time spent":"3.902836892s","remote":"127.0.0.1:45466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130244361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:40:15.554632Z","caller":"traceutil/trace.go:171","msg":"trace[560757640] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2288; }","duration":"1.130331993s","start":"2026-08-24T15:40:14.424294Z","end":"2026-08-24T15:40:15.554627Z","steps":["trace[560757640] 'agreement among raft nodes before linearized reading' (duration: 1.130287115s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.578976877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:15.554648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:14.424275Z","time spent":"1.130369044s","remote":"127.0.0.1:53710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":15,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:40:15.554655Z","caller":"traceutil/trace.go:171","msg":"trace[1631481636] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2288; }","duration":"4.579010218s","start":"2026-08-24T15:40:10.97564Z","end":"2026-08-24T15:40:15.55465Z","steps":["trace[1631481636] 'agreement among raft nodes before linearized reading' (duration: 4.578975869s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.55467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:10.975632Z","time spent":"4.579033707s","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.167070214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-24T15:40:15.554704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721904031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:15.554709Z","caller":"traceutil/trace.go:171","msg":"trace[738293464] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:2288; }","duration":"5.167134566s","start":"2026-08-24T15:40:10.387568Z","end":"2026-08-24T15:40:15.554703Z","steps":["trace[738293464] 'agreement among raft nodes before linearized reading' (duration: 5.167050648s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.554723Z","caller":"traceutil/trace.go:171","msg":"trace[1599075279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2288; }","duration":"1.721967263s","start":"2026-08-24T15:40:13.83275Z","end":"2026-08-24T15:40:15.554718Z","steps":["trace[1599075279] 'agreement among raft nodes before linearized reading' (duration: 1.721935454s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:10.387552Z","time spent":"5.167170746s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":513,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-08-24T15:40:15.55473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.643035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T15:40:15.554739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:13.832727Z","time spent":"1.722008544s","remote":"127.0.0.1:53558","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-08-24T15:40:15.554745Z","caller":"traceutil/trace.go:171","msg":"trace[1772710705] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2288; }","duration":"704.708798ms","start":"2026-08-24T15:40:14.850032Z","end":"2026-08-24T15:40:15.554741Z","steps":["trace[1772710705] 'agreement among raft nodes before linearized reading' (duration: 704.677809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.230226425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T15:40:15.554759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:14.850011Z","time spent":"704.744739ms","remote":"127.0.0.1:53682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:40:15.554771Z","caller":"traceutil/trace.go:171","msg":"trace[541248239] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2288; }","duration":"2.230303555s","start":"2026-08-24T15:40:13.324458Z","end":"2026-08-24T15:40:15.554761Z","steps":["trace[541248239] 'agreement among raft nodes before linearized reading' (duration: 2.230234117s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:13.324443Z","time spent":"2.230352626s","remote":"127.0.0.1:47628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.871772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:15.554821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.583836006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:40:15.554837Z","caller":"traceutil/trace.go:171","msg":"trace[1561908565] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2288; }","duration":"849.920242ms","start":"2026-08-24T15:40:14.704912Z","end":"2026-08-24T15:40:15.554832Z","steps":["trace[1561908565] 'agreement among raft nodes before linearized reading' (duration: 849.895793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479309278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:15.554844Z","caller":"traceutil/trace.go:171","msg":"trace[844408977] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2288; }","duration":"4.583894467s","start":"2026-08-24T15:40:10.970943Z","end":"2026-08-24T15:40:15.554838Z","steps":["trace[844408977] 'agreement among raft nodes before linearized reading' (duration: 4.583841789s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.55485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:14.704893Z","time spent":"849.953423ms","remote":"127.0.0.1:53712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:40:15.554862Z","caller":"traceutil/trace.go:171","msg":"trace[725003666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2288; }","duration":"1.47936403s","start":"2026-08-24T15:40:14.075491Z","end":"2026-08-24T15:40:15.554855Z","steps":["trace[725003666] 'agreement among raft nodes before linearized reading' (duration: 1.479331401s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:10.970922Z","time spent":"4.583938928s","remote":"127.0.0.1:53636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:14.075469Z","time spent":"1.47940591s","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:40:15.554911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633089088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:15.554925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.653475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:15.554929Z","caller":"traceutil/trace.go:171","msg":"trace[1690995753] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2288; }","duration":"1.63314673s","start":"2026-08-24T15:40:13.921777Z","end":"2026-08-24T15:40:15.554924Z","steps":["trace[1690995753] 'agreement among raft nodes before linearized reading' (duration: 1.633116701s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.55494Z","caller":"traceutil/trace.go:171","msg":"trace[30793676] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2288; }","duration":"916.712637ms","start":"2026-08-24T15:40:14.638222Z","end":"2026-08-24T15:40:15.554935Z","steps":["trace[30793676] 'agreement among raft nodes before linearized reading' (duration: 916.685797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.554944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:13.921757Z","time spent":"1.63318311s","remote":"127.0.0.1:45530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.554954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:14.638198Z","time spent":"916.752368ms","remote":"127.0.0.1:40168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:15.55502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093225341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:15.555032Z","caller":"traceutil/trace.go:171","msg":"trace[403728072] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2288; }","duration":"1.093281753s","start":"2026-08-24T15:40:14.461746Z","end":"2026-08-24T15:40:15.555028Z","steps":["trace[403728072] 'agreement among raft nodes before linearized reading' (duration: 1.093261434s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.555044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:14.461724Z","time spent":"1.093317044s","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:40:15.696413Z","caller":"traceutil/trace.go:171","msg":"trace[1710768507] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"121.334024ms","start":"2026-08-24T15:40:15.57506Z","end":"2026-08-24T15:40:15.696395Z","steps":["trace[1710768507] 'process raft request' (duration: 92.902848ms)","trace[1710768507] 'compare' (duration: 28.211132ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:40:15.696479Z","caller":"traceutil/trace.go:171","msg":"trace[729021335] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"119.120056ms","start":"2026-08-24T15:40:15.57733Z","end":"2026-08-24T15:40:15.69645Z","steps":["trace[729021335] 'process raft request' (duration: 118.96342ms)"],"step_count":1}