{"level":"warn","ts":"2026-05-11T00:56:09.923919Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:56:09.924028Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-05-11T00:56:09.924116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:56:09.924128Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-05-11T00:56:09.924153Z","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-05-11T00:56:09.925216Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-05-11T00:56:09.92532Z","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.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-05-11T00:56:09.931348Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.600355ms"} {"level":"info","ts":"2026-05-11T00:56:09.938596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-05-11T00:56:09.938664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:56:09.938682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-05-11T00:56:09.938696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:56:09.938709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-05-11T00:56:09.938748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-05-11T00:56:09.943955Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:56:09.945141Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:56:09.946229Z","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-05-11T00:56:09.94947Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:56:09.949696Z","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-05-11T00:56:09.949769Z","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-05-11T00:56:09.949779Z","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-05-11T00:56:09.949877Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:56:09.950794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-05-11T00:56:09.951848Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-05-11T00:56:09.954015Z","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-05-11T00:56:09.954188Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-05-11T00:56:09.954258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-05-11T00:56:09.954385Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:56:09.954437Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:56:10.238974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:56:10.239054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:56:10.239115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-05-11T00:56:10.239144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:56:10.239188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-05-11T00:56:10.239232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-05-11T00:56:10.239261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-05-11T00:56:10.252172Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:56:10.253905Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:56:10.253917Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:56:10.253974Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:56:10.254212Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:56:10.254273Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:56:10.256127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-05-11T00:56:10.257917Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:56:10.282345Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:56:10.282701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:56:10.282742Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-11T00:56:13.442112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.096113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-11T00:56:13.442332Z","caller":"traceutil/trace.go:171","msg":"trace[1896918388] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"621.483182ms","start":"2026-05-11T00:56:12.820826Z","end":"2026-05-11T00:56:13.442309Z","steps":["trace[1896918388] 'read index received' (duration: 105.496836ms)","trace[1896918388] 'applied index is now lower than readState.Index' (duration: 515.984626ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:56:13.442497Z","caller":"traceutil/trace.go:171","msg":"trace[1483274526] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"692.247401ms","start":"2026-05-11T00:56:12.750201Z","end":"2026-05-11T00:56:13.442448Z","steps":["trace[1483274526] 'process raft request' (duration: 176.129282ms)","trace[1483274526] 'compare' (duration: 514.903208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:56:13.442527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.693328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T00:56:13.442604Z","caller":"traceutil/trace.go:171","msg":"trace[1081598631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"621.77727ms","start":"2026-05-11T00:56:12.82081Z","end":"2026-05-11T00:56:13.442587Z","steps":["trace[1081598631] 'agreement among raft nodes before linearized reading' (duration: 621.568654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:13.442678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:12.820755Z","time spent":"621.910784ms","remote":"127.0.0.1:58744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:56:13.44268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:12.750189Z","time spent":"692.407295ms","remote":"127.0.0.1:59090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:56:42.015689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.896817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:56:42.015841Z","caller":"traceutil/trace.go:171","msg":"trace[744382154] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"324.637367ms","start":"2026-05-11T00:56:41.691171Z","end":"2026-05-11T00:56:42.015809Z","steps":["trace[744382154] 'process raft request' (duration: 108.36653ms)","trace[744382154] 'compare' (duration: 214.971315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:56:42.015942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:41.691156Z","time spent":"324.723748ms","remote":"127.0.0.1:58792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:56:42.029761Z","caller":"traceutil/trace.go:171","msg":"trace[1411756335] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"190.953998ms","start":"2026-05-11T00:56:41.838775Z","end":"2026-05-11T00:56:42.029729Z","steps":["trace[1411756335] 'process raft request' (duration: 190.637431ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:56:46.892699Z","caller":"traceutil/trace.go:171","msg":"trace[123694440] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:590; }","duration":"134.381715ms","start":"2026-05-11T00:56:46.758253Z","end":"2026-05-11T00:56:46.892635Z","steps":["trace[123694440] 'read index received' (duration: 126.880799ms)","trace[123694440] 'applied index is now lower than readState.Index' (duration: 7.499946ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:56:46.892726Z","caller":"traceutil/trace.go:171","msg":"trace[888981380] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"212.303309ms","start":"2026-05-11T00:56:46.680387Z","end":"2026-05-11T00:56:46.89269Z","steps":["trace[888981380] 'process raft request' (duration: 210.675568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:46.892894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.622931ms","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-05-11T00:56:46.892934Z","caller":"traceutil/trace.go:171","msg":"trace[1003665755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"134.723563ms","start":"2026-05-11T00:56:46.7582Z","end":"2026-05-11T00:56:46.892924Z","steps":["trace[1003665755] 'agreement among raft nodes before linearized reading' (duration: 134.5784ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:56:46.893458Z","caller":"traceutil/trace.go:171","msg":"trace[1615238911] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"180.076019ms","start":"2026-05-11T00:56:46.713358Z","end":"2026-05-11T00:56:46.893434Z","steps":["trace[1615238911] 'process raft request' (duration: 179.201328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:47.495496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517918929,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T00:56:47.563561Z","caller":"traceutil/trace.go:171","msg":"trace[167956158] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"568.488028ms","start":"2026-05-11T00:56:46.995048Z","end":"2026-05-11T00:56:47.563536Z","steps":["trace[167956158] 'read index received' (duration: 555.482165ms)","trace[167956158] 'applied index is now lower than readState.Index' (duration: 13.004543ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:56:47.563602Z","caller":"traceutil/trace.go:171","msg":"trace[1241715744] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"573.721848ms","start":"2026-05-11T00:56:46.989837Z","end":"2026-05-11T00:56:47.563559Z","steps":["trace[1241715744] 'process raft request' (duration: 560.745605ms)","trace[1241715744] 'compare' (duration: 12.763756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:56:47.563696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.632101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:56:47.563722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:46.98981Z","time spent":"573.837931ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:56:47.563732Z","caller":"traceutil/trace.go:171","msg":"trace[1381369467] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:575; }","duration":"568.714473ms","start":"2026-05-11T00:56:46.995006Z","end":"2026-05-11T00:56:47.56372Z","steps":["trace[1381369467] 'agreement among raft nodes before linearized reading' (duration: 568.641892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:47.563764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:46.994995Z","time spent":"568.761244ms","remote":"127.0.0.1:59268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-05-11T00:56:47.563802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.83658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:56:47.563959Z","caller":"traceutil/trace.go:171","msg":"trace[1456518284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"335.035804ms","start":"2026-05-11T00:56:47.228888Z","end":"2026-05-11T00:56:47.563924Z","steps":["trace[1456518284] 'agreement among raft nodes before linearized reading' (duration: 334.83624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:47.564012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:47.228866Z","time spent":"335.135317ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:56:47.564185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.000079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6zsjg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-11T00:56:47.564246Z","caller":"traceutil/trace.go:171","msg":"trace[1761571421] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6zsjg; range_end:; response_count:1; response_revision:575; }","duration":"390.100502ms","start":"2026-05-11T00:56:47.174124Z","end":"2026-05-11T00:56:47.564224Z","steps":["trace[1761571421] 'agreement among raft nodes before linearized reading' (duration: 389.925618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:47.564288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:47.174098Z","time spent":"390.178333ms","remote":"127.0.0.1:58912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-6zsjg\" "} {"level":"warn","ts":"2026-05-11T00:56:47.563884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.584836ms","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-05-11T00:56:47.565865Z","caller":"traceutil/trace.go:171","msg":"trace[2063465932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"511.078523ms","start":"2026-05-11T00:56:47.054246Z","end":"2026-05-11T00:56:47.565325Z","steps":["trace[2063465932] 'agreement among raft nodes before linearized reading' (duration: 509.431882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:56:47.56601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:56:47.054236Z","time spent":"511.725478ms","remote":"127.0.0.1:58998","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":"info","ts":"2026-05-11T00:57:08.524018Z","caller":"traceutil/trace.go:171","msg":"trace[1619998257] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"232.700055ms","start":"2026-05-11T00:57:08.291294Z","end":"2026-05-11T00:57:08.523994Z","steps":["trace[1619998257] 'process raft request' (duration: 232.52017ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:43.126973Z","caller":"traceutil/trace.go:171","msg":"trace[1353737295] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"138.468082ms","start":"2026-05-11T00:57:42.988475Z","end":"2026-05-11T00:57:43.126943Z","steps":["trace[1353737295] 'process raft request' (duration: 138.301408ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:43.373401Z","caller":"traceutil/trace.go:171","msg":"trace[1447323522] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"114.778942ms","start":"2026-05-11T00:57:43.258591Z","end":"2026-05-11T00:57:43.37337Z","steps":["trace[1447323522] 'process raft request' (duration: 114.373292ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:07.515102Z","caller":"traceutil/trace.go:171","msg":"trace[122318170] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"282.145819ms","start":"2026-05-11T00:58:07.232928Z","end":"2026-05-11T00:58:07.515074Z","steps":["trace[122318170] 'process raft request' (duration: 281.901852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:07.515216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.408391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:58:07.515257Z","caller":"traceutil/trace.go:171","msg":"trace[1455506677] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1044; }","duration":"245.511664ms","start":"2026-05-11T00:58:07.269734Z","end":"2026-05-11T00:58:07.515246Z","steps":["trace[1455506677] 'agreement among raft nodes before linearized reading' (duration: 245.412381ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:07.515002Z","caller":"traceutil/trace.go:171","msg":"trace[2013738982] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"245.186316ms","start":"2026-05-11T00:58:07.269789Z","end":"2026-05-11T00:58:07.514976Z","steps":["trace[2013738982] 'read index received' (duration: 245.030542ms)","trace[2013738982] 'applied index is now lower than readState.Index' (duration: 154.934µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:58:07.515413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.415874ms","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-05-11T00:58:07.515453Z","caller":"traceutil/trace.go:171","msg":"trace[439908597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"148.503585ms","start":"2026-05-11T00:58:07.366937Z","end":"2026-05-11T00:58:07.515441Z","steps":["trace[439908597] 'agreement among raft nodes before linearized reading' (duration: 148.378982ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:08.191955Z","caller":"traceutil/trace.go:171","msg":"trace[604048577] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"211.937434ms","start":"2026-05-11T00:58:07.979949Z","end":"2026-05-11T00:58:08.191886Z","steps":["trace[604048577] 'process raft request' (duration: 211.769129ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:08.328884Z","caller":"traceutil/trace.go:171","msg":"trace[517937588] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"347.392062ms","start":"2026-05-11T00:58:07.981471Z","end":"2026-05-11T00:58:08.328863Z","steps":["trace[517937588] 'process raft request' (duration: 259.6518ms)","trace[517937588] 'compare' (duration: 87.191579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:58:08.329068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:58:07.981459Z","time spent":"347.546985ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:58:20.5529Z","caller":"traceutil/trace.go:171","msg":"trace[1784565911] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1183; }","duration":"235.91696ms","start":"2026-05-11T00:58:20.316962Z","end":"2026-05-11T00:58:20.552878Z","steps":["trace[1784565911] 'read index received' (duration: 235.727906ms)","trace[1784565911] 'applied index is now lower than readState.Index' (duration: 188.124µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:58:20.553065Z","caller":"traceutil/trace.go:171","msg":"trace[4873607] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"292.902031ms","start":"2026-05-11T00:58:20.26014Z","end":"2026-05-11T00:58:20.553042Z","steps":["trace[4873607] 'process raft request' (duration: 292.608944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:20.553099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.116626ms","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-05-11T00:58:20.553118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.528811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T00:58:20.553162Z","caller":"traceutil/trace.go:171","msg":"trace[1899784543] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1139; }","duration":"236.242149ms","start":"2026-05-11T00:58:20.316904Z","end":"2026-05-11T00:58:20.553146Z","steps":["trace[1899784543] 'agreement among raft nodes before linearized reading' (duration: 236.102085ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:20.553166Z","caller":"traceutil/trace.go:171","msg":"trace[902823889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1139; }","duration":"183.638494ms","start":"2026-05-11T00:58:20.369511Z","end":"2026-05-11T00:58:20.55315Z","steps":["trace[902823889] 'agreement among raft nodes before linearized reading' (duration: 183.49221ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:51.032877Z","caller":"traceutil/trace.go:171","msg":"trace[1799413644] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"151.841461ms","start":"2026-05-11T00:58:50.881014Z","end":"2026-05-11T00:58:51.032856Z","steps":["trace[1799413644] 'process raft request' (duration: 151.686118ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:56.185721Z","caller":"traceutil/trace.go:171","msg":"trace[1904089199] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"447.759328ms","start":"2026-05-11T00:58:55.737938Z","end":"2026-05-11T00:58:56.185698Z","steps":["trace[1904089199] 'process raft request' (duration: 446.332954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:56.18587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:58:55.737922Z","time spent":"447.870731ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:58:56.185905Z","caller":"traceutil/trace.go:171","msg":"trace[1693493740] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"445.602317ms","start":"2026-05-11T00:58:55.740281Z","end":"2026-05-11T00:58:56.185883Z","steps":["trace[1693493740] 'process raft request' (duration: 445.333291ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:56.18597Z","caller":"traceutil/trace.go:171","msg":"trace[1757753428] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"402.867278ms","start":"2026-05-11T00:58:55.783082Z","end":"2026-05-11T00:58:56.185949Z","steps":["trace[1757753428] 'process raft request' (duration: 402.721735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:58:56.186026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:58:55.740266Z","time spent":"445.700259ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:58:56.186081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:58:55.783062Z","time spent":"402.957571ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:13.579446Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517920837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:14.080659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517920837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:14.184982Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.211078578s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T00:59:14.185386Z","caller":"traceutil/trace.go:171","msg":"trace[1702069224] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"1.211519188s","start":"2026-05-11T00:59:12.973842Z","end":"2026-05-11T00:59:14.185361Z","steps":["trace[1702069224] 'process raft request' (duration: 1.211388085s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.185498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:12.973831Z","time spent":"1.2116024s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:14.185735Z","caller":"traceutil/trace.go:171","msg":"trace[1013197128] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1466; }","duration":"1.106780699s","start":"2026-05-11T00:59:13.078922Z","end":"2026-05-11T00:59:14.185703Z","steps":["trace[1013197128] 'read index received' (duration: 1.106766158s)","trace[1013197128] 'applied index is now lower than readState.Index' (duration: 12.19µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:14.186105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107158877s","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:507"} {"level":"warn","ts":"2026-05-11T00:59:14.186145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.968485ms","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:532"} {"level":"info","ts":"2026-05-11T00:59:14.186157Z","caller":"traceutil/trace.go:171","msg":"trace[574105144] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1411; }","duration":"1.10727125s","start":"2026-05-11T00:59:13.078872Z","end":"2026-05-11T00:59:14.186143Z","steps":["trace[574105144] 'agreement among raft nodes before linearized reading' (duration: 1.107040074s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:14.186189Z","caller":"traceutil/trace.go:171","msg":"trace[1768329401] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1411; }","duration":"357.038677ms","start":"2026-05-11T00:59:13.829137Z","end":"2026-05-11T00:59:14.186176Z","steps":["trace[1768329401] 'agreement among raft nodes before linearized reading' (duration: 356.899133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.186199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:13.078854Z","time spent":"1.107335942s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T00:59:14.186232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:13.829114Z","time spent":"357.106048ms","remote":"127.0.0.1:58998","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-05-11T00:59:14.186247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064690107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T00:59:14.186356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.060339ms","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-05-11T00:59:14.186371Z","caller":"traceutil/trace.go:171","msg":"trace[100722120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1411; }","duration":"1.0648412s","start":"2026-05-11T00:59:13.121484Z","end":"2026-05-11T00:59:14.186325Z","steps":["trace[100722120] 'agreement among raft nodes before linearized reading' (duration: 1.064528053s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:14.186381Z","caller":"traceutil/trace.go:171","msg":"trace[1313649694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1411; }","duration":"377.12139ms","start":"2026-05-11T00:59:13.809252Z","end":"2026-05-11T00:59:14.186374Z","steps":["trace[1313649694] 'agreement among raft nodes before linearized reading' (duration: 377.050579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.186427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:13.809234Z","time spent":"377.187363ms","remote":"127.0.0.1:58998","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-05-11T00:59:14.186517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:13.12146Z","time spent":"1.064962533s","remote":"127.0.0.1:58998","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-05-11T00:59:15.470797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517920859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:16.032063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517920859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:16.793208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517920859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:17.229278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000022932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-11T00:59:17.229354Z","caller":"traceutil/trace.go:171","msg":"trace[1542419319] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000151134s","start":"2026-05-11T00:59:15.229185Z","end":"2026-05-11T00:59:17.229336Z","steps":["trace[1542419319] 'agreement among raft nodes before linearized reading' (duration: 2.000048792s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.22941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:15.229169Z","time spent":"2.000234417s","remote":"127.0.0.1:58744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/11 00:59:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-11T00:59:17.294113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517920859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:17.863717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517920859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:17.881333Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.425695309s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T00:59:17.881624Z","caller":"traceutil/trace.go:171","msg":"trace[1231081866] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"3.426059738s","start":"2026-05-11T00:59:14.455541Z","end":"2026-05-11T00:59:17.881601Z","steps":["trace[1231081866] 'process raft request' (duration: 3.425944305s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.881747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.455525Z","time spent":"3.42615489s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:17.882615Z","caller":"traceutil/trace.go:171","msg":"trace[4331778] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1476; }","duration":"2.912054215s","start":"2026-05-11T00:59:14.970533Z","end":"2026-05-11T00:59:17.882587Z","steps":["trace[4331778] 'read index received' (duration: 2.911038331s)","trace[4331778] 'applied index is now lower than readState.Index' (duration: 1.014934ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:17.882701Z","caller":"traceutil/trace.go:171","msg":"trace[1727004403] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"2.287653609s","start":"2026-05-11T00:59:15.595039Z","end":"2026-05-11T00:59:17.882692Z","steps":["trace[1727004403] 'process raft request' (duration: 2.287293539s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.882808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.912273161s","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-05-11T00:59:17.882865Z","caller":"traceutil/trace.go:171","msg":"trace[1483626200] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1422; }","duration":"2.912373263s","start":"2026-05-11T00:59:14.970479Z","end":"2026-05-11T00:59:17.882852Z","steps":["trace[1483626200] 'agreement among raft nodes before linearized reading' (duration: 2.912289802s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.882897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.970459Z","time spent":"2.912430695s","remote":"127.0.0.1:59042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:17.882982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.874483392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:17.882971Z","caller":"traceutil/trace.go:171","msg":"trace[1233698055] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"1.090303342s","start":"2026-05-11T00:59:16.792639Z","end":"2026-05-11T00:59:17.882942Z","steps":["trace[1233698055] 'process raft request' (duration: 1.089815259s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.883029Z","caller":"traceutil/trace.go:171","msg":"trace[811574187] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1422; }","duration":"2.874558043s","start":"2026-05-11T00:59:15.008463Z","end":"2026-05-11T00:59:17.883021Z","steps":["trace[811574187] 'agreement among raft nodes before linearized reading' (duration: 2.874475332s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.883074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:15.008457Z","time spent":"2.874608465s","remote":"127.0.0.1:60770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:17.883083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.531525ms","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-05-11T00:59:17.883112Z","caller":"traceutil/trace.go:171","msg":"trace[2135389041] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1422; }","duration":"356.596596ms","start":"2026-05-11T00:59:17.526507Z","end":"2026-05-11T00:59:17.883103Z","steps":["trace[2135389041] 'agreement among raft nodes before linearized reading' (duration: 356.555995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.883137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:17.526489Z","time spent":"356.641617ms","remote":"127.0.0.1:59210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:17.883267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09065315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-11T00:59:17.883274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.132355ms","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":"warn","ts":"2026-05-11T00:59:17.883278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060061375s","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:526"} {"level":"info","ts":"2026-05-11T00:59:17.883347Z","caller":"traceutil/trace.go:171","msg":"trace[2028501210] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1422; }","duration":"998.252688ms","start":"2026-05-11T00:59:16.885076Z","end":"2026-05-11T00:59:17.883329Z","steps":["trace[2028501210] 'agreement among raft nodes before linearized reading' (duration: 998.011962ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.883359Z","caller":"traceutil/trace.go:171","msg":"trace[1697255896] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1422; }","duration":"1.060150467s","start":"2026-05-11T00:59:16.823191Z","end":"2026-05-11T00:59:17.883341Z","steps":["trace[1697255896] 'agreement among raft nodes before linearized reading' (duration: 1.059916452s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.883378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060580317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T00:59:17.883356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.171092055s","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":"warn","ts":"2026-05-11T00:59:17.88339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.88506Z","time spent":"998.322219ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T00:59:17.883391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.890646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:17.883405Z","caller":"traceutil/trace.go:171","msg":"trace[327807256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1422; }","duration":"1.060611877s","start":"2026-05-11T00:59:16.822785Z","end":"2026-05-11T00:59:17.883397Z","steps":["trace[327807256] 'agreement among raft nodes before linearized reading' (duration: 1.060542246s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.883308Z","caller":"traceutil/trace.go:171","msg":"trace[1455506925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1422; }","duration":"1.090702821s","start":"2026-05-11T00:59:16.792594Z","end":"2026-05-11T00:59:17.883297Z","steps":["trace[1455506925] 'agreement among raft nodes before linearized reading' (duration: 1.090531697s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.88344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.792591Z","time spent":"1.090842025s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T00:59:17.883433Z","caller":"traceutil/trace.go:171","msg":"trace[585530771] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1422; }","duration":"2.171213778s","start":"2026-05-11T00:59:15.7122Z","end":"2026-05-11T00:59:17.883414Z","steps":["trace[585530771] 'agreement among raft nodes before linearized reading' (duration: 2.171056404s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.883444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.822782Z","time spent":"1.060649218s","remote":"127.0.0.1:58998","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":"info","ts":"2026-05-11T00:59:17.883431Z","caller":"traceutil/trace.go:171","msg":"trace[841379614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1422; }","duration":"653.957608ms","start":"2026-05-11T00:59:17.229465Z","end":"2026-05-11T00:59:17.883423Z","steps":["trace[841379614] 'agreement among raft nodes before linearized reading' (duration: 653.901187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.883484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:17.22945Z","time spent":"654.028219ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:59:17.88348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:15.712184Z","time spent":"2.171284279s","remote":"127.0.0.1:60760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:17.883595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091015639s","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:532"} {"level":"warn","ts":"2026-05-11T00:59:17.883599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060813152s","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-05-11T00:59:17.883629Z","caller":"traceutil/trace.go:171","msg":"trace[777574217] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1422; }","duration":"1.091072811s","start":"2026-05-11T00:59:16.792547Z","end":"2026-05-11T00:59:17.88362Z","steps":["trace[777574217] 'agreement among raft nodes before linearized reading' (duration: 1.090953488s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.883025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060051924s","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":"warn","ts":"2026-05-11T00:59:17.883654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.792536Z","time spent":"1.091111041s","remote":"127.0.0.1:58998","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-05-11T00:59:17.883636Z","caller":"traceutil/trace.go:171","msg":"trace[67849021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1422; }","duration":"1.060859623s","start":"2026-05-11T00:59:16.822768Z","end":"2026-05-11T00:59:17.883627Z","steps":["trace[67849021] 'agreement among raft nodes before linearized reading' (duration: 1.060780271s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.883677Z","caller":"traceutil/trace.go:171","msg":"trace[1822841420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1422; }","duration":"1.06071434s","start":"2026-05-11T00:59:16.822954Z","end":"2026-05-11T00:59:17.883668Z","steps":["trace[1822841420] 'agreement among raft nodes before linearized reading' (duration: 1.059992123s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.883691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.822765Z","time spent":"1.060916675s","remote":"127.0.0.1:58998","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-05-11T00:59:17.883712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.822949Z","time spent":"1.060754901s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T00:59:17.883404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.823178Z","time spent":"1.060217988s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T00:59:17.883815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061057818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T00:59:17.883838Z","caller":"traceutil/trace.go:171","msg":"trace[1161965512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1422; }","duration":"1.061102519s","start":"2026-05-11T00:59:16.822728Z","end":"2026-05-11T00:59:17.88383Z","steps":["trace[1161965512] 'agreement among raft nodes before linearized reading' (duration: 1.061024947s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.883861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.822717Z","time spent":"1.06113899s","remote":"127.0.0.1:58998","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":"warn","ts":"2026-05-11T00:59:17.882886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:15.595018Z","time spent":"2.287711911s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:17.883956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.792632Z","time spent":"1.090411705s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:56.605576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517921300,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T00:59:56.665399Z","caller":"traceutil/trace.go:171","msg":"trace[8783170] linearizableReadLoop","detail":"{readStateIndex:1663; appliedIndex:1662; }","duration":"560.277947ms","start":"2026-05-11T00:59:56.105099Z","end":"2026-05-11T00:59:56.665377Z","steps":["trace[8783170] 'read index received' (duration: 560.076682ms)","trace[8783170] 'applied index is now lower than readState.Index' (duration: 198.185µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:56.665516Z","caller":"traceutil/trace.go:171","msg":"trace[1287067642] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"634.043896ms","start":"2026-05-11T00:59:56.031438Z","end":"2026-05-11T00:59:56.665482Z","steps":["trace[1287067642] 'process raft request' (duration: 633.807441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.665578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.476411ms","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-05-11T00:59:56.665604Z","caller":"traceutil/trace.go:171","msg":"trace[68135377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1598; }","duration":"560.556013ms","start":"2026-05-11T00:59:56.105041Z","end":"2026-05-11T00:59:56.665597Z","steps":["trace[68135377] 'agreement among raft nodes before linearized reading' (duration: 560.447591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.665626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.105021Z","time spent":"560.599834ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T00:59:56.66564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.031409Z","time spent":"634.167109ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:56.66569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.662198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:56.665732Z","caller":"traceutil/trace.go:171","msg":"trace[1231052348] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1598; }","duration":"247.75389ms","start":"2026-05-11T00:59:56.417965Z","end":"2026-05-11T00:59:56.665719Z","steps":["trace[1231052348] 'agreement among raft nodes before linearized reading' (duration: 247.688608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.665821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.367977ms","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-05-11T00:59:56.665841Z","caller":"traceutil/trace.go:171","msg":"trace[1705188401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1598; }","duration":"551.405888ms","start":"2026-05-11T00:59:56.11443Z","end":"2026-05-11T00:59:56.665836Z","steps":["trace[1705188401] 'agreement among raft nodes before linearized reading' (duration: 551.303615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.665859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.114421Z","time spent":"551.434168ms","remote":"127.0.0.1:58998","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-05-11T00:59:56.66594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.16751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:59:56.665957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.594485ms","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-05-11T00:59:56.665963Z","caller":"traceutil/trace.go:171","msg":"trace[1145415338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1598; }","duration":"427.247681ms","start":"2026-05-11T00:59:56.238709Z","end":"2026-05-11T00:59:56.665957Z","steps":["trace[1145415338] 'agreement among raft nodes before linearized reading' (duration: 427.210111ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:56.665978Z","caller":"traceutil/trace.go:171","msg":"trace[1267971639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1598; }","duration":"552.615786ms","start":"2026-05-11T00:59:56.113356Z","end":"2026-05-11T00:59:56.665972Z","steps":["trace[1267971639] 'agreement among raft nodes before linearized reading' (duration: 552.557604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.665928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.559486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-11T00:59:56.665982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.23868Z","time spent":"427.298523ms","remote":"127.0.0.1:58744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:59:56.666058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.80924ms","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-05-11T00:59:56.66606Z","caller":"traceutil/trace.go:171","msg":"trace[258679190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1598; }","duration":"555.697269ms","start":"2026-05-11T00:59:56.11032Z","end":"2026-05-11T00:59:56.666018Z","steps":["trace[258679190] 'agreement among raft nodes before linearized reading' (duration: 555.340401ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:56.666074Z","caller":"traceutil/trace.go:171","msg":"trace[1622223462] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1598; }","duration":"549.891942ms","start":"2026-05-11T00:59:56.116177Z","end":"2026-05-11T00:59:56.666069Z","steps":["trace[1622223462] 'agreement among raft nodes before linearized reading' (duration: 549.848551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.665999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.113321Z","time spent":"552.670997ms","remote":"127.0.0.1:58998","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-05-11T00:59:56.666094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.116139Z","time spent":"549.951313ms","remote":"127.0.0.1:58998","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-05-11T00:59:56.666108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.110307Z","time spent":"555.793241ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T00:59:56.666137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.834259ms","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-05-11T00:59:56.666158Z","caller":"traceutil/trace.go:171","msg":"trace[71691165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1598; }","duration":"554.89466ms","start":"2026-05-11T00:59:56.111258Z","end":"2026-05-11T00:59:56.666153Z","steps":["trace[71691165] 'agreement among raft nodes before linearized reading' (duration: 554.845009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.666171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.111234Z","time spent":"554.93453ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T00:59:58.473675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517921314,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:58.974212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517921314,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:59.22034Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.258359831s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T00:59:59.220842Z","caller":"traceutil/trace.go:171","msg":"trace[529162006] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"1.258937916s","start":"2026-05-11T00:59:57.961873Z","end":"2026-05-11T00:59:59.220811Z","steps":["trace[529162006] 'process raft request' (duration: 1.258708231s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.221087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:57.961844Z","time spent":"1.25911882s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:59.22587Z","caller":"traceutil/trace.go:171","msg":"trace[1446839878] linearizableReadLoop","detail":"{readStateIndex:1673; appliedIndex:1669; }","duration":"1.252817082s","start":"2026-05-11T00:59:57.973035Z","end":"2026-05-11T00:59:59.225852Z","steps":["trace[1446839878] 'read index received' (duration: 1.24764764s)","trace[1446839878] 'applied index is now lower than readState.Index' (duration: 5.168052ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:59.22609Z","caller":"traceutil/trace.go:171","msg":"trace[325623832] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"1.259679624s","start":"2026-05-11T00:59:57.966401Z","end":"2026-05-11T00:59:59.226081Z","steps":["trace[325623832] 'process raft request' (duration: 1.259306245s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.226142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:57.966385Z","time spent":"1.259730154s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:59.226265Z","caller":"traceutil/trace.go:171","msg":"trace[667122212] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"554.715826ms","start":"2026-05-11T00:59:58.671541Z","end":"2026-05-11T00:59:59.226257Z","steps":["trace[667122212] 'process raft request' (duration: 554.239085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.226321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.671517Z","time spent":"554.769827ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:59.226403Z","caller":"traceutil/trace.go:171","msg":"trace[693677666] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"553.933158ms","start":"2026-05-11T00:59:58.672464Z","end":"2026-05-11T00:59:59.226397Z","steps":["trace[693677666] 'process raft request' (duration: 553.354565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.226441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.672453Z","time spent":"553.972149ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:59.226593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253555709s","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-05-11T00:59:59.226624Z","caller":"traceutil/trace.go:171","msg":"trace[2117771530] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1608; }","duration":"1.25360597s","start":"2026-05-11T00:59:57.973011Z","end":"2026-05-11T00:59:59.226617Z","steps":["trace[2117771530] 'agreement among raft nodes before linearized reading' (duration: 1.253494087s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.226643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:57.972999Z","time spent":"1.253639912s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-11T00:59:59.22676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057545628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:59.226784Z","caller":"traceutil/trace.go:171","msg":"trace[351406842] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1608; }","duration":"1.05760116s","start":"2026-05-11T00:59:58.169177Z","end":"2026-05-11T00:59:59.226778Z","steps":["trace[351406842] 'agreement among raft nodes before linearized reading' (duration: 1.057558759s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.2268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.16916Z","time spent":"1.057636301s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:59.226961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.27955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T00:59:59.226983Z","caller":"traceutil/trace.go:171","msg":"trace[2107629498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1608; }","duration":"547.30972ms","start":"2026-05-11T00:59:58.679669Z","end":"2026-05-11T00:59:59.226978Z","steps":["trace[2107629498] 'agreement among raft nodes before linearized reading' (duration: 547.247729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.226999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.679665Z","time spent":"547.330221ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T00:59:59.227081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.545173ms","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":"warn","ts":"2026-05-11T00:59:59.227126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000385271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:59.22715Z","caller":"traceutil/trace.go:171","msg":"trace[1569627165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1608; }","duration":"1.000406811s","start":"2026-05-11T00:59:58.226737Z","end":"2026-05-11T00:59:59.227144Z","steps":["trace[1569627165] 'agreement among raft nodes before linearized reading' (duration: 1.00037039s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.227171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.226703Z","time spent":"1.000464113s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T00:59:59.227167Z","caller":"traceutil/trace.go:171","msg":"trace[1327297373] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1608; }","duration":"546.647275ms","start":"2026-05-11T00:59:58.680494Z","end":"2026-05-11T00:59:59.227141Z","steps":["trace[1327297373] 'agreement among raft nodes before linearized reading' (duration: 546.358129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.227213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.680488Z","time spent":"546.716547ms","remote":"127.0.0.1:58998","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-05-11T00:59:59.227288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.945506ms","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-05-11T00:59:59.2274Z","caller":"traceutil/trace.go:171","msg":"trace[1904911411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1608; }","duration":"548.1427ms","start":"2026-05-11T00:59:58.679237Z","end":"2026-05-11T00:59:59.227379Z","steps":["trace[1904911411] 'agreement among raft nodes before linearized reading' (duration: 547.836243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.227481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.679222Z","time spent":"548.246703ms","remote":"127.0.0.1:58998","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-05-11T00:59:59.227957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.432477ms","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-05-11T00:59:59.227983Z","caller":"traceutil/trace.go:171","msg":"trace[440028552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1608; }","duration":"548.473508ms","start":"2026-05-11T00:59:58.679504Z","end":"2026-05-11T00:59:59.227978Z","steps":["trace[440028552] 'agreement among raft nodes before linearized reading' (duration: 548.382476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.227985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.377766ms","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":"warn","ts":"2026-05-11T00:59:59.228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.679497Z","time spent":"548.498348ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T00:59:59.228022Z","caller":"traceutil/trace.go:171","msg":"trace[2118901910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1608; }","duration":"548.423996ms","start":"2026-05-11T00:59:58.679587Z","end":"2026-05-11T00:59:59.228011Z","steps":["trace[2118901910] 'agreement among raft nodes before linearized reading' (duration: 548.053478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.228083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.679584Z","time spent":"548.462757ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T00:59:59.228078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.466537ms","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-05-11T00:59:59.228158Z","caller":"traceutil/trace.go:171","msg":"trace[1949764862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1608; }","duration":"548.57015ms","start":"2026-05-11T00:59:58.679569Z","end":"2026-05-11T00:59:59.228139Z","steps":["trace[1949764862] 'agreement among raft nodes before linearized reading' (duration: 548.305124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.228208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.679564Z","time spent":"548.635203ms","remote":"127.0.0.1:58998","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":"info","ts":"2026-05-11T00:59:59.681563Z","caller":"traceutil/trace.go:171","msg":"trace[1623257652] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"393.749061ms","start":"2026-05-11T00:59:59.287783Z","end":"2026-05-11T00:59:59.681533Z","steps":["trace[1623257652] 'process raft request' (duration: 388.455276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.681742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:59.28777Z","time spent":"393.876223ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:00:00.677154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.65854ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T01:00:00.67724Z","caller":"traceutil/trace.go:171","msg":"trace[869506557] linearizableReadLoop","detail":"{readStateIndex:1682; appliedIndex:1680; }","duration":"1.059505484s","start":"2026-05-11T00:59:59.617719Z","end":"2026-05-11T01:00:00.677224Z","steps":["trace[869506557] 'read index received' (duration: 58.599991ms)","trace[869506557] 'applied index is now lower than readState.Index' (duration: 1.000904523s)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:00.67731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059584126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:00:00.677333Z","caller":"traceutil/trace.go:171","msg":"trace[434217951] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1616; }","duration":"1.059671658s","start":"2026-05-11T00:59:59.617654Z","end":"2026-05-11T01:00:00.677326Z","steps":["trace[434217951] 'agreement among raft nodes before linearized reading' (duration: 1.059605146s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.677362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:59.617624Z","time spent":"1.059730549s","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:01:28.170959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517922324,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T01:01:28.671701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202183517922324,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T01:01:29.042118Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.37648529s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T01:01:29.042477Z","caller":"traceutil/trace.go:171","msg":"trace[347961506] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"1.376960872s","start":"2026-05-11T01:01:27.665492Z","end":"2026-05-11T01:01:29.042453Z","steps":["trace[347961506] 'process raft request' (duration: 1.376815778s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:29.042634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:01:27.665468Z","time spent":"1.377087455s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:01:29.043349Z","caller":"traceutil/trace.go:171","msg":"trace[1049732333] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2097; }","duration":"1.373269774s","start":"2026-05-11T01:01:27.670044Z","end":"2026-05-11T01:01:29.043314Z","steps":["trace[1049732333] 'read index received' (duration: 1.37227314s)","trace[1049732333] 'applied index is now lower than readState.Index' (duration: 995.364µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:01:29.043456Z","caller":"traceutil/trace.go:171","msg":"trace[297648413] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"1.375047837s","start":"2026-05-11T01:01:27.668396Z","end":"2026-05-11T01:01:29.043443Z","steps":["trace[297648413] 'process raft request' (duration: 1.374797691s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:29.043569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:01:27.668384Z","time spent":"1.375105508s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:01:29.043628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.934784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:01:29.043704Z","caller":"traceutil/trace.go:171","msg":"trace[717030515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2016; }","duration":"815.049067ms","start":"2026-05-11T01:01:28.228636Z","end":"2026-05-11T01:01:29.043685Z","steps":["trace[717030515] 'agreement among raft nodes before linearized reading' (duration: 814.941504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:29.043748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:01:28.228615Z","time spent":"815.125189ms","remote":"127.0.0.1:58744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T01:01:29.043775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372534226s","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:532"} {"level":"info","ts":"2026-05-11T01:01:29.043825Z","caller":"traceutil/trace.go:171","msg":"trace[1603787078] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2016; }","duration":"1.372590697s","start":"2026-05-11T01:01:27.671222Z","end":"2026-05-11T01:01:29.043812Z","steps":["trace[1603787078] 'agreement among raft nodes before linearized reading' (duration: 1.372429443s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:29.043863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:01:27.671217Z","time spent":"1.372636998s","remote":"127.0.0.1:58998","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-05-11T01:01:29.043855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23809333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T01:01:29.044058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373980551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T01:01:29.044309Z","caller":"traceutil/trace.go:171","msg":"trace[287882875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2016; }","duration":"1.374259558s","start":"2026-05-11T01:01:27.67003Z","end":"2026-05-11T01:01:29.04429Z","steps":["trace[287882875] 'agreement among raft nodes before linearized reading' (duration: 1.373618153s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:29.04443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:01:27.670025Z","time spent":"1.374391091s","remote":"127.0.0.1:58998","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-05-11T01:01:29.044176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373180341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-11T01:01:29.04455Z","caller":"traceutil/trace.go:171","msg":"trace[1296254266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2016; }","duration":"1.37356304s","start":"2026-05-11T01:01:27.670974Z","end":"2026-05-11T01:01:29.044537Z","steps":["trace[1296254266] 'agreement among raft nodes before linearized reading' (duration: 1.372554336s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:29.044657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:01:27.670969Z","time spent":"1.373675583s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T01:01:29.044743Z","caller":"traceutil/trace.go:171","msg":"trace[1571830249] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2016; }","duration":"1.239110245s","start":"2026-05-11T01:01:27.805609Z","end":"2026-05-11T01:01:29.044719Z","steps":["trace[1571830249] 'agreement among raft nodes before linearized reading' (duration: 1.237992268s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:01:29.044823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:01:27.805586Z","time spent":"1.239217877s","remote":"127.0.0.1:60672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:02:05.087338Z","caller":"traceutil/trace.go:171","msg":"trace[1457983702] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"156.67274ms","start":"2026-05-11T01:02:04.930628Z","end":"2026-05-11T01:02:05.087301Z","steps":["trace[1457983702] 'process raft request' (duration: 156.448094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:05.3234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.579515ms","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-05-11T01:02:05.323495Z","caller":"traceutil/trace.go:171","msg":"trace[1373396938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2221; }","duration":"112.737379ms","start":"2026-05-11T01:02:05.21074Z","end":"2026-05-11T01:02:05.323477Z","steps":["trace[1373396938] 'range keys from in-memory index tree' (duration: 112.386561ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:11.808836Z","caller":"traceutil/trace.go:171","msg":"trace[828869676] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"402.307043ms","start":"2026-05-11T01:02:11.406503Z","end":"2026-05-11T01:02:11.80881Z","steps":["trace[828869676] 'process raft request' (duration: 401.979425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.809049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.406473Z","time spent":"402.450728ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:02:11.809056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.550391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-11T01:02:11.809177Z","caller":"traceutil/trace.go:171","msg":"trace[888274856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2247; }","duration":"400.699356ms","start":"2026-05-11T01:02:11.408461Z","end":"2026-05-11T01:02:11.80916Z","steps":["trace[888274856] 'agreement among raft nodes before linearized reading' (duration: 400.417318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.809215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.408453Z","time spent":"400.753127ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T01:02:11.809658Z","caller":"traceutil/trace.go:171","msg":"trace[1396571865] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"162.997512ms","start":"2026-05-11T01:02:11.646626Z","end":"2026-05-11T01:02:11.809624Z","steps":["trace[1396571865] 'process raft request' (duration: 162.750696ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:11.808723Z","caller":"traceutil/trace.go:171","msg":"trace[1810408009] linearizableReadLoop","detail":"{readStateIndex:2340; appliedIndex:2339; }","duration":"400.211152ms","start":"2026-05-11T01:02:11.40848Z","end":"2026-05-11T01:02:11.808691Z","steps":["trace[1810408009] 'read index received' (duration: 399.885505ms)","trace[1810408009] 'applied index is now lower than readState.Index' (duration: 324.407µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:02:11.810109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.140185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:913"} {"level":"info","ts":"2026-05-11T01:02:11.810176Z","caller":"traceutil/trace.go:171","msg":"trace[1832396284] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:2249; }","duration":"335.231616ms","start":"2026-05-11T01:02:11.474924Z","end":"2026-05-11T01:02:11.810156Z","steps":["trace[1832396284] 'agreement among raft nodes before linearized reading' (duration: 335.112784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.810229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.474912Z","time spent":"335.305788ms","remote":"127.0.0.1:59090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":936,"request content":"key:\"/registry/clusterroles/system:controller:deployment-controller\" "} {"level":"warn","ts":"2026-05-11T01:02:11.810545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.370845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T01:02:11.810621Z","caller":"traceutil/trace.go:171","msg":"trace[1527776173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2249; }","duration":"397.473218ms","start":"2026-05-11T01:02:11.413135Z","end":"2026-05-11T01:02:11.810608Z","steps":["trace[1527776173] 'agreement among raft nodes before linearized reading' (duration: 397.358545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.81065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.413125Z","time spent":"397.519289ms","remote":"127.0.0.1:58998","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-05-11T01:02:11.810745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.751594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-05-11T01:02:11.810764Z","caller":"traceutil/trace.go:171","msg":"trace[1089099903] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:2249; }","duration":"380.769575ms","start":"2026-05-11T01:02:11.429989Z","end":"2026-05-11T01:02:11.810758Z","steps":["trace[1089099903] 'agreement among raft nodes before linearized reading' (duration: 380.733924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.810777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.429956Z","time spent":"380.818366ms","remote":"127.0.0.1:59090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":981,"request content":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" "} {"level":"warn","ts":"2026-05-11T01:02:11.811069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.334534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T01:02:11.81112Z","caller":"traceutil/trace.go:171","msg":"trace[472415288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2249; }","duration":"402.401366ms","start":"2026-05-11T01:02:11.408704Z","end":"2026-05-11T01:02:11.811105Z","steps":["trace[472415288] 'agreement among raft nodes before linearized reading' (duration: 402.240702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.811166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.408687Z","time spent":"402.462757ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T01:02:11.811459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.872847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-11T01:02:11.811543Z","caller":"traceutil/trace.go:171","msg":"trace[1299350289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2249; }","duration":"403.015231ms","start":"2026-05-11T01:02:11.408508Z","end":"2026-05-11T01:02:11.811523Z","steps":["trace[1299350289] 'agreement among raft nodes before linearized reading' (duration: 401.523055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.811589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.408495Z","time spent":"403.084402ms","remote":"127.0.0.1:58998","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\" "}