{"level":"warn","ts":"2026-08-19T09:05:52.659099Z","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-19T09:05:52.659418Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-19T09:05:52.65952Z","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-19T09:05:52.659535Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-08-19T09:05:52.659557Z","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-19T09:05:52.660393Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-08-19T09:05:52.660497Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-19T09:05:52.663037Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.2691ms"} {"level":"info","ts":"2026-08-19T09:05:52.674121Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-08-19T09:05:52.67421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T09:05:52.67423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-08-19T09:05:52.674241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T09:05:52.674255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-08-19T09:05:52.674325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-08-19T09:05:52.679632Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T09:05:52.681783Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T09:05:52.684494Z","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-19T09:05:52.686917Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T09:05:52.687319Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T09:05:52.687342Z","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-19T09:05:52.687505Z","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-19T09:05:52.687535Z","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-19T09:05:52.688682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-08-19T09:05:52.689628Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-08-19T09:05:52.691767Z","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-19T09:05:52.691918Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-08-19T09:05:52.692003Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-08-19T09:05:52.692093Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T09:05:52.692137Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T09:05:53.675203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T09:05:53.675288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T09:05:53.675335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-08-19T09:05:53.675361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-08-19T09:05:53.675373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-08-19T09:05:53.6754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-08-19T09:05:53.675415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-08-19T09:05:53.676572Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:05:53.677451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T09:05:53.677626Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:05:53.677762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:05:53.677883Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T09:05:53.677949Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T09:05:53.67811Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:05:53.67826Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:05:53.678315Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:05:53.680879Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-08-19T09:05:53.68129Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T09:06:11.42634Z","caller":"traceutil/trace.go:171","msg":"trace[564113025] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"184.443792ms","start":"2026-08-19T09:06:11.241862Z","end":"2026-08-19T09:06:11.426306Z","steps":["trace[564113025] 'process raft request' (duration: 184.183075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:11.620794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.321562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:06:11.62102Z","caller":"traceutil/trace.go:171","msg":"trace[390131906] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"189.563755ms","start":"2026-08-19T09:06:11.431413Z","end":"2026-08-19T09:06:11.620977Z","steps":["trace[390131906] 'process raft request' (duration: 57.319208ms)","trace[390131906] 'compare' (duration: 131.150848ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:22.099405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.975502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:22.099512Z","caller":"traceutil/trace.go:171","msg":"trace[448449636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:443; }","duration":"148.195868ms","start":"2026-08-19T09:06:21.951296Z","end":"2026-08-19T09:06:22.099492Z","steps":["trace[448449636] 'range keys from in-memory index tree' (duration: 147.89218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:29.839003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.999097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:06:29.839253Z","caller":"traceutil/trace.go:171","msg":"trace[1317064029] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"270.831863ms","start":"2026-08-19T09:06:29.568396Z","end":"2026-08-19T09:06:29.839227Z","steps":["trace[1317064029] 'process raft request' (duration: 42.477417ms)","trace[1317064029] 'compare' (duration: 227.73885ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:29.862065Z","caller":"traceutil/trace.go:171","msg":"trace[2058283819] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"161.505878ms","start":"2026-08-19T09:06:29.700525Z","end":"2026-08-19T09:06:29.862031Z","steps":["trace[2058283819] 'read index received' (duration: 161.172479ms)","trace[2058283819] 'applied index is now lower than readState.Index' (duration: 332.009µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:29.862309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.761624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-19T09:06:29.862343Z","caller":"traceutil/trace.go:171","msg":"trace[270354848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"161.867988ms","start":"2026-08-19T09:06:29.700464Z","end":"2026-08-19T09:06:29.862332Z","steps":["trace[270354848] 'agreement among raft nodes before linearized reading' (duration: 161.683263ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:29.862592Z","caller":"traceutil/trace.go:171","msg":"trace[1465181992] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"269.457437ms","start":"2026-08-19T09:06:29.593124Z","end":"2026-08-19T09:06:29.862581Z","steps":["trace[1465181992] 'process raft request' (duration: 268.582534ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:29.862681Z","caller":"traceutil/trace.go:171","msg":"trace[119177029] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"266.838599ms","start":"2026-08-19T09:06:29.595832Z","end":"2026-08-19T09:06:29.86267Z","steps":["trace[119177029] 'process raft request' (duration: 266.1151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:36.4629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.931946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:06:36.463041Z","caller":"traceutil/trace.go:171","msg":"trace[1063367508] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"428.673734ms","start":"2026-08-19T09:06:36.034347Z","end":"2026-08-19T09:06:36.463021Z","steps":["trace[1063367508] 'process raft request' (duration: 138.525003ms)","trace[1063367508] 'compare' (duration: 289.540515ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:36.463186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:36.034318Z","time spent":"428.761637ms","remote":"127.0.0.1:45100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:36.485045Z","caller":"traceutil/trace.go:171","msg":"trace[1274310009] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"316.218367ms","start":"2026-08-19T09:06:36.168804Z","end":"2026-08-19T09:06:36.485022Z","steps":["trace[1274310009] 'process raft request' (duration: 316.105984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:36.485192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:36.168752Z","time spent":"316.35841ms","remote":"127.0.0.1:45282","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-19T09:06:37.007539Z","caller":"traceutil/trace.go:171","msg":"trace[1367315682] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"364.937999ms","start":"2026-08-19T09:06:36.64258Z","end":"2026-08-19T09:06:37.007518Z","steps":["trace[1367315682] 'read index received' (duration: 364.811995ms)","trace[1367315682] 'applied index is now lower than readState.Index' (duration: 124.814µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:37.007674Z","caller":"traceutil/trace.go:171","msg":"trace[706719663] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"457.232266ms","start":"2026-08-19T09:06:36.550398Z","end":"2026-08-19T09:06:37.00763Z","steps":["trace[706719663] 'process raft request' (duration: 457.001919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:37.007761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.257358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T09:06:37.007813Z","caller":"traceutil/trace.go:171","msg":"trace[1976252350] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:606; }","duration":"243.34267ms","start":"2026-08-19T09:06:36.764456Z","end":"2026-08-19T09:06:37.007798Z","steps":["trace[1976252350] 'agreement among raft nodes before linearized reading' (duration: 243.251928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:37.007889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:36.550362Z","time spent":"457.428941ms","remote":"127.0.0.1:45198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:06:37.007735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.144064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dbj2l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-19T09:06:37.008056Z","caller":"traceutil/trace.go:171","msg":"trace[1933810018] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dbj2l; range_end:; response_count:1; response_revision:606; }","duration":"365.509003ms","start":"2026-08-19T09:06:36.642536Z","end":"2026-08-19T09:06:37.008045Z","steps":["trace[1933810018] 'agreement among raft nodes before linearized reading' (duration: 365.113243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:37.008101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:36.642518Z","time spent":"365.571035ms","remote":"127.0.0.1:45198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-dbj2l\" "} {"level":"warn","ts":"2026-08-19T09:06:51.828729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.830245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:06:51.828849Z","caller":"traceutil/trace.go:171","msg":"trace[696483935] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"275.614569ms","start":"2026-08-19T09:06:51.553216Z","end":"2026-08-19T09:06:51.82883Z","steps":["trace[696483935] 'read index received' (duration: 148.590128ms)","trace[696483935] 'applied index is now lower than readState.Index' (duration: 127.02279ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:51.828956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.750932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:51.828993Z","caller":"traceutil/trace.go:171","msg":"trace[1684834468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"275.832935ms","start":"2026-08-19T09:06:51.553147Z","end":"2026-08-19T09:06:51.82898Z","steps":["trace[1684834468] 'agreement among raft nodes before linearized reading' (duration: 275.777063ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:51.828969Z","caller":"traceutil/trace.go:171","msg":"trace[608574366] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"298.49809ms","start":"2026-08-19T09:06:51.530434Z","end":"2026-08-19T09:06:51.828932Z","steps":["trace[608574366] 'process raft request' (duration: 171.362357ms)","trace[608574366] 'compare' (duration: 126.700712ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:51.829269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.643088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T09:06:51.829345Z","caller":"traceutil/trace.go:171","msg":"trace[713728889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"132.777882ms","start":"2026-08-19T09:06:51.696546Z","end":"2026-08-19T09:06:51.829324Z","steps":["trace[713728889] 'agreement among raft nodes before linearized reading' (duration: 132.507134ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:25.650663Z","caller":"traceutil/trace.go:171","msg":"trace[1783428741] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"170.083024ms","start":"2026-08-19T09:08:25.480554Z","end":"2026-08-19T09:08:25.650637Z","steps":["trace[1783428741] 'process raft request' (duration: 169.93595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:04.829357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.219322ms","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":"warn","ts":"2026-08-19T09:09:04.829357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.916499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-19T09:09:04.829527Z","caller":"traceutil/trace.go:171","msg":"trace[1541552656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2193; }","duration":"169.435838ms","start":"2026-08-19T09:09:04.660065Z","end":"2026-08-19T09:09:04.829501Z","steps":["trace[1541552656] 'range keys from in-memory index tree' (duration: 169.052148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:04.829519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.231015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T09:09:04.829579Z","caller":"traceutil/trace.go:171","msg":"trace[1369591714] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2193; }","duration":"175.186395ms","start":"2026-08-19T09:09:04.654365Z","end":"2026-08-19T09:09:04.829552Z","steps":["trace[1369591714] 'range keys from in-memory index tree' (duration: 174.623551ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:04.829623Z","caller":"traceutil/trace.go:171","msg":"trace[1505772342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2193; }","duration":"173.409159ms","start":"2026-08-19T09:09:04.656194Z","end":"2026-08-19T09:09:04.829603Z","steps":["trace[1505772342] 'range keys from in-memory index tree' (duration: 173.01901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:09:05.108964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.963272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:09:05.109451Z","caller":"traceutil/trace.go:171","msg":"trace[29114700] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"273.027915ms","start":"2026-08-19T09:09:04.836405Z","end":"2026-08-19T09:09:05.109432Z","steps":["trace[29114700] 'process raft request' (duration: 272.697167ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:05.109498Z","caller":"traceutil/trace.go:171","msg":"trace[1129233135] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"273.137518ms","start":"2026-08-19T09:09:04.836325Z","end":"2026-08-19T09:09:05.109462Z","steps":["trace[1129233135] 'process raft request' (duration: 116.338995ms)","trace[1129233135] 'compare' (duration: 155.434258ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:09:05.10952Z","caller":"traceutil/trace.go:171","msg":"trace[765309847] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"273.079537ms","start":"2026-08-19T09:09:04.836414Z","end":"2026-08-19T09:09:05.109494Z","steps":["trace[765309847] 'process raft request' (duration: 272.80857ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:05.109621Z","caller":"traceutil/trace.go:171","msg":"trace[1532838485] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"272.985255ms","start":"2026-08-19T09:09:04.836589Z","end":"2026-08-19T09:09:05.109574Z","steps":["trace[1532838485] 'process raft request' (duration: 272.685747ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:05.109353Z","caller":"traceutil/trace.go:171","msg":"trace[796670964] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2276; }","duration":"258.39596ms","start":"2026-08-19T09:09:04.850933Z","end":"2026-08-19T09:09:05.109329Z","steps":["trace[796670964] 'read index received' (duration: 29.650281ms)","trace[796670964] 'applied index is now lower than readState.Index' (duration: 228.744539ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:09:05.109872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.919284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-19T09:09:05.109889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.702252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:09:05.109914Z","caller":"traceutil/trace.go:171","msg":"trace[1968704287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2198; }","duration":"258.996635ms","start":"2026-08-19T09:09:04.850904Z","end":"2026-08-19T09:09:05.109901Z","steps":["trace[1968704287] 'agreement among raft nodes before linearized reading' (duration: 258.843111ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:09:05.109936Z","caller":"traceutil/trace.go:171","msg":"trace[1888530418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2198; }","duration":"158.780774ms","start":"2026-08-19T09:09:04.95114Z","end":"2026-08-19T09:09:05.109921Z","steps":["trace[1888530418] 'agreement among raft nodes before linearized reading' (duration: 158.706112ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:10:22.994786Z","caller":"traceutil/trace.go:171","msg":"trace[637782395] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"106.808822ms","start":"2026-08-19T09:10:22.88795Z","end":"2026-08-19T09:10:22.994758Z","steps":["trace[637782395] 'process raft request' (duration: 106.527974ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:28.158366Z","caller":"traceutil/trace.go:171","msg":"trace[967666859] transaction","detail":"{read_only:false; response_revision:3165; number_of_response:1; }","duration":"189.352956ms","start":"2026-08-19T09:11:27.968979Z","end":"2026-08-19T09:11:28.158332Z","steps":["trace[967666859] 'process raft request' (duration: 189.259324ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:28.158598Z","caller":"traceutil/trace.go:171","msg":"trace[2108094896] transaction","detail":"{read_only:false; response_revision:3166; number_of_response:1; }","duration":"188.634917ms","start":"2026-08-19T09:11:27.969932Z","end":"2026-08-19T09:11:28.158566Z","steps":["trace[2108094896] 'process raft request' (duration: 188.36012ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:28.1595Z","caller":"traceutil/trace.go:171","msg":"trace[1184760356] transaction","detail":"{read_only:false; response_revision:3164; number_of_response:1; }","duration":"189.830709ms","start":"2026-08-19T09:11:27.968951Z","end":"2026-08-19T09:11:28.158781Z","steps":["trace[1184760356] 'process raft request' (duration: 189.048569ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:33.619627Z","caller":"traceutil/trace.go:171","msg":"trace[334970066] transaction","detail":"{read_only:false; response_revision:3192; number_of_response:1; }","duration":"314.478128ms","start":"2026-08-19T09:11:33.305122Z","end":"2026-08-19T09:11:33.6196Z","steps":["trace[334970066] 'process raft request' (duration: 314.282833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:33.61982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:33.305104Z","time spent":"314.615681ms","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:11:33.746148Z","caller":"traceutil/trace.go:171","msg":"trace[891066444] linearizableReadLoop","detail":"{readStateIndex:3315; appliedIndex:3313; }","duration":"145.491803ms","start":"2026-08-19T09:11:33.600622Z","end":"2026-08-19T09:11:33.746113Z","steps":["trace[891066444] 'read index received' (duration: 18.877882ms)","trace[891066444] 'applied index is now lower than readState.Index' (duration: 126.612161ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:11:33.746323Z","caller":"traceutil/trace.go:171","msg":"trace[1299241955] transaction","detail":"{read_only:false; response_revision:3193; number_of_response:1; }","duration":"272.150344ms","start":"2026-08-19T09:11:33.474157Z","end":"2026-08-19T09:11:33.746308Z","steps":["trace[1299241955] 'process raft request' (duration: 223.65502ms)","trace[1299241955] 'compare' (duration: 48.139995ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:11:33.74653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.79587ms","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-19T09:11:33.746615Z","caller":"traceutil/trace.go:171","msg":"trace[763705513] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3193; }","duration":"146.039397ms","start":"2026-08-19T09:11:33.600561Z","end":"2026-08-19T09:11:33.7466Z","steps":["trace[763705513] 'agreement among raft nodes before linearized reading' (duration: 145.80355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:35.450956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698514,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:11:35.951211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698514,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:11:36.452511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698514,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:11:36.647055Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.231434021s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-19T09:11:36.657512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:35.484368Z","time spent":"1.17313298s","remote":"127.0.0.1:41392","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-19T09:11:36.657596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.668447684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-19T09:11:36.657655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.335887984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:11:36.657654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.471780296s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-19T09:11:36.657711Z","caller":"traceutil/trace.go:171","msg":"trace[70622379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3193; }","duration":"2.668626758s","start":"2026-08-19T09:11:33.989056Z","end":"2026-08-19T09:11:36.657683Z","steps":["trace[70622379] 'range keys from in-memory index tree' (duration: 2.668186447s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:36.657744Z","caller":"traceutil/trace.go:171","msg":"trace[644276189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3193; }","duration":"2.336038678s","start":"2026-08-19T09:11:34.321685Z","end":"2026-08-19T09:11:36.657724Z","steps":["trace[644276189] 'count revisions from in-memory index tree' (duration: 2.335788952s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:36.657771Z","caller":"traceutil/trace.go:171","msg":"trace[220724029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3193; }","duration":"2.471952212s","start":"2026-08-19T09:11:34.185787Z","end":"2026-08-19T09:11:36.657739Z","steps":["trace[220724029] 'range keys from in-memory index tree' (duration: 2.47153573s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.657788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:33.989032Z","time spent":"2.668741521s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:11:36.657839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.18576Z","time spent":"2.472068884s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T09:11:36.657858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.471290124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-19T09:11:36.657786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.321662Z","time spent":"2.336114781s","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:11:36.657951Z","caller":"traceutil/trace.go:171","msg":"trace[826207015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3193; }","duration":"2.471391236s","start":"2026-08-19T09:11:34.186536Z","end":"2026-08-19T09:11:36.657927Z","steps":["trace[826207015] 'range keys from in-memory index tree' (duration: 2.47114233s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.658004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.186527Z","time spent":"2.471467428s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:11:36.65798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.471551961s","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-19T09:11:36.658063Z","caller":"traceutil/trace.go:171","msg":"trace[611811878] transaction","detail":"{read_only:false; response_revision:3194; number_of_response:1; }","duration":"2.242597713s","start":"2026-08-19T09:11:34.415452Z","end":"2026-08-19T09:11:36.65805Z","steps":["trace[611811878] 'process raft request' (duration: 2.231992945s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:36.658084Z","caller":"traceutil/trace.go:171","msg":"trace[112189531] transaction","detail":"{read_only:false; response_revision:3195; number_of_response:1; }","duration":"1.85233324s","start":"2026-08-19T09:11:34.805741Z","end":"2026-08-19T09:11:36.658074Z","steps":["trace[112189531] 'process raft request' (duration: 1.852223407s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:36.658138Z","caller":"traceutil/trace.go:171","msg":"trace[2002983295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3193; }","duration":"2.471767616s","start":"2026-08-19T09:11:34.186331Z","end":"2026-08-19T09:11:36.658098Z","steps":["trace[2002983295] 'range keys from in-memory index tree' (duration: 2.471278703s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.658169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.805708Z","time spent":"1.852400672s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:11:36.658188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.415417Z","time spent":"2.242688605s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:11:36.658207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.186285Z","time spent":"2.47190676s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T09:11:36.658284Z","caller":"traceutil/trace.go:171","msg":"trace[1753713740] transaction","detail":"{read_only:false; response_revision:3196; number_of_response:1; }","duration":"1.715230745s","start":"2026-08-19T09:11:34.943043Z","end":"2026-08-19T09:11:36.658274Z","steps":["trace[1753713740] 'process raft request' (duration: 1.714995889s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:36.658288Z","caller":"traceutil/trace.go:171","msg":"trace[1967329520] transaction","detail":"{read_only:false; response_revision:3197; number_of_response:1; }","duration":"1.032839092s","start":"2026-08-19T09:11:35.625417Z","end":"2026-08-19T09:11:36.658257Z","steps":["trace[1967329520] 'process raft request' (duration: 1.032721919s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.658345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.943005Z","time spent":"1.715309428s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:11:36.658422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:35.625386Z","time spent":"1.032946685s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:11:36.658462Z","caller":"traceutil/trace.go:171","msg":"trace[1278999588] transaction","detail":"{read_only:false; response_revision:3198; number_of_response:1; }","duration":"904.657101ms","start":"2026-08-19T09:11:35.753795Z","end":"2026-08-19T09:11:36.658452Z","steps":["trace[1278999588] 'process raft request' (duration: 904.431755ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:36.658456Z","caller":"traceutil/trace.go:171","msg":"trace[249491687] linearizableReadLoop","detail":"{readStateIndex:3320; appliedIndex:3315; }","duration":"1.708303836s","start":"2026-08-19T09:11:34.950132Z","end":"2026-08-19T09:11:36.658435Z","steps":["trace[249491687] 'read index received' (duration: 1.697301818s)","trace[249491687] 'applied index is now lower than readState.Index' (duration: 11.000348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:11:36.658516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:35.753766Z","time spent":"904.720993ms","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:11:36.658632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70850231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:11:36.658682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.498679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T09:11:36.658701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379018422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-19T09:11:36.658686Z","caller":"traceutil/trace.go:171","msg":"trace[1536085333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3198; }","duration":"1.708593623s","start":"2026-08-19T09:11:34.950079Z","end":"2026-08-19T09:11:36.658672Z","steps":["trace[1536085333] 'agreement among raft nodes before linearized reading' (duration: 1.70847068s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:36.658723Z","caller":"traceutil/trace.go:171","msg":"trace[949717022] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3198; }","duration":"1.379082923s","start":"2026-08-19T09:11:35.279634Z","end":"2026-08-19T09:11:36.658717Z","steps":["trace[949717022] 'agreement among raft nodes before linearized reading' (duration: 1.37897314s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.658714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.679156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:11:36.65874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.950065Z","time spent":"1.708664955s","remote":"127.0.0.1:45006","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-19T09:11:36.658748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:35.279611Z","time spent":"1.379133645s","remote":"127.0.0.1:45208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-19T09:11:36.658768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.85165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:11:36.658813Z","caller":"traceutil/trace.go:171","msg":"trace[2091275375] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:3198; }","duration":"182.949252ms","start":"2026-08-19T09:11:36.475854Z","end":"2026-08-19T09:11:36.658803Z","steps":["trace[2091275375] 'agreement among raft nodes before linearized reading' (duration: 182.887561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.65888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.765594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:11:36.658772Z","caller":"traceutil/trace.go:171","msg":"trace[62361914] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:3198; }","duration":"698.798839ms","start":"2026-08-19T09:11:35.959952Z","end":"2026-08-19T09:11:36.65875Z","steps":["trace[62361914] 'agreement among raft nodes before linearized reading' (duration: 698.703176ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:36.658932Z","caller":"traceutil/trace.go:171","msg":"trace[337808339] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:3198; }","duration":"432.855416ms","start":"2026-08-19T09:11:36.226059Z","end":"2026-08-19T09:11:36.658915Z","steps":["trace[337808339] 'agreement among raft nodes before linearized reading' (duration: 432.787814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.658969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:35.959927Z","time spent":"699.033235ms","remote":"127.0.0.1:56714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:11:36.658971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:36.226033Z","time spent":"432.929248ms","remote":"127.0.0.1:57112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:11:36.658722Z","caller":"traceutil/trace.go:171","msg":"trace[1614097198] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3198; }","duration":"504.580001ms","start":"2026-08-19T09:11:36.15413Z","end":"2026-08-19T09:11:36.658709Z","steps":["trace[1614097198] 'agreement among raft nodes before linearized reading' (duration: 504.508969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.659139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:36.154099Z","time spent":"504.996242ms","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":6,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:11:36.659182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082641594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18cd29d006717aa3\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-19T09:11:36.659409Z","caller":"traceutil/trace.go:171","msg":"trace[148595363] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18cd29d006717aa3; range_end:; response_count:1; response_revision:3198; }","duration":"1.082912191s","start":"2026-08-19T09:11:35.576477Z","end":"2026-08-19T09:11:36.659389Z","steps":["trace[148595363] 'agreement among raft nodes before linearized reading' (duration: 1.082184962s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.65948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.63069687s","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-19T09:11:36.659536Z","caller":"traceutil/trace.go:171","msg":"trace[1223984378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3193; }","duration":"2.630809002s","start":"2026-08-19T09:11:34.028715Z","end":"2026-08-19T09:11:36.659524Z","steps":["trace[1223984378] 'range keys from in-memory index tree' (duration: 2.627907517s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:36.65951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:35.576452Z","time spent":"1.083040124s","remote":"127.0.0.1:45088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18cd29d006717aa3\" "} {"level":"warn","ts":"2026-08-19T09:11:36.659575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:11:34.028676Z","time spent":"2.630888104s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:12:08.452077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:08.953036Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:09.453339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:09.95201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000036198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:09.952108Z","caller":"traceutil/trace.go:171","msg":"trace[354206659] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000201033s","start":"2026-08-19T09:12:07.951884Z","end":"2026-08-19T09:12:09.952085Z","steps":["trace[354206659] 'agreement among raft nodes before linearized reading' (duration: 2.00008087s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:09.952161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:07.951855Z","time spent":"2.000296165s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:12:09 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:09.954138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:10.454971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:10.955177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:11.455557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:11.472287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999922734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:11.472353Z","caller":"traceutil/trace.go:171","msg":"trace[80943073] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000044658s","start":"2026-08-19T09:12:09.472286Z","end":"2026-08-19T09:12:11.47233Z","steps":["trace[80943073] 'agreement among raft nodes before linearized reading' (duration: 1.999952035s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:11.472411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:09.472271Z","time spent":"2.000121369s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:12:11 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:11.952234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999938715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:11.952356Z","caller":"traceutil/trace.go:171","msg":"trace[1132064441] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000098378s","start":"2026-08-19T09:12:09.952227Z","end":"2026-08-19T09:12:11.952325Z","steps":["trace[1132064441] 'agreement among raft nodes before linearized reading' (duration: 1.999962075s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:11.952411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:09.952211Z","time spent":"2.000188601s","remote":"127.0.0.1:45012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:12:11 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:11.956363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:12.457599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:12.958167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:13.459316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:13.553493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000165511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:13.553583Z","caller":"traceutil/trace.go:171","msg":"trace[58381689] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000321745s","start":"2026-08-19T09:12:11.553235Z","end":"2026-08-19T09:12:13.553557Z","steps":["trace[58381689] 'agreement among raft nodes before linearized reading' (duration: 2.000200312s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:13.553635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.553216Z","time spent":"2.000407268s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:12:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:13.788787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.790389Z","time spent":"4.998388755s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/19 09:12:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:13.793756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.79516Z","time spent":"4.99858892s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/19 09:12:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:13.79744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.799824Z","time spent":"4.997606144s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:13.797463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.798842Z","time spent":"4.998612411s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/19 09:12:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:13.872956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000066889s","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-19T09:12:13.873051Z","caller":"traceutil/trace.go:171","msg":"trace[1910984375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000211532s","start":"2026-08-19T09:12:08.87282Z","end":"2026-08-19T09:12:13.873032Z","steps":["trace[1910984375] 'agreement among raft nodes before linearized reading' (duration: 5.000095239s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:13.873139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.872805Z","time spent":"5.000321555s","remote":"127.0.0.1:45282","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-19T09:12:13.880674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999428712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-19T09:12:13.880812Z","caller":"traceutil/trace.go:171","msg":"trace[938259356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999627167s","start":"2026-08-19T09:12:08.881156Z","end":"2026-08-19T09:12:13.880783Z","steps":["trace[938259356] 'agreement among raft nodes before linearized reading' (duration: 4.999448383s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:13.880886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.881145Z","time spent":"4.99972799s","remote":"127.0.0.1:45282","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-19T09:12:13.88225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999523835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-19T09:12:13.882329Z","caller":"traceutil/trace.go:171","msg":"trace[360712135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999633737s","start":"2026-08-19T09:12:08.882677Z","end":"2026-08-19T09:12:13.882311Z","steps":["trace[360712135] 'agreement among raft nodes before linearized reading' (duration: 4.999545745s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:13.882378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.882666Z","time spent":"4.99970168s","remote":"127.0.0.1:45282","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/19 09:12:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:13.959966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:13.961176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000244622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-19T09:12:13.96129Z","caller":"traceutil/trace.go:171","msg":"trace[1680835654] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000487979s","start":"2026-08-19T09:12:11.960775Z","end":"2026-08-19T09:12:13.961263Z","steps":["trace[1680835654] 'agreement among raft nodes before linearized reading' (duration: 2.000325455s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:13.961359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.960759Z","time spent":"2.000589221s","remote":"127.0.0.1:45012","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-19T09:12:14.426487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:07.425454Z","time spent":"7.00102036s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:14.461128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:14.952354Z","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-19T09:12:15.453489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:15.738396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.737447Z","time spent":"7.000940597s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:15.954528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:15.971628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999683568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:15.971739Z","caller":"traceutil/trace.go:171","msg":"trace[1679816087] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000443278s","start":"2026-08-19T09:12:13.97127Z","end":"2026-08-19T09:12:15.971713Z","steps":["trace[1679816087] 'agreement among raft nodes before linearized reading' (duration: 2.000286544s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:15.971799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.971239Z","time spent":"2.000548121s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:12:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:16.455603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:16.956238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:17.457187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:17.95812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:17.972427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000419538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-19T09:12:17.972581Z","caller":"traceutil/trace.go:171","msg":"trace[1842173932] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000629464s","start":"2026-08-19T09:12:15.971902Z","end":"2026-08-19T09:12:17.972531Z","steps":["trace[1842173932] 'agreement among raft nodes before linearized reading' (duration: 2.000450159s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:17.972696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:15.971882Z","time spent":"2.000799668s","remote":"127.0.0.1:45012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:12:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.459242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:18.789777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998784824s","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-19T09:12:18.789908Z","caller":"traceutil/trace.go:171","msg":"trace[537461465] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; }","duration":"4.998976569s","start":"2026-08-19T09:12:13.790903Z","end":"2026-08-19T09:12:18.78988Z","steps":["trace[537461465] 'agreement among raft nodes before linearized reading' (duration: 4.998821525s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.789968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.790872Z","time spent":"4.999082962s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.794316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998986911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:18.79443Z","caller":"traceutil/trace.go:171","msg":"trace[1808221041] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; }","duration":"4.999156814s","start":"2026-08-19T09:12:13.795244Z","end":"2026-08-19T09:12:18.794401Z","steps":["trace[1808221041] 'agreement among raft nodes before linearized reading' (duration: 4.999004s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.794494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.795231Z","time spent":"4.999250956s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.798567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998712023s","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-19T09:12:18.798628Z","caller":"traceutil/trace.go:171","msg":"trace[70500924] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; }","duration":"4.998799384s","start":"2026-08-19T09:12:13.799814Z","end":"2026-08-19T09:12:18.798614Z","steps":["trace[70500924] 'agreement among raft nodes before linearized reading' (duration: 4.998730603s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.798666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.799804Z","time spent":"4.998851357s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.798723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998757083s","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-19T09:12:18.798781Z","caller":"traceutil/trace.go:171","msg":"trace[418020194] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.998872206s","start":"2026-08-19T09:12:13.799894Z","end":"2026-08-19T09:12:18.798766Z","steps":["trace[418020194] 'agreement among raft nodes before linearized reading' (duration: 4.998795334s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.798823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.799866Z","time spent":"4.998945199s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.82867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.241948019s","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-19T09:12:18.828759Z","caller":"traceutil/trace.go:171","msg":"trace[266898339] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; }","duration":"10.242119883s","start":"2026-08-19T09:12:08.586619Z","end":"2026-08-19T09:12:18.828739Z","steps":["trace[266898339] 'agreement among raft nodes before linearized reading' (duration: 10.2420005s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.828802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.58659Z","time spent":"10.242203615s","remote":"127.0.0.1:45198","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.872433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997783912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:18.872573Z","caller":"traceutil/trace.go:171","msg":"trace[1427001889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.997978267s","start":"2026-08-19T09:12:15.874571Z","end":"2026-08-19T09:12:18.872549Z","steps":["trace[1427001889] 'agreement among raft nodes before linearized reading' (duration: 2.997807583s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.872633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:15.874551Z","time spent":"2.99806671s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.880746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.995556694s","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-19T09:12:18.880863Z","caller":"traceutil/trace.go:171","msg":"trace[1950557523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.995674908s","start":"2026-08-19T09:12:15.885143Z","end":"2026-08-19T09:12:18.880818Z","steps":["trace[1950557523] 'agreement among raft nodes before linearized reading' (duration: 2.995570115s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.880904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:15.885131Z","time spent":"2.99576456s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.881395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000469286s","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-19T09:12:18.881487Z","caller":"traceutil/trace.go:171","msg":"trace[537473442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.000597039s","start":"2026-08-19T09:12:08.880869Z","end":"2026-08-19T09:12:18.881466Z","steps":["trace[537473442] 'agreement among raft nodes before linearized reading' (duration: 10.000486727s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.881536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.880858Z","time spent":"10.000667821s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.881637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000690931s","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-19T09:12:18.882102Z","caller":"traceutil/trace.go:171","msg":"trace[1520019134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.001211405s","start":"2026-08-19T09:12:08.880856Z","end":"2026-08-19T09:12:18.882068Z","steps":["trace[1520019134] 'agreement among raft nodes before linearized reading' (duration: 10.000705682s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.882232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.880846Z","time spent":"10.001346629s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.882661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997512555s","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-19T09:12:18.882706Z","caller":"traceutil/trace.go:171","msg":"trace[16671779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997624349s","start":"2026-08-19T09:12:15.88507Z","end":"2026-08-19T09:12:18.882695Z","steps":["trace[16671779] 'agreement among raft nodes before linearized reading' (duration: 2.997536196s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:18.882742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:15.885057Z","time spent":"2.99767601s","remote":"127.0.0.1:45282","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/19 09:12:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:18.957142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.956929Z","time spent":"7.000202289s","remote":"127.0.0.1:45088","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:18.959896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:19.123434Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"54cea01945143ebd","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-19T09:12:19.460214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:19.960861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:19.981696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000422209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:19.981857Z","caller":"traceutil/trace.go:171","msg":"trace[790310958] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000656185s","start":"2026-08-19T09:12:17.98116Z","end":"2026-08-19T09:12:19.981816Z","steps":["trace[790310958] 'agreement among raft nodes before linearized reading' (duration: 2.00047784s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:19.982022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:17.981142Z","time spent":"2.00086517s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:12:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:20.461863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:20.554405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.553597Z","time spent":"7.000802025s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:20.817643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.816472Z","time spent":"7.001162414s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:20.962103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:21.43316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:14.431917Z","time spent":"7.001232366s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:21.462936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636698990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:21.485607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:14.484433Z","time spent":"7.001168434s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:21.952759Z","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-19T09:12:21.952964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.14701815s","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":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-19T09:12:21.95303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.230290417s","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":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-19T09:12:21.953037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.673419116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-19T09:12:21.953059Z","caller":"traceutil/trace.go:171","msg":"trace[1409978124] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; }","duration":"8.147172534s","start":"2026-08-19T09:12:13.805864Z","end":"2026-08-19T09:12:21.953036Z","steps":["trace[1409978124] 'agreement among raft nodes before linearized reading' (duration: 8.147062251s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:21.953085Z","caller":"traceutil/trace.go:171","msg":"trace[1828798496] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; }","duration":"8.23038739s","start":"2026-08-19T09:12:13.722676Z","end":"2026-08-19T09:12:21.953064Z","steps":["trace[1828798496] 'agreement among raft nodes before linearized reading' (duration: 8.230339858s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:21.95309Z","caller":"traceutil/trace.go:171","msg":"trace[842187096] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; }","duration":"12.673501508s","start":"2026-08-19T09:12:09.279562Z","end":"2026-08-19T09:12:21.953064Z","steps":["trace[842187096] 'agreement among raft nodes before linearized reading' (duration: 12.673464747s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.953127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.805842Z","time spent":"8.147269757s","remote":"127.0.0.1:34232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:21.953088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.626874209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-19T09:12:21.95313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.265119469s","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":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-19T09:12:21.953208Z","caller":"traceutil/trace.go:171","msg":"trace[1723914140] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; }","duration":"12.627089115s","start":"2026-08-19T09:12:09.326093Z","end":"2026-08-19T09:12:21.953182Z","steps":["trace[1723914140] 'agreement among raft nodes before linearized reading' (duration: 12.62689539s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.95325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.674479037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-19T09:12:21.953292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.722642Z","time spent":"8.230472272s","remote":"127.0.0.1:33932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":0,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:12:21.953315Z","caller":"traceutil/trace.go:171","msg":"trace[545849356] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; }","duration":"7.674616961s","start":"2026-08-19T09:12:14.278685Z","end":"2026-08-19T09:12:21.953302Z","steps":["trace[545849356] 'agreement among raft nodes before linearized reading' (duration: 7.674512858s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.953252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.21062644s","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":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-19T09:12:21.953354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:14.278664Z","time spent":"7.674678673s","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":0,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:21.953374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:09.326069Z","time spent":"12.627242478s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":0,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:21.952982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.710201601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-19T09:12:21.95315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:09.279537Z","time spent":"12.673602051s","remote":"127.0.0.1:45208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":0,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-08-19T09:12:21.953916Z","caller":"traceutil/trace.go:171","msg":"trace[943863262] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; }","duration":"10.711154766s","start":"2026-08-19T09:12:11.242712Z","end":"2026-08-19T09:12:21.953867Z","steps":["trace[943863262] 'agreement among raft nodes before linearized reading' (duration: 10.710199381s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.95295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.562511696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-19T09:12:21.953993Z","caller":"traceutil/trace.go:171","msg":"trace[606262450] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; }","duration":"9.563634076s","start":"2026-08-19T09:12:12.390346Z","end":"2026-08-19T09:12:21.95398Z","steps":["trace[606262450] 'agreement among raft nodes before linearized reading' (duration: 9.562549567s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.953998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.242691Z","time spent":"10.71128638s","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":0,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:21.954047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:12.39032Z","time spent":"9.563713368s","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":0,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:12:21.953231Z","caller":"traceutil/trace.go:171","msg":"trace[1965005238] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; }","duration":"11.265298163s","start":"2026-08-19T09:12:10.687908Z","end":"2026-08-19T09:12:21.953206Z","steps":["trace[1965005238] 'agreement among raft nodes before linearized reading' (duration: 11.265139649s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.953196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.010513304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-19T09:12:21.95416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:10.687892Z","time spent":"11.266229097s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:12:21.954208Z","caller":"traceutil/trace.go:171","msg":"trace[558891488] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; }","duration":"12.011588913s","start":"2026-08-19T09:12:09.942606Z","end":"2026-08-19T09:12:21.954194Z","steps":["trace[558891488] 'agreement among raft nodes before linearized reading' (duration: 12.010557866s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.953265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.56736533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-19T09:12:21.954292Z","caller":"traceutil/trace.go:171","msg":"trace[1757519261] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; }","duration":"8.568434457s","start":"2026-08-19T09:12:13.385843Z","end":"2026-08-19T09:12:21.954277Z","steps":["trace[1757519261] 'agreement among raft nodes before linearized reading' (duration: 8.567415801s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.954329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:13.385821Z","time spent":"8.568496439s","remote":"127.0.0.1:34358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:12:21.953408Z","caller":"traceutil/trace.go:171","msg":"trace[1228666950] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; }","duration":"13.210870037s","start":"2026-08-19T09:12:08.742507Z","end":"2026-08-19T09:12:21.953377Z","steps":["trace[1228666950] 'agreement among raft nodes before linearized reading' (duration: 13.210634061s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.954435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:08.74249Z","time spent":"13.211933874s","remote":"127.0.0.1:34310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:21.953385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.547099877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-19T09:12:21.954492Z","caller":"traceutil/trace.go:171","msg":"trace[2019152283] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; }","duration":"7.54834083s","start":"2026-08-19T09:12:14.406141Z","end":"2026-08-19T09:12:21.954482Z","steps":["trace[2019152283] 'agreement among raft nodes before linearized reading' (duration: 7.547116148s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.954531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:14.406123Z","time spent":"7.548398502s","remote":"127.0.0.1:34354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:21.95425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:09.942582Z","time spent":"12.011658574s","remote":"127.0.0.1:45124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:21.983535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001369512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-19T09:12:21.983623Z","caller":"traceutil/trace.go:171","msg":"trace[667355719] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001526356s","start":"2026-08-19T09:12:19.982076Z","end":"2026-08-19T09:12:21.983602Z","steps":["trace[667355719] 'agreement among raft nodes before linearized reading' (duration: 2.001413123s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:21.983706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:19.982041Z","time spent":"2.001653289s","remote":"127.0.0.1:45012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/19 09:12:21 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-19T09:12:22.45377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369811636699000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:12:22.588619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"15.163005272s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:12:22.655779Z","caller":"traceutil/trace.go:171","msg":"trace[1411314686] transaction","detail":"{read_only:false; response_revision:3367; number_of_response:1; }","duration":"3.780383932s","start":"2026-08-19T09:12:18.875372Z","end":"2026-08-19T09:12:22.655756Z","steps":["trace[1411314686] 'process raft request' (duration: 3.780201378s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.655938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:18.875342Z","time spent":"3.780517356s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:12:22.65637Z","caller":"traceutil/trace.go:171","msg":"trace[1722732246] transaction","detail":"{read_only:false; response_revision:3368; number_of_response:1; }","duration":"3.772922447s","start":"2026-08-19T09:12:18.883429Z","end":"2026-08-19T09:12:22.656352Z","steps":["trace[1722732246] 'process raft request' (duration: 3.772298751s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:22.656354Z","caller":"traceutil/trace.go:171","msg":"trace[860548569] transaction","detail":"{read_only:false; response_revision:3369; number_of_response:1; }","duration":"3.7707565s","start":"2026-08-19T09:12:18.885562Z","end":"2026-08-19T09:12:22.656319Z","steps":["trace[860548569] 'process raft request' (duration: 3.770565615s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.656476Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"55.681µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-08-19T09:12:22.656571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:18.883404Z","time spent":"3.77303007s","remote":"127.0.0.1:45282","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-19T09:12:22.65684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:18.885553Z","time spent":"3.770885254s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:12:22.657084Z","caller":"traceutil/trace.go:171","msg":"trace[1278661968] transaction","detail":"{read_only:false; response_revision:3370; number_of_response:1; }","duration":"3.770990327s","start":"2026-08-19T09:12:18.886083Z","end":"2026-08-19T09:12:22.657073Z","steps":["trace[1278661968] 'process raft request' (duration: 3.770182646s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:22.657266Z","caller":"traceutil/trace.go:171","msg":"trace[480749546] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3370; }","duration":"1.21789951s","start":"2026-08-19T09:12:21.439348Z","end":"2026-08-19T09:12:22.657248Z","steps":["trace[480749546] 'process raft request' (duration: 1.217198222s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.657332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:21.439312Z","time spent":"1.217987243s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:12:22.657387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:18.886072Z","time spent":"3.771038128s","remote":"127.0.0.1:45282","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-19T09:12:22.657481Z","caller":"traceutil/trace.go:171","msg":"trace[387996228] transaction","detail":"{read_only:false; response_revision:3371; number_of_response:1; }","duration":"653.299449ms","start":"2026-08-19T09:12:22.004174Z","end":"2026-08-19T09:12:22.657473Z","steps":["trace[387996228] 'process raft request' (duration: 652.408246ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:22.657442Z","caller":"traceutil/trace.go:171","msg":"trace[1160559642] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3370; }","duration":"1.834739565s","start":"2026-08-19T09:12:20.822613Z","end":"2026-08-19T09:12:22.657353Z","steps":["trace[1160559642] 'process raft request' (duration: 1.833903863s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.657525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:22.004146Z","time spent":"653.359241ms","remote":"127.0.0.1:45100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74847,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:12:22.657608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:20.822588Z","time spent":"1.834943851s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:12:22.657748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:18.959038Z","time spent":"3.698707026s","remote":"127.0.0.1:45088","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-19T09:12:22.657826Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"54cea01945143ebd","error":"lease not found"} {"level":"info","ts":"2026-08-19T09:12:22.666751Z","caller":"traceutil/trace.go:171","msg":"trace[1750628202] linearizableReadLoop","detail":"{readStateIndex:3508; appliedIndex:3486; }","duration":"713.861932ms","start":"2026-08-19T09:12:21.952868Z","end":"2026-08-19T09:12:22.66673Z","steps":["trace[1750628202] 'read index received' (duration: 636.954182ms)","trace[1750628202] 'applied index is now lower than readState.Index' (duration: 76.90679ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:12:22.667628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113170452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:22.667672Z","caller":"traceutil/trace.go:171","msg":"trace[237835600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3373; }","duration":"1.113230164s","start":"2026-08-19T09:12:21.554432Z","end":"2026-08-19T09:12:22.667662Z","steps":["trace[237835600] 'agreement among raft nodes before linearized reading' (duration: 1.112666499s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.667689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:21.554392Z","time spent":"1.113292615s","remote":"127.0.0.1:45006","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-19T09:12:22.667786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156906886s","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":"info","ts":"2026-08-19T09:12:22.667807Z","caller":"traceutil/trace.go:171","msg":"trace[1039408621] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:3373; }","duration":"1.156931636s","start":"2026-08-19T09:12:21.510871Z","end":"2026-08-19T09:12:22.667803Z","steps":["trace[1039408621] 'agreement among raft nodes before linearized reading' (duration: 1.156341021s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.66782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:21.510838Z","time spent":"1.156979168s","remote":"127.0.0.1:45418","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":"warn","ts":"2026-08-19T09:12:22.667912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.72086337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:22.667936Z","caller":"traceutil/trace.go:171","msg":"trace[533299486] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3373; }","duration":"4.720953853s","start":"2026-08-19T09:12:17.946975Z","end":"2026-08-19T09:12:22.667929Z","steps":["trace[533299486] 'agreement among raft nodes before linearized reading' (duration: 4.720328136s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.667951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:17.946928Z","time spent":"4.721018994s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.667947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.692320763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-08-19T09:12:22.668042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.096107961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:22.668065Z","caller":"traceutil/trace.go:171","msg":"trace[1703170187] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:3373; }","duration":"5.096190842s","start":"2026-08-19T09:12:17.57187Z","end":"2026-08-19T09:12:22.668061Z","steps":["trace[1703170187] 'agreement among raft nodes before linearized reading' (duration: 5.095440863s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.66808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:17.571844Z","time spent":"5.096232304s","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.668124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178436409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-19T09:12:22.668152Z","caller":"traceutil/trace.go:171","msg":"trace[856306561] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3373; }","duration":"1.17849636s","start":"2026-08-19T09:12:21.489649Z","end":"2026-08-19T09:12:22.668146Z","steps":["trace[856306561] 'agreement among raft nodes before linearized reading' (duration: 1.177572096s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.537574351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:12:22.668225Z","caller":"traceutil/trace.go:171","msg":"trace[375580121] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3373; }","duration":"5.537644232s","start":"2026-08-19T09:12:17.130573Z","end":"2026-08-19T09:12:22.668218Z","steps":["trace[375580121] 'agreement among raft nodes before linearized reading' (duration: 5.536744729s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535733019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:12:22.668245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:17.130545Z","time spent":"5.537696214s","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:12:22.668254Z","caller":"traceutil/trace.go:171","msg":"trace[872855140] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:3373; }","duration":"1.535811262s","start":"2026-08-19T09:12:21.132436Z","end":"2026-08-19T09:12:22.668247Z","steps":["trace[872855140] 'agreement among raft nodes before linearized reading' (duration: 1.534793085s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:21.132404Z","time spent":"1.535869254s","remote":"127.0.0.1:60096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.668333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.811280716s","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":"info","ts":"2026-08-19T09:12:22.668368Z","caller":"traceutil/trace.go:171","msg":"trace[600577316] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:3373; }","duration":"5.811342438s","start":"2026-08-19T09:12:16.857008Z","end":"2026-08-19T09:12:22.668351Z","steps":["trace[600577316] 'agreement among raft nodes before linearized reading' (duration: 5.810316091s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:16.856984Z","time spent":"5.81139652s","remote":"127.0.0.1:34280","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-19T09:12:22.668493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.884399687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:12:22.668497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.957080084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:12:22.668511Z","caller":"traceutil/trace.go:171","msg":"trace[1362709852] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:0; response_revision:3373; }","duration":"5.88446751s","start":"2026-08-19T09:12:16.784038Z","end":"2026-08-19T09:12:22.668505Z","steps":["trace[1362709852] 'agreement among raft nodes before linearized reading' (duration: 5.883293609s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:16.784009Z","time spent":"5.88451246s","remote":"127.0.0.1:45050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-08-19T09:12:22.66853Z","caller":"traceutil/trace.go:171","msg":"trace[873620917] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:3373; }","duration":"1.957167166s","start":"2026-08-19T09:12:20.711351Z","end":"2026-08-19T09:12:22.668518Z","steps":["trace[873620917] 'agreement among raft nodes before linearized reading' (duration: 1.955886353s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:20.711323Z","time spent":"1.957227208s","remote":"127.0.0.1:33878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":4,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.668683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.925709052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-19T09:12:22.668705Z","caller":"traceutil/trace.go:171","msg":"trace[429150481] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:3373; }","duration":"6.925775574s","start":"2026-08-19T09:12:15.742923Z","end":"2026-08-19T09:12:22.668699Z","steps":["trace[429150481] 'agreement among raft nodes before linearized reading' (duration: 6.924414338s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031230943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:22.668748Z","caller":"traceutil/trace.go:171","msg":"trace[861486775] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:3373; }","duration":"2.031305355s","start":"2026-08-19T09:12:20.637433Z","end":"2026-08-19T09:12:22.668739Z","steps":["trace[861486775] 'agreement among raft nodes before linearized reading' (duration: 2.029811116s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.200532218s","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-19T09:12:22.668776Z","caller":"traceutil/trace.go:171","msg":"trace[86988149] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3373; }","duration":"7.20059689s","start":"2026-08-19T09:12:15.468173Z","end":"2026-08-19T09:12:22.66877Z","steps":["trace[86988149] 'agreement among raft nodes before linearized reading' (duration: 7.199171253s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:20.637406Z","time spent":"2.031366397s","remote":"127.0.0.1:41088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.668796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:15.46815Z","time spent":"7.20064152s","remote":"127.0.0.1:45444","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-19T09:12:22.668038Z","caller":"traceutil/trace.go:171","msg":"trace[1145930845] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:3373; }","duration":"4.692457888s","start":"2026-08-19T09:12:17.97554Z","end":"2026-08-19T09:12:22.667998Z","steps":["trace[1145930845] 'agreement among raft nodes before linearized reading' (duration: 4.691755629s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.668995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:17.975509Z","time spent":"4.693478274s","remote":"127.0.0.1:45198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7134,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-19T09:12:22.668996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.110067364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-19T09:12:22.669031Z","caller":"traceutil/trace.go:171","msg":"trace[1750800372] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3373; }","duration":"2.110173266s","start":"2026-08-19T09:12:20.558846Z","end":"2026-08-19T09:12:22.66902Z","steps":["trace[1750800372] 'agreement among raft nodes before linearized reading' (duration: 2.10840548s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.669061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:20.558824Z","time spent":"2.110231298s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-19T09:12:22.668196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:21.489635Z","time spent":"1.178555122s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-19T09:12:22.669237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.553160378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:12:22.669265Z","caller":"traceutil/trace.go:171","msg":"trace[635908236] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:3373; }","duration":"2.553231399s","start":"2026-08-19T09:12:20.116027Z","end":"2026-08-19T09:12:22.669258Z","steps":["trace[635908236] 'agreement among raft nodes before linearized reading' (duration: 2.551231477s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.66929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:20.116006Z","time spent":"2.553274031s","remote":"127.0.0.1:45198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":24,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.669415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.318564618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:12:22.669439Z","caller":"traceutil/trace.go:171","msg":"trace[956007290] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:3373; }","duration":"3.31863692s","start":"2026-08-19T09:12:19.350796Z","end":"2026-08-19T09:12:22.669433Z","steps":["trace[956007290] 'agreement among raft nodes before linearized reading' (duration: 3.316468453s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.669462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:19.350772Z","time spent":"3.318683482s","remote":"127.0.0.1:57068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.669602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.483952552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T09:12:22.668723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:15.7429Z","time spent":"6.925816825s","remote":"127.0.0.1:45282","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":"info","ts":"2026-08-19T09:12:22.669627Z","caller":"traceutil/trace.go:171","msg":"trace[1143568134] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:3373; }","duration":"3.484033394s","start":"2026-08-19T09:12:19.185588Z","end":"2026-08-19T09:12:22.669621Z","steps":["trace[1143568134] 'agreement among raft nodes before linearized reading' (duration: 3.481686333s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.669649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:19.185562Z","time spent":"3.484080886s","remote":"127.0.0.1:45100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":26,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.669722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.244112156s","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":"info","ts":"2026-08-19T09:12:22.669751Z","caller":"traceutil/trace.go:171","msg":"trace[882935084] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:3373; }","duration":"4.244187198s","start":"2026-08-19T09:12:18.425555Z","end":"2026-08-19T09:12:22.669742Z","steps":["trace[882935084] 'agreement among raft nodes before linearized reading' (duration: 4.241726474s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.66977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:18.425535Z","time spent":"4.244229599s","remote":"127.0.0.1:56244","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":"warn","ts":"2026-08-19T09:12:22.670679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.574476152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:12:22.670709Z","caller":"traceutil/trace.go:171","msg":"trace[1046251829] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3373; }","duration":"4.574555274s","start":"2026-08-19T09:12:18.096148Z","end":"2026-08-19T09:12:22.670703Z","steps":["trace[1046251829] 'agreement among raft nodes before linearized reading' (duration: 4.571140785s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.670731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:18.096117Z","time spent":"4.574608576s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":91,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:12:22.67088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.960907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:22.670902Z","caller":"traceutil/trace.go:171","msg":"trace[1263683355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3374; }","duration":"680.028458ms","start":"2026-08-19T09:12:21.990868Z","end":"2026-08-19T09:12:22.670897Z","steps":["trace[1263683355] 'agreement among raft nodes before linearized reading' (duration: 679.974217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:22.670914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:21.990836Z","time spent":"680.07454ms","remote":"127.0.0.1:45012","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-19T09:12:22.671096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.97672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:12:22.671148Z","caller":"traceutil/trace.go:171","msg":"trace[1182114964] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:3374; }","duration":"215.083523ms","start":"2026-08-19T09:12:22.456059Z","end":"2026-08-19T09:12:22.671142Z","steps":["trace[1182114964] 'agreement among raft nodes before linearized reading' (duration: 215.017561ms)"],"step_count":1}