{"level":"warn","ts":"2026-07-07T08:24:31.039488Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T08:24:31.03956Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.39:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.39:2380","--initial-cluster=instance=https://199.19.213.39:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.39:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.39: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-07-07T08:24:31.039618Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T08:24:31.039626Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.39:2380"]} {"level":"info","ts":"2026-07-07T08:24:31.039641Z","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-07-07T08:24:31.04023Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"]} {"level":"info","ts":"2026-07-07T08:24:31.040296Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.39:2380"],"listen-peer-urls":["https://199.19.213.39:2380"],"advertise-client-urls":["https://199.19.213.39:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.39: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-07-07T08:24:31.042861Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.399773ms"} {"level":"info","ts":"2026-07-07T08:24:31.048823Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95026ee9c61f87d3","cluster-id":"98e25849a4b355e0"} {"level":"info","ts":"2026-07-07T08:24:31.048923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T08:24:31.048946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became follower at term 0"} {"level":"info","ts":"2026-07-07T08:24:31.048958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95026ee9c61f87d3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T08:24:31.048966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became follower at term 1"} {"level":"info","ts":"2026-07-07T08:24:31.049006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=(10737266411935074259)"} {"level":"warn","ts":"2026-07-07T08:24:31.054689Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T08:24:31.056188Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T08:24:31.0576Z","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-07-07T08:24:31.060224Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95026ee9c61f87d3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T08:24:31.060517Z","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-07-07T08:24:31.060689Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95026ee9c61f87d3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T08:24:31.060719Z","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-07-07T08:24:31.060777Z","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-07-07T08:24:31.06369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=(10737266411935074259)"} {"level":"info","ts":"2026-07-07T08:24:31.063933Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98e25849a4b355e0","local-member-id":"95026ee9c61f87d3","added-peer-id":"95026ee9c61f87d3","added-peer-peer-urls":["https://199.19.213.39:2380"]} {"level":"info","ts":"2026-07-07T08:24:31.066681Z","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-07-07T08:24:31.066821Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.39:2380"} {"level":"info","ts":"2026-07-07T08:24:31.066844Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.39:2380"} {"level":"info","ts":"2026-07-07T08:24:31.067714Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95026ee9c61f87d3","initial-advertise-peer-urls":["https://199.19.213.39:2380"],"listen-peer-urls":["https://199.19.213.39:2380"],"advertise-client-urls":["https://199.19.213.39:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T08:24:31.06774Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T08:24:31.849354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T08:24:31.849427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T08:24:31.849567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 received MsgPreVoteResp from 95026ee9c61f87d3 at term 1"} {"level":"info","ts":"2026-07-07T08:24:31.849606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became candidate at term 2"} {"level":"info","ts":"2026-07-07T08:24:31.849625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 received MsgVoteResp from 95026ee9c61f87d3 at term 2"} {"level":"info","ts":"2026-07-07T08:24:31.84965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became leader at term 2"} {"level":"info","ts":"2026-07-07T08:24:31.849672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95026ee9c61f87d3 elected leader 95026ee9c61f87d3 at term 2"} {"level":"info","ts":"2026-07-07T08:24:31.850715Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T08:24:31.851608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95026ee9c61f87d3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.39:2379]}","request-path":"/0/members/95026ee9c61f87d3/attributes","cluster-id":"98e25849a4b355e0","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T08:24:31.851656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T08:24:31.85215Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98e25849a4b355e0","local-member-id":"95026ee9c61f87d3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T08:24:31.852193Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T08:24:31.852283Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T08:24:31.852339Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T08:24:31.854982Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T08:24:31.855944Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.39:2379"} {"level":"info","ts":"2026-07-07T08:24:31.856974Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T08:24:31.857009Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T08:25:09.674335Z","caller":"traceutil/trace.go:171","msg":"trace[615659378] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"182.402181ms","start":"2026-07-07T08:25:09.491914Z","end":"2026-07-07T08:25:09.674316Z","steps":["trace[615659378] 'process raft request' (duration: 177.935144ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:25:09.676094Z","caller":"traceutil/trace.go:171","msg":"trace[729463364] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"181.687888ms","start":"2026-07-07T08:25:09.49431Z","end":"2026-07-07T08:25:09.675998Z","steps":["trace[729463364] 'process raft request' (duration: 181.352158ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:25:09.67653Z","caller":"traceutil/trace.go:171","msg":"trace[115470361] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"180.864594ms","start":"2026-07-07T08:25:09.495642Z","end":"2026-07-07T08:25:09.676507Z","steps":["trace[115470361] 'process raft request' (duration: 180.248745ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:25:15.777051Z","caller":"traceutil/trace.go:171","msg":"trace[1899499488] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"129.968967ms","start":"2026-07-07T08:25:15.647054Z","end":"2026-07-07T08:25:15.777023Z","steps":["trace[1899499488] 'process raft request' (duration: 129.155963ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:25:16.563171Z","caller":"traceutil/trace.go:171","msg":"trace[712085921] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"273.081645ms","start":"2026-07-07T08:25:16.290016Z","end":"2026-07-07T08:25:16.563098Z","steps":["trace[712085921] 'process raft request' (duration: 266.724822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:25:31.467329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.119997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:25:31.467509Z","caller":"traceutil/trace.go:171","msg":"trace[519516552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"186.354694ms","start":"2026-07-07T08:25:31.281114Z","end":"2026-07-07T08:25:31.467469Z","steps":["trace[519516552] 'range keys from in-memory index tree' (duration: 186.028614ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:04.393889Z","caller":"traceutil/trace.go:171","msg":"trace[512064301] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"114.086707ms","start":"2026-07-07T08:26:04.279779Z","end":"2026-07-07T08:26:04.393866Z","steps":["trace[512064301] 'read index received' (duration: 113.881301ms)","trace[512064301] 'applied index is now lower than readState.Index' (duration: 204.486µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:26:04.394026Z","caller":"traceutil/trace.go:171","msg":"trace[2087714236] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"163.75213ms","start":"2026-07-07T08:26:04.23024Z","end":"2026-07-07T08:26:04.393992Z","steps":["trace[2087714236] 'process raft request' (duration: 163.464402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:04.394055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.235982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:04.394115Z","caller":"traceutil/trace.go:171","msg":"trace[382015509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"114.359255ms","start":"2026-07-07T08:26:04.279744Z","end":"2026-07-07T08:26:04.394103Z","steps":["trace[382015509] 'agreement among raft nodes before linearized reading' (duration: 114.216221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:15.054076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873299904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:15.555124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873299904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:16.056081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873299904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:16.557204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873299904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:17.057516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873299904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:17.280915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000189934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T08:26:17.280997Z","caller":"traceutil/trace.go:171","msg":"trace[848517583] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000418791s","start":"2026-07-07T08:26:15.280559Z","end":"2026-07-07T08:26:17.280978Z","steps":["trace[848517583] 'agreement among raft nodes before linearized reading' (duration: 2.000281157s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:17.281052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:15.280537Z","time spent":"2.000503743s","remote":"127.0.0.1:59946","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-07-07T08:26:17.558104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873299904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:18.059268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873299904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:18.490843Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.081746278s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T08:26:18.491264Z","caller":"traceutil/trace.go:171","msg":"trace[1717744486] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"4.082250993s","start":"2026-07-07T08:26:14.408989Z","end":"2026-07-07T08:26:18.49124Z","steps":["trace[1717744486] 'process raft request' (duration: 4.082062197s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.491884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:14.40897Z","time spent":"4.082366357s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:18.492577Z","caller":"traceutil/trace.go:171","msg":"trace[1661928617] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1084; }","duration":"3.938775989s","start":"2026-07-07T08:26:14.553781Z","end":"2026-07-07T08:26:18.492557Z","steps":["trace[1661928617] 'read index received' (duration: 3.937314445s)","trace[1661928617] 'applied index is now lower than readState.Index' (duration: 1.460674ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:26:18.492605Z","caller":"traceutil/trace.go:171","msg":"trace[1177712511] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"3.708468012s","start":"2026-07-07T08:26:14.784119Z","end":"2026-07-07T08:26:18.492587Z","steps":["trace[1177712511] 'process raft request' (duration: 3.708316556s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.492711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.938915922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T08:26:18.492714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:14.784093Z","time spent":"3.708578475s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:18.492697Z","caller":"traceutil/trace.go:171","msg":"trace[1865823187] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"172.064268ms","start":"2026-07-07T08:26:18.320583Z","end":"2026-07-07T08:26:18.492647Z","steps":["trace[1865823187] 'process raft request' (duration: 171.948605ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:18.492779Z","caller":"traceutil/trace.go:171","msg":"trace[1398817310] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1045; }","duration":"3.939027426s","start":"2026-07-07T08:26:14.553741Z","end":"2026-07-07T08:26:18.492768Z","steps":["trace[1398817310] 'agreement among raft nodes before linearized reading' (duration: 3.938913413s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.492874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.82359892s","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-07-07T08:26:18.492894Z","caller":"traceutil/trace.go:171","msg":"trace[615619960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1045; }","duration":"3.823656732s","start":"2026-07-07T08:26:14.669232Z","end":"2026-07-07T08:26:18.492888Z","steps":["trace[615619960] 'agreement among raft nodes before linearized reading' (duration: 3.823568069s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.492889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:14.553724Z","time spent":"3.93914965s","remote":"127.0.0.1:40810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:18.49292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:14.669215Z","time spent":"3.823699043s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-07T08:26:18.493002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443589285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T08:26:18.493008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375767239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T08:26:18.493074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.773808634s","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-07-07T08:26:18.493095Z","caller":"traceutil/trace.go:171","msg":"trace[1925864956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1045; }","duration":"2.773876895s","start":"2026-07-07T08:26:15.719213Z","end":"2026-07-07T08:26:18.493089Z","steps":["trace[1925864956] 'agreement among raft nodes before linearized reading' (duration: 2.773822714s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.493116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:15.719188Z","time spent":"2.773924047s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T08:26:18.493231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.778997459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-07T08:26:18.493251Z","caller":"traceutil/trace.go:171","msg":"trace[2044360724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1045; }","duration":"2.77904602s","start":"2026-07-07T08:26:15.7142Z","end":"2026-07-07T08:26:18.493246Z","steps":["trace[2044360724] 'agreement among raft nodes before linearized reading' (duration: 2.778993059s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.493266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:15.714186Z","time spent":"2.779076622s","remote":"127.0.0.1:60238","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-07-07T08:26:18.493087Z","caller":"traceutil/trace.go:171","msg":"trace[84780179] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1045; }","duration":"1.375827671s","start":"2026-07-07T08:26:17.117213Z","end":"2026-07-07T08:26:18.493041Z","steps":["trace[84780179] 'agreement among raft nodes before linearized reading' (duration: 1.375739858s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.493294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.747041184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.39\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-07T08:26:18.493317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.654366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:18.493355Z","caller":"traceutil/trace.go:171","msg":"trace[567605170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"102.723168ms","start":"2026-07-07T08:26:18.390621Z","end":"2026-07-07T08:26:18.493344Z","steps":["trace[567605170] 'agreement among raft nodes before linearized reading' (duration: 102.673527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.493338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:17.11719Z","time spent":"1.37612626s","remote":"127.0.0.1:40874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-07T08:26:18.493024Z","caller":"traceutil/trace.go:171","msg":"trace[868708861] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1045; }","duration":"1.443652657s","start":"2026-07-07T08:26:17.049365Z","end":"2026-07-07T08:26:18.493018Z","steps":["trace[868708861] 'agreement among raft nodes before linearized reading' (duration: 1.443620806s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.49341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.156643005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-07T08:26:18.49345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.456427217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-07T08:26:18.493479Z","caller":"traceutil/trace.go:171","msg":"trace[1856828597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"3.456492869s","start":"2026-07-07T08:26:15.036978Z","end":"2026-07-07T08:26:18.493471Z","steps":["trace[1856828597] 'agreement among raft nodes before linearized reading' (duration: 3.456400556s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.493471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:17.049343Z","time spent":"1.44408775s","remote":"127.0.0.1:40856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:18.493508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:15.036948Z","time spent":"3.456554901s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T08:26:18.49351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21200089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:18.493541Z","caller":"traceutil/trace.go:171","msg":"trace[1131656579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"1.212053472s","start":"2026-07-07T08:26:17.281479Z","end":"2026-07-07T08:26:18.493533Z","steps":["trace[1131656579] 'agreement among raft nodes before linearized reading' (duration: 1.21201054s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.493566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:17.281467Z","time spent":"1.212093482s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T08:26:18.493347Z","caller":"traceutil/trace.go:171","msg":"trace[2143345027] range","detail":"{range_begin:/registry/masterleases/199.19.213.39; range_end:; response_count:1; response_revision:1045; }","duration":"3.747118226s","start":"2026-07-07T08:26:14.746205Z","end":"2026-07-07T08:26:18.493324Z","steps":["trace[2143345027] 'agreement among raft nodes before linearized reading' (duration: 3.747000093s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.493604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:14.746188Z","time spent":"3.747405175s","remote":"127.0.0.1:59994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.39\" "} {"level":"warn","ts":"2026-07-07T08:26:18.493707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.312451439s","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-07-07T08:26:18.493735Z","caller":"traceutil/trace.go:171","msg":"trace[93372059] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1045; }","duration":"1.31248134s","start":"2026-07-07T08:26:17.181246Z","end":"2026-07-07T08:26:18.493727Z","steps":["trace[93372059] 'agreement among raft nodes before linearized reading' (duration: 1.312437659s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.493767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:17.181233Z","time spent":"1.312526451s","remote":"127.0.0.1:40866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-07T08:26:18.494694Z","caller":"traceutil/trace.go:171","msg":"trace[1048660695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1045; }","duration":"3.156692506s","start":"2026-07-07T08:26:15.33673Z","end":"2026-07-07T08:26:18.493423Z","steps":["trace[1048660695] 'agreement among raft nodes before linearized reading' (duration: 3.156641365s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:18.494739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:15.336717Z","time spent":"3.158012546s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T08:26:28.707127Z","caller":"traceutil/trace.go:171","msg":"trace[82862598] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"173.902261ms","start":"2026-07-07T08:26:28.533205Z","end":"2026-07-07T08:26:28.707107Z","steps":["trace[82862598] 'process raft request' (duration: 173.795388ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:28.734415Z","caller":"traceutil/trace.go:171","msg":"trace[256550408] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"198.4031ms","start":"2026-07-07T08:26:28.535992Z","end":"2026-07-07T08:26:28.734395Z","steps":["trace[256550408] 'read index received' (duration: 171.460749ms)","trace[256550408] 'applied index is now lower than readState.Index' (duration: 26.941281ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:26:28.73452Z","caller":"traceutil/trace.go:171","msg":"trace[647929347] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"200.596995ms","start":"2026-07-07T08:26:28.53383Z","end":"2026-07-07T08:26:28.734427Z","steps":["trace[647929347] 'process raft request' (duration: 200.4745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:28.734587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.582425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T08:26:28.73462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.293107ms","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-07-07T08:26:28.734652Z","caller":"traceutil/trace.go:171","msg":"trace[506354773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"158.38261ms","start":"2026-07-07T08:26:28.576262Z","end":"2026-07-07T08:26:28.734644Z","steps":["trace[506354773] 'agreement among raft nodes before linearized reading' (duration: 158.252917ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:28.734653Z","caller":"traceutil/trace.go:171","msg":"trace[1212441194] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1079; }","duration":"198.662367ms","start":"2026-07-07T08:26:28.535977Z","end":"2026-07-07T08:26:28.734639Z","steps":["trace[1212441194] 'agreement among raft nodes before linearized reading' (duration: 198.521523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:28.734727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.532316ms","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-07-07T08:26:28.734764Z","caller":"traceutil/trace.go:171","msg":"trace[285694692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1079; }","duration":"153.570577ms","start":"2026-07-07T08:26:28.581186Z","end":"2026-07-07T08:26:28.734756Z","steps":["trace[285694692] 'agreement among raft nodes before linearized reading' (duration: 153.459793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:28.734952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.837424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-07T08:26:28.735112Z","caller":"traceutil/trace.go:171","msg":"trace[1527403191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"158.008469ms","start":"2026-07-07T08:26:28.577088Z","end":"2026-07-07T08:26:28.735097Z","steps":["trace[1527403191] 'agreement among raft nodes before linearized reading' (duration: 157.662688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:28.735384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.448982ms","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-07-07T08:26:28.735417Z","caller":"traceutil/trace.go:171","msg":"trace[1084032790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"157.509704ms","start":"2026-07-07T08:26:28.577899Z","end":"2026-07-07T08:26:28.735409Z","steps":["trace[1084032790] 'agreement among raft nodes before linearized reading' (duration: 156.780472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:28.735659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.433343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-07T08:26:28.735717Z","caller":"traceutil/trace.go:171","msg":"trace[1323679760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"153.525166ms","start":"2026-07-07T08:26:28.582179Z","end":"2026-07-07T08:26:28.735704Z","steps":["trace[1323679760] 'agreement among raft nodes before linearized reading' (duration: 152.604548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:31.251851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:31.75209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:32.252723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:33.872955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:33.873125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.593685951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T08:26:33.873167Z","caller":"traceutil/trace.go:171","msg":"trace[257804333] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.593750023s","start":"2026-07-07T08:26:31.279404Z","end":"2026-07-07T08:26:33.873154Z","steps":["trace[257804333] 'agreement among raft nodes before linearized reading' (duration: 2.593702491s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:33.873205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:31.279396Z","time spent":"2.593802745s","remote":"127.0.0.1:59946","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-07-07T08:26:35.110707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:36.19813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:36.198607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:30.710892Z","time spent":"5.487712042s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-07T08:26:36.198664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.447449465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T08:26:36.198692Z","caller":"traceutil/trace.go:171","msg":"trace[1668064396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.447491127s","start":"2026-07-07T08:26:30.751187Z","end":"2026-07-07T08:26:36.198678Z","steps":["trace[1668064396] 'agreement among raft nodes before linearized reading' (duration: 5.447450065s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:36.198715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:30.751027Z","time spent":"5.447684723s","remote":"127.0.0.1:60238","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-07-07T08:26:36.198737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.413764953s","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-07-07T08:26:36.198748Z","caller":"traceutil/trace.go:171","msg":"trace[1956253723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.413816676s","start":"2026-07-07T08:26:30.784928Z","end":"2026-07-07T08:26:36.198745Z","steps":["trace[1956253723] 'agreement among raft nodes before linearized reading' (duration: 5.413805195s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:36.198761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:30.784906Z","time spent":"5.413851936s","remote":"127.0.0.1:60238","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-07-07T08:26:36.198784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.412927708s","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-07-07T08:26:36.198796Z","caller":"traceutil/trace.go:171","msg":"trace[339613980] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.412966469s","start":"2026-07-07T08:26:30.785825Z","end":"2026-07-07T08:26:36.198792Z","steps":["trace[339613980] 'agreement among raft nodes before linearized reading' (duration: 5.412952158s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:36.198809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:30.785814Z","time spent":"5.41299149s","remote":"127.0.0.1:60238","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\" "} {"level":"warn","ts":"2026-07-07T08:26:40.159885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:40.162769Z","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-07-07T08:26:40.163965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.0532584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T08:26:40.164003Z","caller":"traceutil/trace.go:171","msg":"trace[1942780193] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"5.053331412s","start":"2026-07-07T08:26:35.110659Z","end":"2026-07-07T08:26:40.16399Z","steps":["trace[1942780193] 'agreement among raft nodes before linearized reading' (duration: 5.053283391s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.164033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:35.110644Z","time spent":"5.053383083s","remote":"127.0.0.1:59932","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-07-07T08:26:40.296747Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"9.58574064s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T08:26:40.39818Z","caller":"traceutil/trace.go:171","msg":"trace[1412709584] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1130; }","duration":"235.327195ms","start":"2026-07-07T08:26:40.162838Z","end":"2026-07-07T08:26:40.398166Z","steps":["trace[1412709584] 'read index received' (duration: 136.190903ms)","trace[1412709584] 'applied index is now lower than readState.Index' (duration: 99.135812ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:26:40.398456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.905202227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.398475Z","caller":"traceutil/trace.go:171","msg":"trace[511264439] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1089; }","duration":"8.905237338s","start":"2026-07-07T08:26:31.493233Z","end":"2026-07-07T08:26:40.39847Z","steps":["trace[511264439] 'agreement among raft nodes before linearized reading' (duration: 8.905189087s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.398493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:31.493169Z","time spent":"8.905319601s","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:40.398473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.520481809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.398546Z","caller":"traceutil/trace.go:171","msg":"trace[944023988] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1089; }","duration":"6.520584373s","start":"2026-07-07T08:26:33.877941Z","end":"2026-07-07T08:26:40.398526Z","steps":["trace[944023988] 'agreement among raft nodes before linearized reading' (duration: 6.520439419s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.398581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:33.877932Z","time spent":"6.520640104s","remote":"127.0.0.1:49654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:40.39861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.141805853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.398626Z","caller":"traceutil/trace.go:171","msg":"trace[97439480] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1089; }","duration":"9.141860205s","start":"2026-07-07T08:26:31.256761Z","end":"2026-07-07T08:26:40.398621Z","steps":["trace[97439480] 'agreement among raft nodes before linearized reading' (duration: 9.141830994s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.39864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:31.256741Z","time spent":"9.141894945s","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:40.398755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.612098278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-07T08:26:40.398767Z","caller":"traceutil/trace.go:171","msg":"trace[908693170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"9.612128999s","start":"2026-07-07T08:26:30.786635Z","end":"2026-07-07T08:26:40.398764Z","steps":["trace[908693170] 'agreement among raft nodes before linearized reading' (duration: 9.612068747s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.398779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:30.786628Z","time spent":"9.61214819s","remote":"127.0.0.1:60238","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-07-07T08:26:40.398801Z","caller":"traceutil/trace.go:171","msg":"trace[1476987056] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"218.567138ms","start":"2026-07-07T08:26:40.180223Z","end":"2026-07-07T08:26:40.39879Z","steps":["trace[1476987056] 'process raft request' (duration: 217.837606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.398868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.612254823s","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-07-07T08:26:40.398883Z","caller":"traceutil/trace.go:171","msg":"trace[1467477569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"9.612291414s","start":"2026-07-07T08:26:30.786588Z","end":"2026-07-07T08:26:40.398879Z","steps":["trace[1467477569] 'agreement among raft nodes before linearized reading' (duration: 9.612248483s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.398895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:30.786576Z","time spent":"9.612317085s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-07T08:26:40.398928Z","caller":"traceutil/trace.go:171","msg":"trace[1885311427] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"218.67918ms","start":"2026-07-07T08:26:40.180241Z","end":"2026-07-07T08:26:40.39892Z","steps":["trace[1885311427] 'process raft request' (duration: 217.899037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.398997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.327169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bff4791e211a20\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-07-07T08:26:40.399013Z","caller":"traceutil/trace.go:171","msg":"trace[1072589504] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18bff4791e211a20; range_end:; response_count:1; response_revision:1089; }","duration":"220.37294ms","start":"2026-07-07T08:26:40.178636Z","end":"2026-07-07T08:26:40.399009Z","steps":["trace[1072589504] 'agreement among raft nodes before linearized reading' (duration: 220.325489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.287476533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.399094Z","caller":"traceutil/trace.go:171","msg":"trace[1474889239] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1089; }","duration":"5.287531724s","start":"2026-07-07T08:26:35.111553Z","end":"2026-07-07T08:26:40.399085Z","steps":["trace[1474889239] 'agreement among raft nodes before linearized reading' (duration: 5.287459802s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:35.11154Z","time spent":"5.287570965s","remote":"127.0.0.1:49608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:40.399238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.287740739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.39\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T08:26:40.399254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.485521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.399278Z","caller":"traceutil/trace.go:171","msg":"trace[1247340969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"198.530962ms","start":"2026-07-07T08:26:40.200739Z","end":"2026-07-07T08:26:40.39927Z","steps":["trace[1247340969] 'agreement among raft nodes before linearized reading' (duration: 198.498821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.287858703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.399303Z","caller":"traceutil/trace.go:171","msg":"trace[1485667799] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1089; }","duration":"5.287904774s","start":"2026-07-07T08:26:35.111393Z","end":"2026-07-07T08:26:40.399298Z","steps":["trace[1485667799] 'agreement among raft nodes before linearized reading' (duration: 5.287875694s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:35.111381Z","time spent":"5.287934346s","remote":"127.0.0.1:60120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:40.399356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.412845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.399371Z","caller":"traceutil/trace.go:171","msg":"trace[1161576416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"209.458087ms","start":"2026-07-07T08:26:40.189909Z","end":"2026-07-07T08:26:40.399367Z","steps":["trace[1161576416] 'agreement among raft nodes before linearized reading' (duration: 209.434446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.521353576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T08:26:40.399448Z","caller":"traceutil/trace.go:171","msg":"trace[1845542289] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1089; }","duration":"6.521375477s","start":"2026-07-07T08:26:33.878068Z","end":"2026-07-07T08:26:40.399443Z","steps":["trace[1845542289] 'agreement among raft nodes before linearized reading' (duration: 6.521340136s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.246128ms","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":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T08:26:40.399469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:33.878057Z","time spent":"6.521404078s","remote":"127.0.0.1:60358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-07-07T08:26:40.399473Z","caller":"traceutil/trace.go:171","msg":"trace[367047489] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1089; }","duration":"219.271289ms","start":"2026-07-07T08:26:40.180198Z","end":"2026-07-07T08:26:40.399469Z","steps":["trace[367047489] 'agreement among raft nodes before linearized reading' (duration: 219.232508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.364122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T08:26:40.399566Z","caller":"traceutil/trace.go:171","msg":"trace[1601663554] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1089; }","duration":"219.388862ms","start":"2026-07-07T08:26:40.180173Z","end":"2026-07-07T08:26:40.399562Z","steps":["trace[1601663554] 'agreement among raft nodes before linearized reading' (duration: 219.363491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.521552032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T08:26:40.399598Z","caller":"traceutil/trace.go:171","msg":"trace[246527699] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1089; }","duration":"6.521574373s","start":"2026-07-07T08:26:33.878017Z","end":"2026-07-07T08:26:40.399592Z","steps":["trace[246527699] 'agreement among raft nodes before linearized reading' (duration: 6.521539702s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:33.878005Z","time spent":"6.521606384s","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:40.39965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.571298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.399664Z","caller":"traceutil/trace.go:171","msg":"trace[1731631076] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1089; }","duration":"219.597659ms","start":"2026-07-07T08:26:40.180063Z","end":"2026-07-07T08:26:40.399661Z","steps":["trace[1731631076] 'agreement among raft nodes before linearized reading' (duration: 219.573218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.690121ms","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-07-07T08:26:40.399758Z","caller":"traceutil/trace.go:171","msg":"trace[1454270182] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1089; }","duration":"219.724563ms","start":"2026-07-07T08:26:40.180029Z","end":"2026-07-07T08:26:40.399753Z","steps":["trace[1454270182] 'agreement among raft nodes before linearized reading' (duration: 219.700082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.882927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-07-07T08:26:40.399874Z","caller":"traceutil/trace.go:171","msg":"trace[1339266401] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1089; }","duration":"219.904977ms","start":"2026-07-07T08:26:40.179965Z","end":"2026-07-07T08:26:40.39987Z","steps":["trace[1339266401] 'agreement among raft nodes before linearized reading' (duration: 219.862696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.399953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.003841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:40.399968Z","caller":"traceutil/trace.go:171","msg":"trace[313653615] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1089; }","duration":"220.028231ms","start":"2026-07-07T08:26:40.179935Z","end":"2026-07-07T08:26:40.399964Z","steps":["trace[313653615] 'agreement among raft nodes before linearized reading' (duration: 220.00255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.400048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.118314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T08:26:40.400062Z","caller":"traceutil/trace.go:171","msg":"trace[1789348189] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1089; }","duration":"220.152885ms","start":"2026-07-07T08:26:40.179905Z","end":"2026-07-07T08:26:40.400058Z","steps":["trace[1789348189] 'agreement among raft nodes before linearized reading' (duration: 220.124504ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:40.399257Z","caller":"traceutil/trace.go:171","msg":"trace[406188213] range","detail":"{range_begin:/registry/masterleases/199.19.213.39; range_end:; response_count:0; response_revision:1089; }","duration":"5.287768071s","start":"2026-07-07T08:26:35.111483Z","end":"2026-07-07T08:26:40.399251Z","steps":["trace[406188213] 'agreement among raft nodes before linearized reading' (duration: 5.2877344s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:40.40025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:35.111479Z","time spent":"5.288763101s","remote":"127.0.0.1:59994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/199.19.213.39\" "} {"level":"warn","ts":"2026-07-07T08:26:40.620454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.703583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T08:26:40.62136Z","caller":"traceutil/trace.go:171","msg":"trace[1496697937] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"161.867305ms","start":"2026-07-07T08:26:40.459479Z","end":"2026-07-07T08:26:40.621347Z","steps":["trace[1496697937] 'process raft request' (duration: 44.10984ms)","trace[1496697937] 'compare' (duration: 116.60625ms)"],"step_count":2} 2026/07/07 08:26:40 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:43.038899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300089,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T08:26:43.497823Z","caller":"traceutil/trace.go:171","msg":"trace[948836406] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"979.72376ms","start":"2026-07-07T08:26:42.518082Z","end":"2026-07-07T08:26:43.497806Z","steps":["trace[948836406] 'process raft request' (duration: 979.616527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:43.497923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:42.518065Z","time spent":"979.806293ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:43.513149Z","caller":"traceutil/trace.go:171","msg":"trace[1629119259] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1163; }","duration":"974.472284ms","start":"2026-07-07T08:26:42.538638Z","end":"2026-07-07T08:26:43.513111Z","steps":["trace[1629119259] 'read index received' (duration: 959.083658ms)","trace[1629119259] 'applied index is now lower than readState.Index' (duration: 15.387546ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:26:43.513287Z","caller":"traceutil/trace.go:171","msg":"trace[771138138] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"993.613063ms","start":"2026-07-07T08:26:42.51966Z","end":"2026-07-07T08:26:43.513273Z","steps":["trace[771138138] 'process raft request' (duration: 993.330844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:43.513386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.152559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:43.513434Z","caller":"traceutil/trace.go:171","msg":"trace[369134902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1117; }","duration":"235.243503ms","start":"2026-07-07T08:26:43.278178Z","end":"2026-07-07T08:26:43.513421Z","steps":["trace[369134902] 'agreement among raft nodes before linearized reading' (duration: 235.166501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:43.513608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.966279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T08:26:43.51368Z","caller":"traceutil/trace.go:171","msg":"trace[294493215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1117; }","duration":"975.04511ms","start":"2026-07-07T08:26:42.538598Z","end":"2026-07-07T08:26:43.513643Z","steps":["trace[294493215] 'agreement among raft nodes before linearized reading' (duration: 974.805163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:43.513717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:42.538586Z","time spent":"975.120773ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T08:26:43.514072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:42.519646Z","time spent":"993.669884ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:26:44.592451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.241602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T08:26:44.593452Z","caller":"traceutil/trace.go:171","msg":"trace[2036244711] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1165; }","duration":"314.71027ms","start":"2026-07-07T08:26:44.278713Z","end":"2026-07-07T08:26:44.593424Z","steps":["trace[2036244711] 'read index received' (duration: 314.134522ms)","trace[2036244711] 'applied index is now lower than readState.Index' (duration: 574.338µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:26:44.593588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.869904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:44.593619Z","caller":"traceutil/trace.go:171","msg":"trace[2089507228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1119; }","duration":"314.957996ms","start":"2026-07-07T08:26:44.278652Z","end":"2026-07-07T08:26:44.59361Z","steps":["trace[2089507228] 'agreement among raft nodes before linearized reading' (duration: 314.876734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:44.59365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:44.278625Z","time spent":"315.016758ms","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T08:26:44.59384Z","caller":"traceutil/trace.go:171","msg":"trace[1466796619] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"1.074603806s","start":"2026-07-07T08:26:43.519225Z","end":"2026-07-07T08:26:44.593829Z","steps":["trace[1466796619] 'process raft request' (duration: 1.074062531s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:44.593928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:43.519208Z","time spent":"1.074666448s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:44.594069Z","caller":"traceutil/trace.go:171","msg":"trace[1824119764] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"1.076002927s","start":"2026-07-07T08:26:43.517742Z","end":"2026-07-07T08:26:44.593745Z","steps":["trace[1824119764] 'process raft request' (duration: 85.148397ms)","trace[1824119764] 'compare' (duration: 988.261813ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:26:44.594197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:43.517718Z","time spent":"1.0764411s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:44.595031Z","caller":"traceutil/trace.go:171","msg":"trace[1413180498] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"177.774905ms","start":"2026-07-07T08:26:44.417244Z","end":"2026-07-07T08:26:44.595019Z","steps":["trace[1413180498] 'process raft request' (duration: 177.326982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:45.296716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:45.797374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:46.2982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:46.799248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:47.279707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000007376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:47.279797Z","caller":"traceutil/trace.go:171","msg":"trace[1038658090] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00013776s","start":"2026-07-07T08:26:45.279634Z","end":"2026-07-07T08:26:47.279771Z","steps":["trace[1038658090] 'agreement among raft nodes before linearized reading' (duration: 2.000035537s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:47.279848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:45.279615Z","time spent":"2.000221302s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 08:26:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:47.299539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:47.799884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:48.300903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:48.80158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:49.280198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000072728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:49.280289Z","caller":"traceutil/trace.go:171","msg":"trace[292032879] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000206332s","start":"2026-07-07T08:26:47.280065Z","end":"2026-07-07T08:26:49.280271Z","steps":["trace[292032879] 'agreement among raft nodes before linearized reading' (duration: 2.000097059s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:49.280333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:47.280048Z","time spent":"2.000275884s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 08:26:49 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:49.30244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:49.60401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:44.604832Z","time spent":"4.999165302s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/07 08:26:49 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:49.79552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999192384s","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-07-07T08:26:49.795586Z","caller":"traceutil/trace.go:171","msg":"trace[298796087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999310548s","start":"2026-07-07T08:26:44.79626Z","end":"2026-07-07T08:26:49.795571Z","steps":["trace[298796087] 'agreement among raft nodes before linearized reading' (duration: 4.999229355s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:49.795623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:44.796237Z","time spent":"4.99937887s","remote":"127.0.0.1:60238","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/07/07 08:26:49 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:49.803058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:50.304144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:50.804675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:51.288304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99981775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:51.288374Z","caller":"traceutil/trace.go:171","msg":"trace[664909795] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999948834s","start":"2026-07-07T08:26:49.288411Z","end":"2026-07-07T08:26:51.28836Z","steps":["trace[664909795] 'agreement among raft nodes before linearized reading' (duration: 1.999852351s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:51.288415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:49.288389Z","time spent":"2.000019077s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 08:26:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:51.305577Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:51.599429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:46.601363Z","time spent":"4.998059688s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/07 08:26:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:51.599525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999613824s","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-07-07T08:26:51.599622Z","caller":"traceutil/trace.go:171","msg":"trace[671955462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999766589s","start":"2026-07-07T08:26:46.599836Z","end":"2026-07-07T08:26:51.599602Z","steps":["trace[671955462] 'agreement among raft nodes before linearized reading' (duration: 4.999647175s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:51.599676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:46.599814Z","time spent":"4.999852071s","remote":"127.0.0.1:60238","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/07/07 08:26:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:51.602875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:44.602368Z","time spent":"7.000503607s","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-07T08:26:51.797434Z","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-07-07T08:26:52.298319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:52.798896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:53.289189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000915261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:53.289269Z","caller":"traceutil/trace.go:171","msg":"trace[1987533354] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001023304s","start":"2026-07-07T08:26:51.288228Z","end":"2026-07-07T08:26:53.289252Z","steps":["trace[1987533354] 'agreement among raft nodes before linearized reading' (duration: 2.000926091s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:53.289315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:51.288222Z","time spent":"2.001084255s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 08:26:53 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:53.299715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:53.800052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:54.301005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:54.601536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.995029064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:54.60162Z","caller":"traceutil/trace.go:171","msg":"trace[980536873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.995157728s","start":"2026-07-07T08:26:51.606443Z","end":"2026-07-07T08:26:54.601601Z","steps":["trace[980536873] 'agreement among raft nodes before linearized reading' (duration: 2.995055025s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:54.601669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:51.606424Z","time spent":"2.99523545s","remote":"127.0.0.1:60238","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/07/07 08:26:54 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:54.801522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:55.218189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000894651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:55.218276Z","caller":"traceutil/trace.go:171","msg":"trace[500025997] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001018164s","start":"2026-07-07T08:26:53.217235Z","end":"2026-07-07T08:26:55.218253Z","steps":["trace[500025997] 'agreement among raft nodes before linearized reading' (duration: 2.000915541s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:55.218329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:53.217216Z","time spent":"2.001098437s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 08:26:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:55.298158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000383956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:55.298237Z","caller":"traceutil/trace.go:171","msg":"trace[2103229335] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00050405s","start":"2026-07-07T08:26:53.297715Z","end":"2026-07-07T08:26:55.298219Z","steps":["trace[2103229335] 'agreement among raft nodes before linearized reading' (duration: 2.000405297s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:55.298338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:53.297699Z","time spent":"2.000626703s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 08:26:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:55.30232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:55.502096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999906531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:55.502318Z","caller":"traceutil/trace.go:171","msg":"trace[313968015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.000055185s","start":"2026-07-07T08:26:45.502119Z","end":"2026-07-07T08:26:55.502174Z","steps":["trace[313968015] 'agreement among raft nodes before linearized reading' (duration: 9.999936202s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:55.50256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:45.5021Z","time spent":"10.000439567s","remote":"127.0.0.1:60238","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/07/07 08:26:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:55.518047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999835528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:26:55.518103Z","caller":"traceutil/trace.go:171","msg":"trace[2077497663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"9.99991376s","start":"2026-07-07T08:26:45.518177Z","end":"2026-07-07T08:26:55.51809Z","steps":["trace[2077497663] 'agreement among raft nodes before linearized reading' (duration: 9.999853099s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:55.518134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:45.518167Z","time spent":"9.999958872s","remote":"127.0.0.1:60238","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/07/07 08:26:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:55.802489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:56.303325Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:56.6004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998442827s","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-07-07T08:26:56.600518Z","caller":"traceutil/trace.go:171","msg":"trace[2112580545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.998612642s","start":"2026-07-07T08:26:53.601881Z","end":"2026-07-07T08:26:56.600494Z","steps":["trace[2112580545] 'agreement among raft nodes before linearized reading' (duration: 2.998473038s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:56.600586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:53.60186Z","time spent":"2.998709255s","remote":"127.0.0.1:60238","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/07/07 08:26:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:56.600773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998862892s","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-07-07T08:26:56.600815Z","caller":"traceutil/trace.go:171","msg":"trace[973583674] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.998932644s","start":"2026-07-07T08:26:51.60187Z","end":"2026-07-07T08:26:56.600802Z","steps":["trace[973583674] 'agreement among raft nodes before linearized reading' (duration: 4.998888213s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:56.60088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:51.601856Z","time spent":"4.999011786s","remote":"127.0.0.1:60238","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/07/07 08:26:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:26:56.804156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:57.304666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:26:57.305831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000003264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T08:26:57.305913Z","caller":"traceutil/trace.go:171","msg":"trace[1866021372] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000137928s","start":"2026-07-07T08:26:55.305755Z","end":"2026-07-07T08:26:57.305893Z","steps":["trace[1866021372] 'agreement among raft nodes before linearized reading' (duration: 2.000032685s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:57.305962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:55.305735Z","time spent":"2.00021777s","remote":"127.0.0.1:59946","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-07-07T08:26:57.310462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999115088s","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-07-07T08:26:57.31053Z","caller":"traceutil/trace.go:171","msg":"trace[12082779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999236481s","start":"2026-07-07T08:26:52.311275Z","end":"2026-07-07T08:26:57.310511Z","steps":["trace[12082779] 'agreement among raft nodes before linearized reading' (duration: 4.999149279s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:57.310593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:52.311249Z","time spent":"4.999326864s","remote":"127.0.0.1:60238","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-07-07T08:26:57.489496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:50.488528Z","time spent":"7.000961257s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-07T08:26:57.609715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:50.608851Z","time spent":"7.000856954s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-07T08:26:57.773649Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"13.123147775s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-07T08:26:57.847941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.739695133s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:57.848195Z","caller":"traceutil/trace.go:171","msg":"trace[517345563] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1120; }","duration":"12.739970371s","start":"2026-07-07T08:26:45.108199Z","end":"2026-07-07T08:26:57.848169Z","steps":["trace[517345563] 'range keys from in-memory index tree' (duration: 12.739674983s)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.039922Z","caller":"traceutil/trace.go:171","msg":"trace[868906802] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1125; }","duration":"3.436447538s","start":"2026-07-07T08:26:54.603458Z","end":"2026-07-07T08:26:58.039906Z","steps":["trace[868906802] 'process raft request' (duration: 3.436367356s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.040043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:54.603436Z","time spent":"3.436538201s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":47,"response count":0,"response size":514,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:58.040223Z","caller":"traceutil/trace.go:171","msg":"trace[668802295] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"2.535780716s","start":"2026-07-07T08:26:55.504434Z","end":"2026-07-07T08:26:58.040215Z","steps":["trace[668802295] 'process raft request' (duration: 2.535426426s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.040282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:55.504418Z","time spent":"2.535833227s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:58.040334Z","caller":"traceutil/trace.go:171","msg":"trace[229732951] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"2.52013824s","start":"2026-07-07T08:26:55.520165Z","end":"2026-07-07T08:26:58.040304Z","steps":["trace[229732951] 'process raft request' (duration: 2.519972025s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.04078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:55.520073Z","time spent":"2.520625004s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:58.059922Z","caller":"traceutil/trace.go:171","msg":"trace[36328529] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"1.456717377s","start":"2026-07-07T08:26:56.603171Z","end":"2026-07-07T08:26:58.059889Z","steps":["trace[36328529] 'process raft request' (duration: 1.456589793s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:56.603135Z","time spent":"1.456842501s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:26:58.060123Z","caller":"traceutil/trace.go:171","msg":"trace[1934342247] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"1.456366837s","start":"2026-07-07T08:26:56.603723Z","end":"2026-07-07T08:26:58.06009Z","steps":["trace[1934342247] 'process raft request' (duration: 1.45612998s)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.060152Z","caller":"traceutil/trace.go:171","msg":"trace[705144230] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1168; }","duration":"6.262624421s","start":"2026-07-07T08:26:51.797494Z","end":"2026-07-07T08:26:58.060119Z","steps":["trace[705144230] 'read index received' (duration: 5.976363029s)","trace[705144230] 'applied index is now lower than readState.Index' (duration: 286.260732ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:26:58.060206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.928576192s","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-07-07T08:26:58.060247Z","caller":"traceutil/trace.go:171","msg":"trace[803313428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1130; }","duration":"6.928655004s","start":"2026-07-07T08:26:51.131582Z","end":"2026-07-07T08:26:58.060237Z","steps":["trace[803313428] 'agreement among raft nodes before linearized reading' (duration: 6.928580172s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:56.603696Z","time spent":"1.456498111s","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T08:26:58.06027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.158011665s","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-07-07T08:26:58.060321Z","caller":"traceutil/trace.go:171","msg":"trace[1391595100] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1130; }","duration":"566.800504ms","start":"2026-07-07T08:26:57.493513Z","end":"2026-07-07T08:26:58.060314Z","steps":["trace[1391595100] 'process raft request' (duration: 566.480924ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.060336Z","caller":"traceutil/trace.go:171","msg":"trace[1900883569] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1130; }","duration":"11.158129859s","start":"2026-07-07T08:26:46.902193Z","end":"2026-07-07T08:26:58.060323Z","steps":["trace[1900883569] 'agreement among raft nodes before linearized reading' (duration: 11.157985625s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.777596534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:7075"} {"level":"warn","ts":"2026-07-07T08:26:58.060353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.615073656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T08:26:58.060375Z","caller":"traceutil/trace.go:171","msg":"trace[1259916680] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1130; }","duration":"6.777652915s","start":"2026-07-07T08:26:51.282714Z","end":"2026-07-07T08:26:58.060367Z","steps":["trace[1259916680] 'agreement among raft nodes before linearized reading' (duration: 6.777574913s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:57.49349Z","time spent":"566.862316ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":722,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:26:58.060383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:46.902178Z","time spent":"11.15819319s","remote":"127.0.0.1:60448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:58.060398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:51.282689Z","time spent":"6.777705657s","remote":"127.0.0.1:60158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":7099,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-07-07T08:26:58.060434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.948476541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.39\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:58.060454Z","caller":"traceutil/trace.go:171","msg":"trace[328073761] range","detail":"{range_begin:/registry/masterleases/199.19.213.39; range_end:; response_count:0; response_revision:1130; }","duration":"12.948528303s","start":"2026-07-07T08:26:45.111921Z","end":"2026-07-07T08:26:58.060449Z","steps":["trace[328073761] 'agreement among raft nodes before linearized reading' (duration: 12.948494022s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:45.111901Z","time spent":"12.948574624s","remote":"127.0.0.1:59994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/199.19.213.39\" "} {"level":"warn","ts":"2026-07-07T08:26:58.060276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:51.131563Z","time spent":"6.928706726s","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:58.060559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.035478657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:58.060589Z","caller":"traceutil/trace.go:171","msg":"trace[1330136558] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1130; }","duration":"6.035551458s","start":"2026-07-07T08:26:52.025029Z","end":"2026-07-07T08:26:58.060581Z","steps":["trace[1330136558] 'agreement among raft nodes before linearized reading' (duration: 6.035507107s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:52.025006Z","time spent":"6.03560174s","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-07-07T08:26:58.060399Z","caller":"traceutil/trace.go:171","msg":"trace[212537986] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1130; }","duration":"12.615163509s","start":"2026-07-07T08:26:45.445227Z","end":"2026-07-07T08:26:58.060391Z","steps":["trace[212537986] 'agreement among raft nodes before linearized reading' (duration: 12.615093297s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.777715733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bff4791e211a20\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-07-07T08:26:58.060712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:45.445204Z","time spent":"12.615495179s","remote":"127.0.0.1:60490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:58.060754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.446343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:58.06078Z","caller":"traceutil/trace.go:171","msg":"trace[1480492494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1130; }","duration":"754.498135ms","start":"2026-07-07T08:26:57.306276Z","end":"2026-07-07T08:26:58.060774Z","steps":["trace[1480492494] 'agreement among raft nodes before linearized reading' (duration: 754.460994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:57.306258Z","time spent":"754.539366ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T08:26:58.060301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.021912657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T08:26:58.060286Z","caller":"traceutil/trace.go:171","msg":"trace[947297989] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1130; }","duration":"446.6273ms","start":"2026-07-07T08:26:57.613645Z","end":"2026-07-07T08:26:58.060272Z","steps":["trace[947297989] 'process raft request' (duration: 446.432755ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.060848Z","caller":"traceutil/trace.go:171","msg":"trace[423375293] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1130; }","duration":"12.022517175s","start":"2026-07-07T08:26:46.038321Z","end":"2026-07-07T08:26:58.060838Z","steps":["trace[423375293] 'agreement among raft nodes before linearized reading' (duration: 12.021931058s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.06087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99917731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T08:26:58.060878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:57.613608Z","time spent":"447.245148ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":571,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:26:58.060883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:46.038301Z","time spent":"12.022571566s","remote":"127.0.0.1:60154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-07T08:26:58.060901Z","caller":"traceutil/trace.go:171","msg":"trace[489291295] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1130; }","duration":"4.999235492s","start":"2026-07-07T08:26:53.061656Z","end":"2026-07-07T08:26:58.060892Z","steps":["trace[489291295] 'agreement among raft nodes before linearized reading' (duration: 4.99918859s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.060926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:53.061641Z","time spent":"4.999279813s","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:58.060935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.746852597s","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-07-07T08:26:58.060993Z","caller":"traceutil/trace.go:171","msg":"trace[1403439771] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1130; }","duration":"3.74695054s","start":"2026-07-07T08:26:54.314025Z","end":"2026-07-07T08:26:58.060976Z","steps":["trace[1403439771] 'agreement among raft nodes before linearized reading' (duration: 3.746858657s)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.060727Z","caller":"traceutil/trace.go:171","msg":"trace[2110693407] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18bff4791e211a20; range_end:; response_count:1; response_revision:1130; }","duration":"8.777784804s","start":"2026-07-07T08:26:49.282935Z","end":"2026-07-07T08:26:58.06072Z","steps":["trace[2110693407] 'agreement among raft nodes before linearized reading' (duration: 8.777658981s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.061036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:54.314Z","time spent":"3.747026713s","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:26:58.061055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:49.282915Z","time spent":"8.778130785s","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bff4791e211a20\" "} {"level":"info","ts":"2026-07-07T08:26:58.306415Z","caller":"traceutil/trace.go:171","msg":"trace[1953047686] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1182; }","duration":"229.807543ms","start":"2026-07-07T08:26:58.076587Z","end":"2026-07-07T08:26:58.306395Z","steps":["trace[1953047686] 'read index received' (duration: 229.168204ms)","trace[1953047686] 'applied index is now lower than readState.Index' (duration: 638.329µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:26:58.306884Z","caller":"traceutil/trace.go:171","msg":"trace[1407104217] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"238.09691ms","start":"2026-07-07T08:26:58.068328Z","end":"2026-07-07T08:26:58.306425Z","steps":["trace[1407104217] 'process raft request' (duration: 237.525643ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.306929Z","caller":"traceutil/trace.go:171","msg":"trace[1281444405] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"238.822222ms","start":"2026-07-07T08:26:58.068101Z","end":"2026-07-07T08:26:58.306923Z","steps":["trace[1281444405] 'process raft request' (duration: 237.605395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.306936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.707599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:26:58.307477Z","caller":"traceutil/trace.go:171","msg":"trace[981893740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1132; }","duration":"238.295766ms","start":"2026-07-07T08:26:58.06917Z","end":"2026-07-07T08:26:58.307466Z","steps":["trace[981893740] 'agreement among raft nodes before linearized reading' (duration: 237.267086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.307621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.675397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:687"} {"level":"info","ts":"2026-07-07T08:26:58.308219Z","caller":"traceutil/trace.go:171","msg":"trace[1643000894] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:1132; }","duration":"243.279765ms","start":"2026-07-07T08:26:58.06485Z","end":"2026-07-07T08:26:58.30813Z","steps":["trace[1643000894] 'agreement among raft nodes before linearized reading' (duration: 242.11206ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.314421Z","caller":"traceutil/trace.go:171","msg":"trace[2006206015] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"236.960946ms","start":"2026-07-07T08:26:58.077435Z","end":"2026-07-07T08:26:58.314396Z","steps":["trace[2006206015] 'process raft request' (duration: 236.236714ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.314549Z","caller":"traceutil/trace.go:171","msg":"trace[1040838943] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"235.018378ms","start":"2026-07-07T08:26:58.079468Z","end":"2026-07-07T08:26:58.314486Z","steps":["trace[1040838943] 'process raft request' (duration: 234.758299ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.783115Z","caller":"traceutil/trace.go:171","msg":"trace[529039618] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"213.864539ms","start":"2026-07-07T08:26:58.56923Z","end":"2026-07-07T08:26:58.783094Z","steps":["trace[529039618] 'process raft request' (duration: 213.715445ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:58.95393Z","caller":"traceutil/trace.go:171","msg":"trace[1430525225] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"167.214642ms","start":"2026-07-07T08:26:58.7867Z","end":"2026-07-07T08:26:58.953915Z","steps":["trace[1430525225] 'read index received' (duration: 167.054208ms)","trace[1430525225] 'applied index is now lower than readState.Index' (duration: 159.474µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:26:58.953989Z","caller":"traceutil/trace.go:171","msg":"trace[458092035] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"361.441728ms","start":"2026-07-07T08:26:58.592488Z","end":"2026-07-07T08:26:58.95393Z","steps":["trace[458092035] 'process raft request' (duration: 361.203681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:26:58.954267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:26:58.592471Z","time spent":"361.748906ms","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T08:26:58.954171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.46267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-07-07T08:26:58.954393Z","caller":"traceutil/trace.go:171","msg":"trace[967426220] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1144; }","duration":"167.730468ms","start":"2026-07-07T08:26:58.786647Z","end":"2026-07-07T08:26:58.954377Z","steps":["trace[967426220] 'agreement among raft nodes before linearized reading' (duration: 167.391338ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:59.448547Z","caller":"traceutil/trace.go:171","msg":"trace[1216739549] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"152.643698ms","start":"2026-07-07T08:26:59.295886Z","end":"2026-07-07T08:26:59.44853Z","steps":["trace[1216739549] 'process raft request' (duration: 145.561968ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:59.46562Z","caller":"traceutil/trace.go:171","msg":"trace[225700889] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"106.033892ms","start":"2026-07-07T08:26:59.359539Z","end":"2026-07-07T08:26:59.465572Z","steps":["trace[225700889] 'process raft request' (duration: 105.886788ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:26:59.597343Z","caller":"traceutil/trace.go:171","msg":"trace[1533699274] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"122.479762ms","start":"2026-07-07T08:26:59.474801Z","end":"2026-07-07T08:26:59.59728Z","steps":["trace[1533699274] 'process raft request' (duration: 114.528726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:27:00.268248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.912698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:429"} {"level":"info","ts":"2026-07-07T08:27:00.268341Z","caller":"traceutil/trace.go:171","msg":"trace[1703890433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1152; }","duration":"112.044832ms","start":"2026-07-07T08:27:00.156274Z","end":"2026-07-07T08:27:00.268319Z","steps":["trace[1703890433] 'range keys from in-memory index tree' (duration: 111.752423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:27:00.268243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.915148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:425"} {"level":"info","ts":"2026-07-07T08:27:00.268458Z","caller":"traceutil/trace.go:171","msg":"trace[2108434388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1152; }","duration":"112.144265ms","start":"2026-07-07T08:27:00.156296Z","end":"2026-07-07T08:27:00.268441Z","steps":["trace[2108434388] 'range keys from in-memory index tree' (duration: 111.796314ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:27:00.628789Z","caller":"traceutil/trace.go:171","msg":"trace[2106947179] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"135.749377ms","start":"2026-07-07T08:27:00.49301Z","end":"2026-07-07T08:27:00.628759Z","steps":["trace[2106947179] 'process raft request' (duration: 54.15124ms)","trace[2106947179] 'compare' (duration: 81.436152ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:27:00.818382Z","caller":"traceutil/trace.go:171","msg":"trace[606891061] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1212; }","duration":"172.033205ms","start":"2026-07-07T08:27:00.646301Z","end":"2026-07-07T08:27:00.818334Z","steps":["trace[606891061] 'read index received' (duration: 67.476896ms)","trace[606891061] 'applied index is now lower than readState.Index' (duration: 104.554789ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:27:00.818444Z","caller":"traceutil/trace.go:171","msg":"trace[98911770] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"180.855208ms","start":"2026-07-07T08:27:00.637563Z","end":"2026-07-07T08:27:00.818418Z","steps":["trace[98911770] 'process raft request' (duration: 102.831188ms)","trace[98911770] 'compare' (duration: 77.802443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:27:00.818555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.227631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-07T08:27:00.818615Z","caller":"traceutil/trace.go:171","msg":"trace[831802907] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:1161; }","duration":"172.325764ms","start":"2026-07-07T08:27:00.646274Z","end":"2026-07-07T08:27:00.818599Z","steps":["trace[831802907] 'agreement among raft nodes before linearized reading' (duration: 172.19358ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:27:02.109128Z","caller":"traceutil/trace.go:171","msg":"trace[1577402829] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"125.10614ms","start":"2026-07-07T08:27:01.983993Z","end":"2026-07-07T08:27:02.109099Z","steps":["trace[1577402829] 'process raft request' (duration: 124.932725ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:27:03.19295Z","caller":"traceutil/trace.go:171","msg":"trace[244666410] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"124.675217ms","start":"2026-07-07T08:27:03.068239Z","end":"2026-07-07T08:27:03.192914Z","steps":["trace[244666410] 'process raft request' (duration: 95.592253ms)","trace[244666410] 'compare' (duration: 28.9442ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:27:10.97438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:27:11.474969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873300351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:27:11.491358Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.186288274s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T08:27:11.491698Z","caller":"traceutil/trace.go:171","msg":"trace[1752549794] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"1.018157955s","start":"2026-07-07T08:27:10.473518Z","end":"2026-07-07T08:27:11.491676Z","steps":["trace[1752549794] 'read index received' (duration: 1.017962679s)","trace[1752549794] 'applied index is now lower than readState.Index' (duration: 194.496µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:27:11.491807Z","caller":"traceutil/trace.go:171","msg":"trace[760530575] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"1.186853311s","start":"2026-07-07T08:27:10.304946Z","end":"2026-07-07T08:27:11.491799Z","steps":["trace[760530575] 'process raft request' (duration: 1.186606783s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:27:11.491918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:27:10.304923Z","time spent":"1.186905663s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:27:11.492116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018603739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T08:27:11.492144Z","caller":"traceutil/trace.go:171","msg":"trace[2051242932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"1.018672662s","start":"2026-07-07T08:27:10.473465Z","end":"2026-07-07T08:27:11.492137Z","steps":["trace[2051242932] 'agreement among raft nodes before linearized reading' (duration: 1.018532726s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:27:11.492166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:27:10.473444Z","time spent":"1.018717292s","remote":"127.0.0.1:60238","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-07-07T08:27:11.492258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.054058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-07T08:27:11.492275Z","caller":"traceutil/trace.go:171","msg":"trace[297475198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"580.106599ms","start":"2026-07-07T08:27:10.912163Z","end":"2026-07-07T08:27:11.492269Z","steps":["trace[297475198] 'agreement among raft nodes before linearized reading' (duration: 580.052168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:27:11.492292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:27:10.912142Z","time spent":"580.145411ms","remote":"127.0.0.1:60238","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-07-07T08:27:11.49232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.174304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-07T08:27:11.492395Z","caller":"traceutil/trace.go:171","msg":"trace[121770857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1195; }","duration":"949.283877ms","start":"2026-07-07T08:27:10.543087Z","end":"2026-07-07T08:27:11.492371Z","steps":["trace[121770857] 'agreement among raft nodes before linearized reading' (duration: 949.04353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:27:11.492462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:27:10.543043Z","time spent":"949.405132ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-07T08:27:11.492816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.754027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:27:11.492907Z","caller":"traceutil/trace.go:171","msg":"trace[972407712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"212.474249ms","start":"2026-07-07T08:27:11.280414Z","end":"2026-07-07T08:27:11.492889Z","steps":["trace[972407712] 'agreement among raft nodes before linearized reading' (duration: 211.737917ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:28:00.704519Z","caller":"traceutil/trace.go:171","msg":"trace[1121504582] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"124.095645ms","start":"2026-07-07T08:28:00.580392Z","end":"2026-07-07T08:28:00.704488Z","steps":["trace[1121504582] 'process raft request' (duration: 123.897479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:12.667744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301109,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T08:28:12.877201Z","caller":"traceutil/trace.go:171","msg":"trace[612950541] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"856.825897ms","start":"2026-07-07T08:28:12.020353Z","end":"2026-07-07T08:28:12.877179Z","steps":["trace[612950541] 'process raft request' (duration: 851.106107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:12.877572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:12.020315Z","time spent":"857.177917ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:28:12.882177Z","caller":"traceutil/trace.go:171","msg":"trace[961955019] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"859.97108ms","start":"2026-07-07T08:28:12.022186Z","end":"2026-07-07T08:28:12.882157Z","steps":["trace[961955019] 'process raft request' (duration: 859.807185ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:28:12.882251Z","caller":"traceutil/trace.go:171","msg":"trace[429554738] linearizableReadLoop","detail":"{readStateIndex:1583; appliedIndex:1581; }","duration":"715.344046ms","start":"2026-07-07T08:28:12.166892Z","end":"2026-07-07T08:28:12.882236Z","steps":["trace[429554738] 'read index received' (duration: 704.577417ms)","trace[429554738] 'applied index is now lower than readState.Index' (duration: 10.765079ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:28:12.88237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:12.022172Z","time spent":"860.063402ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:28:12.882416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.514332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-07T08:28:12.882458Z","caller":"traceutil/trace.go:171","msg":"trace[1743362379] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1514; }","duration":"715.594034ms","start":"2026-07-07T08:28:12.166853Z","end":"2026-07-07T08:28:12.882447Z","steps":["trace[1743362379] 'agreement among raft nodes before linearized reading' (duration: 715.472061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:12.882484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:12.166818Z","time spent":"715.659316ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-07T08:28:12.882521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.056898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"warn","ts":"2026-07-07T08:28:12.882561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.252656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-07T08:28:12.882605Z","caller":"traceutil/trace.go:171","msg":"trace[436088408] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1514; }","duration":"427.178841ms","start":"2026-07-07T08:28:12.455404Z","end":"2026-07-07T08:28:12.882583Z","steps":["trace[436088408] 'agreement among raft nodes before linearized reading' (duration: 426.989086ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:28:12.882622Z","caller":"traceutil/trace.go:171","msg":"trace[2038925943] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1514; }","duration":"264.357909ms","start":"2026-07-07T08:28:12.61825Z","end":"2026-07-07T08:28:12.882608Z","steps":["trace[2038925943] 'agreement among raft nodes before linearized reading' (duration: 264.154673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:12.882651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:12.455383Z","time spent":"427.256914ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-07-07T08:28:23.449908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:23.950297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:24.004803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088777353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-07T08:28:24.004894Z","caller":"traceutil/trace.go:171","msg":"trace[425948308] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1557; }","duration":"1.088906917s","start":"2026-07-07T08:28:22.91597Z","end":"2026-07-07T08:28:24.004877Z","steps":["trace[425948308] 'range keys from in-memory index tree' (duration: 1.088660629s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.004938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:22.915949Z","time spent":"1.088980808s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-07T08:28:24.005109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086968728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T08:28:24.005141Z","caller":"traceutil/trace.go:171","msg":"trace[1316080710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1557; }","duration":"1.08701653s","start":"2026-07-07T08:28:22.918118Z","end":"2026-07-07T08:28:24.005135Z","steps":["trace[1316080710] 'range keys from in-memory index tree' (duration: 1.086896957s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.005168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:22.918113Z","time spent":"1.087049271s","remote":"127.0.0.1:60238","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-07-07T08:28:24.005281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082235168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-07T08:28:24.005314Z","caller":"traceutil/trace.go:171","msg":"trace[1719317373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1557; }","duration":"1.082274288s","start":"2026-07-07T08:28:22.923031Z","end":"2026-07-07T08:28:24.005305Z","steps":["trace[1719317373] 'range keys from in-memory index tree' (duration: 1.082187517s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.005341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:22.923027Z","time spent":"1.08230861s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-07T08:28:24.005331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.005086004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-07T08:28:24.005393Z","caller":"traceutil/trace.go:171","msg":"trace[1485999127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1557; }","duration":"2.005182818s","start":"2026-07-07T08:28:22.000196Z","end":"2026-07-07T08:28:24.005379Z","steps":["trace[1485999127] 'range keys from in-memory index tree' (duration: 2.00497054s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.005421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:22.000182Z","time spent":"2.005233499s","remote":"127.0.0.1:60238","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-07-07T08:28:24.005591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.058735007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-07T08:28:24.005607Z","caller":"traceutil/trace.go:171","msg":"trace[427897603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1557; }","duration":"2.058752087s","start":"2026-07-07T08:28:21.94685Z","end":"2026-07-07T08:28:24.005602Z","steps":["trace[427897603] 'range keys from in-memory index tree' (duration: 2.05852367s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.005621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:21.946759Z","time spent":"2.058857991s","remote":"127.0.0.1:60238","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-07-07T08:28:24.005842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000721755s","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-07-07T08:28:24.005858Z","caller":"traceutil/trace.go:171","msg":"trace[2046337285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1557; }","duration":"2.000747735s","start":"2026-07-07T08:28:22.005105Z","end":"2026-07-07T08:28:24.005852Z","steps":["trace[2046337285] 'range keys from in-memory index tree' (duration: 2.000675633s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.005871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:22.005099Z","time spent":"2.000768946s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T08:28:24.008378Z","caller":"traceutil/trace.go:171","msg":"trace[1787323042] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"1.072080196s","start":"2026-07-07T08:28:22.936282Z","end":"2026-07-07T08:28:24.008362Z","steps":["trace[1787323042] 'process raft request' (duration: 1.066897022s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.008456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:22.93627Z","time spent":"1.072131377s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:28:24.412883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.069285ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-07T08:28:24.413056Z","caller":"traceutil/trace.go:171","msg":"trace[912021387] linearizableReadLoop","detail":"{readStateIndex:1631; appliedIndex:1629; }","duration":"1.465918549s","start":"2026-07-07T08:28:22.947116Z","end":"2026-07-07T08:28:24.413035Z","steps":["trace[912021387] 'read index received' (duration: 1.056072841s)","trace[912021387] 'applied index is now lower than readState.Index' (duration: 409.844348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:28:24.413394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466137476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-07T08:28:24.413428Z","caller":"traceutil/trace.go:171","msg":"trace[98538184] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1559; }","duration":"1.466330001s","start":"2026-07-07T08:28:22.947088Z","end":"2026-07-07T08:28:24.413418Z","steps":["trace[98538184] 'agreement among raft nodes before linearized reading' (duration: 1.466000581s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.413557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:22.947077Z","time spent":"1.466367212s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-07T08:28:24.413697Z","caller":"traceutil/trace.go:171","msg":"trace[915418577] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"397.298915ms","start":"2026-07-07T08:28:24.016385Z","end":"2026-07-07T08:28:24.413684Z","steps":["trace[915418577] 'process raft request' (duration: 397.105349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.413775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:24.016377Z","time spent":"397.352406ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:28:24.413802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126846062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:28:24.413825Z","caller":"traceutil/trace.go:171","msg":"trace[901435540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1564; }","duration":"1.126924235s","start":"2026-07-07T08:28:23.286893Z","end":"2026-07-07T08:28:24.413818Z","steps":["trace[901435540] 'agreement among raft nodes before linearized reading' (duration: 1.12676656s)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:28:24.413867Z","caller":"traceutil/trace.go:171","msg":"trace[190071794] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"400.622043ms","start":"2026-07-07T08:28:24.013237Z","end":"2026-07-07T08:28:24.413859Z","steps":["trace[190071794] 'process raft request' (duration: 399.746657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.41391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:24.013221Z","time spent":"400.664725ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:28:24.413845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:23.286863Z","time spent":"1.126977165s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-07T08:28:24.413962Z","caller":"traceutil/trace.go:171","msg":"trace[29651299] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"399.930003ms","start":"2026-07-07T08:28:24.014025Z","end":"2026-07-07T08:28:24.413955Z","steps":["trace[29651299] 'process raft request' (duration: 399.289884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.413996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:24.01402Z","time spent":"399.956933ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T08:28:24.414022Z","caller":"traceutil/trace.go:171","msg":"trace[1213761665] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"400.103067ms","start":"2026-07-07T08:28:24.013905Z","end":"2026-07-07T08:28:24.414008Z","steps":["trace[1213761665] 'process raft request' (duration: 399.258053ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:28:24.414012Z","caller":"traceutil/trace.go:171","msg":"trace[80428186] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"400.005675ms","start":"2026-07-07T08:28:24.013971Z","end":"2026-07-07T08:28:24.413976Z","steps":["trace[80428186] 'process raft request' (duration: 399.328546ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:28:24.414109Z","caller":"traceutil/trace.go:171","msg":"trace[1972139947] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"397.675165ms","start":"2026-07-07T08:28:24.016427Z","end":"2026-07-07T08:28:24.414103Z","steps":["trace[1972139947] 'process raft request' (duration: 397.092758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.414106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:24.013888Z","time spent":"400.18234ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:28:24.414147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:24.016423Z","time spent":"397.703667ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:28:24.414242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.119782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T08:28:24.414215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.928675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:28:24.414264Z","caller":"traceutil/trace.go:171","msg":"trace[695766374] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1564; }","duration":"518.179084ms","start":"2026-07-07T08:28:23.896079Z","end":"2026-07-07T08:28:24.414258Z","steps":["trace[695766374] 'agreement among raft nodes before linearized reading' (duration: 518.140343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.41429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:23.896053Z","time spent":"518.230674ms","remote":"127.0.0.1:60154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-07T08:28:24.414292Z","caller":"traceutil/trace.go:171","msg":"trace[1489338107] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1564; }","duration":"518.033229ms","start":"2026-07-07T08:28:23.896245Z","end":"2026-07-07T08:28:24.414278Z","steps":["trace[1489338107] 'agreement among raft nodes before linearized reading' (duration: 517.896505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.414324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:23.896241Z","time spent":"518.07327ms","remote":"127.0.0.1:40898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:28:24.414087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.73197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-07T08:28:24.414594Z","caller":"traceutil/trace.go:171","msg":"trace[609327478] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1564; }","duration":"518.237815ms","start":"2026-07-07T08:28:23.896339Z","end":"2026-07-07T08:28:24.414577Z","steps":["trace[609327478] 'agreement among raft nodes before linearized reading' (duration: 517.700699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:24.414793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:24.013965Z","time spent":"400.15147ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:28:24.414635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:23.896336Z","time spent":"518.285506ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-07T08:28:44.629301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:45.130257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:45.631277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:46.128638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000318821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T08:28:46.128705Z","caller":"traceutil/trace.go:171","msg":"trace[1375277577] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000464497s","start":"2026-07-07T08:28:44.128227Z","end":"2026-07-07T08:28:46.128691Z","steps":["trace[1375277577] 'agreement among raft nodes before linearized reading' (duration: 2.000353532s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:46.128738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.128175Z","time spent":"2.000556309s","remote":"127.0.0.1:59946","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-07-07T08:28:46.131527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:46.278143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000426974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-07T08:28:46.278228Z","caller":"traceutil/trace.go:171","msg":"trace[2066154601] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000552898s","start":"2026-07-07T08:28:44.277655Z","end":"2026-07-07T08:28:46.278208Z","steps":["trace[2066154601] 'agreement among raft nodes before linearized reading' (duration: 2.000450435s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:46.278275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.277635Z","time spent":"2.00063071s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 08:28:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:28:46.632536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:47.133553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:47.633834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:48.134185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:48.285254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000239109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T08:28:48.285346Z","caller":"traceutil/trace.go:171","msg":"trace[2028474048] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000383803s","start":"2026-07-07T08:28:46.284943Z","end":"2026-07-07T08:28:48.285327Z","steps":["trace[2028474048] 'agreement among raft nodes before linearized reading' (duration: 2.000277159s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.2854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:46.284922Z","time spent":"2.000464454s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/07 08:28:48 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-07T08:28:48.634615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787341493873301439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T08:28:48.770475Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.695964005s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T08:28:48.770725Z","caller":"traceutil/trace.go:171","msg":"trace[394972863] linearizableReadLoop","detail":"{readStateIndex:1727; appliedIndex:1726; }","duration":"4.642427389s","start":"2026-07-07T08:28:44.128283Z","end":"2026-07-07T08:28:48.77071Z","steps":["trace[394972863] 'read index received' (duration: 4.642270555s)","trace[394972863] 'applied index is now lower than readState.Index' (duration: 156.264µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T08:28:48.770778Z","caller":"traceutil/trace.go:171","msg":"trace[175460148] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"4.696410828s","start":"2026-07-07T08:28:44.07436Z","end":"2026-07-07T08:28:48.770771Z","steps":["trace[175460148] 'process raft request' (duration: 4.696237363s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.770868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.074328Z","time spent":"4.69646667s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T08:28:48.771078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.319851235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-07T08:28:48.771102Z","caller":"traceutil/trace.go:171","msg":"trace[1655449661] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1650; }","duration":"4.319915888s","start":"2026-07-07T08:28:44.45118Z","end":"2026-07-07T08:28:48.771096Z","steps":["trace[1655449661] 'agreement among raft nodes before linearized reading' (duration: 4.319786384s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.771118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.451156Z","time spent":"4.319958328s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-07T08:28:48.777743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.25923725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-07T08:28:48.777831Z","caller":"traceutil/trace.go:171","msg":"trace[1244406099] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1650; }","duration":"4.259355573s","start":"2026-07-07T08:28:44.518459Z","end":"2026-07-07T08:28:48.777815Z","steps":["trace[1244406099] 'agreement among raft nodes before linearized reading' (duration: 4.259128607s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.777871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.518451Z","time spent":"4.259411435s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-07T08:28:48.777976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.786418956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:28:48.778007Z","caller":"traceutil/trace.go:171","msg":"trace[632105623] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1650; }","duration":"3.786480188s","start":"2026-07-07T08:28:44.991514Z","end":"2026-07-07T08:28:48.777994Z","steps":["trace[632105623] 'agreement among raft nodes before linearized reading' (duration: 3.786430066s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.778027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.991498Z","time spent":"3.786522799s","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:28:48.778065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.353988984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T08:28:48.778191Z","caller":"traceutil/trace.go:171","msg":"trace[1724741452] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1650; }","duration":"2.354090107s","start":"2026-07-07T08:28:46.42403Z","end":"2026-07-07T08:28:48.77812Z","steps":["trace[1724741452] 'agreement among raft nodes before linearized reading' (duration: 2.353892441s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.778204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.024219469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:28:48.778232Z","caller":"traceutil/trace.go:171","msg":"trace[1566491324] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1650; }","duration":"4.024271311s","start":"2026-07-07T08:28:44.753948Z","end":"2026-07-07T08:28:48.77822Z","steps":["trace[1566491324] 'agreement among raft nodes before linearized reading' (duration: 4.02422514s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.778226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:46.424012Z","time spent":"2.354197891s","remote":"127.0.0.1:60494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:28:48.778251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.753935Z","time spent":"4.024310042s","remote":"127.0.0.1:60288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:28:48.778405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.257727174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-07T08:28:48.778435Z","caller":"traceutil/trace.go:171","msg":"trace[1228199921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1650; }","duration":"4.257766496s","start":"2026-07-07T08:28:44.520653Z","end":"2026-07-07T08:28:48.77842Z","steps":["trace[1228199921] 'agreement among raft nodes before linearized reading' (duration: 4.257706464s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.778439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.544947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.39\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-07T08:28:48.778455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.520615Z","time spent":"4.257835369s","remote":"127.0.0.1:60238","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":"info","ts":"2026-07-07T08:28:48.778469Z","caller":"traceutil/trace.go:171","msg":"trace[1745799983] range","detail":"{range_begin:/registry/masterleases/199.19.213.39; range_end:; response_count:1; response_revision:1650; }","duration":"336.596928ms","start":"2026-07-07T08:28:48.441858Z","end":"2026-07-07T08:28:48.778455Z","steps":["trace[1745799983] 'agreement among raft nodes before linearized reading' (duration: 336.525196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.77849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:48.441844Z","time spent":"336.63885ms","remote":"127.0.0.1:59994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.39\" "} {"level":"warn","ts":"2026-07-07T08:28:48.778546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.258257862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-07T08:28:48.778568Z","caller":"traceutil/trace.go:171","msg":"trace[167295650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1650; }","duration":"4.258297042s","start":"2026-07-07T08:28:44.520261Z","end":"2026-07-07T08:28:48.778558Z","steps":["trace[167295650] 'agreement among raft nodes before linearized reading' (duration: 4.25823639s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.778584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.52025Z","time spent":"4.258329814s","remote":"127.0.0.1:60238","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-07-07T08:28:48.77867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.193976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:28:48.77872Z","caller":"traceutil/trace.go:171","msg":"trace[2135809760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1650; }","duration":"493.261228ms","start":"2026-07-07T08:28:48.285429Z","end":"2026-07-07T08:28:48.778691Z","steps":["trace[2135809760] 'agreement among raft nodes before linearized reading' (duration: 493.207976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.778747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:48.285411Z","time spent":"493.32885ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T08:28:48.778907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.349553124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-07T08:28:48.778933Z","caller":"traceutil/trace.go:171","msg":"trace[1670520334] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1650; }","duration":"2.349596975s","start":"2026-07-07T08:28:46.429324Z","end":"2026-07-07T08:28:48.778921Z","steps":["trace[1670520334] 'agreement among raft nodes before linearized reading' (duration: 2.349537653s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.77897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:46.429286Z","time spent":"2.349678098s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-07T08:28:48.779313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.643438413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-07T08:28:48.779347Z","caller":"traceutil/trace.go:171","msg":"trace[25734804] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1650; }","duration":"3.643486495s","start":"2026-07-07T08:28:45.135843Z","end":"2026-07-07T08:28:48.77933Z","steps":["trace[25734804] 'agreement among raft nodes before linearized reading' (duration: 3.643414033s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.779368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:45.135832Z","time spent":"3.643529306s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-07T08:28:48.779745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.406574903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:28:48.779778Z","caller":"traceutil/trace.go:171","msg":"trace[1942960306] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1650; }","duration":"2.406629514s","start":"2026-07-07T08:28:46.373137Z","end":"2026-07-07T08:28:48.779766Z","steps":["trace[1942960306] 'agreement among raft nodes before linearized reading' (duration: 2.406567102s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.779802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:46.373096Z","time spent":"2.406696296s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:28:48.780218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.648419015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bff48169ebc371\" ","response":"range_response_count:1 size:773"} {"level":"info","ts":"2026-07-07T08:28:48.780247Z","caller":"traceutil/trace.go:171","msg":"trace[1608735208] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18bff48169ebc371; range_end:; response_count:1; response_revision:1650; }","duration":"2.648481846s","start":"2026-07-07T08:28:46.131752Z","end":"2026-07-07T08:28:48.780234Z","steps":["trace[1608735208] 'agreement among raft nodes before linearized reading' (duration: 2.648352712s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.780269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:46.131731Z","time spent":"2.648532118s","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bff48169ebc371\" "} {"level":"warn","ts":"2026-07-07T08:28:48.781131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.269454383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-07T08:28:48.781157Z","caller":"traceutil/trace.go:171","msg":"trace[511337188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1650; }","duration":"4.269508775s","start":"2026-07-07T08:28:44.51164Z","end":"2026-07-07T08:28:48.781149Z","steps":["trace[511337188] 'agreement among raft nodes before linearized reading' (duration: 4.269433523s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.781182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.511629Z","time spent":"4.269547426s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-07T08:28:48.781246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.653876581s","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":"warn","ts":"2026-07-07T08:28:48.781315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.265376502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-07T08:28:48.781301Z","caller":"traceutil/trace.go:171","msg":"trace[1841313211] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1650; }","duration":"3.653977504s","start":"2026-07-07T08:28:45.12731Z","end":"2026-07-07T08:28:48.781288Z","steps":["trace[1841313211] 'agreement among raft nodes before linearized reading' (duration: 3.653898282s)"],"step_count":1} {"level":"info","ts":"2026-07-07T08:28:48.781343Z","caller":"traceutil/trace.go:171","msg":"trace[217136299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1650; }","duration":"4.265451043s","start":"2026-07-07T08:28:44.51588Z","end":"2026-07-07T08:28:48.781331Z","steps":["trace[217136299] 'agreement among raft nodes before linearized reading' (duration: 4.265388832s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.781362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.515872Z","time spent":"4.265485265s","remote":"127.0.0.1:60238","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-07-07T08:28:48.78137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:45.12729Z","time spent":"3.654051567s","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-07T08:28:48.781739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.27170066s","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-07-07T08:28:48.781778Z","caller":"traceutil/trace.go:171","msg":"trace[456117304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1650; }","duration":"4.271739741s","start":"2026-07-07T08:28:44.51003Z","end":"2026-07-07T08:28:48.78177Z","steps":["trace[456117304] 'agreement among raft nodes before linearized reading' (duration: 4.271653039s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.781806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.510013Z","time spent":"4.271778272s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T08:28:48.783185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.326878284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-07T08:28:48.783249Z","caller":"traceutil/trace.go:171","msg":"trace[477141414] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1650; }","duration":"4.328211273s","start":"2026-07-07T08:28:44.455012Z","end":"2026-07-07T08:28:48.783223Z","steps":["trace[477141414] 'agreement among raft nodes before linearized reading' (duration: 4.326811372s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T08:28:48.783365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:44.454998Z","time spent":"4.328276105s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-07T08:28:48.788253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.702504522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T08:28:48.78897Z","caller":"traceutil/trace.go:171","msg":"trace[1356846778] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1650; }","duration":"3.703211493s","start":"2026-07-07T08:28:45.085719Z","end":"2026-07-07T08:28:48.788931Z","steps":["trace[1356846778] 'agreement among raft nodes before linearized reading' (duration: 3.685423376s)","trace[1356846778] 'filter and sort the key-value pairs' (duration: 17.057055ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T08:28:48.789051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T08:28:45.085704Z","time spent":"3.703309786s","remote":"127.0.0.1:40900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "}