{"level":"warn","ts":"2026-04-07T09:42:44.208802Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T09:42:44.209056Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-04-07T09:42:44.20914Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T09:42:44.209149Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-07T09:42:44.20918Z","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-04-07T09:42:44.209976Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-04-07T09:42:44.210064Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-04-07T09:42:44.21678Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.243627ms"} {"level":"info","ts":"2026-04-07T09:42:44.227732Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-04-07T09:42:44.227939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T09:42:44.228011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-04-07T09:42:44.228072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T09:42:44.228112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-04-07T09:42:44.228206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-04-07T09:42:44.235341Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T09:42:44.238213Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T09:42:44.240379Z","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-04-07T09:42:44.242931Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T09:42:44.243283Z","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-04-07T09:42:44.243355Z","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-04-07T09:42:44.243368Z","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-04-07T09:42:44.243668Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T09:42:44.247142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-04-07T09:42:44.247508Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-07T09:42:44.25034Z","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-04-07T09:42:44.250498Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-07T09:42:44.250565Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-07T09:42:44.250654Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T09:42:44.25069Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T09:42:45.029533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T09:42:45.02962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T09:42:45.02965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-04-07T09:42:45.029674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-04-07T09:42:45.029691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-07T09:42:45.029712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-04-07T09:42:45.0298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-07T09:42:45.030797Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:42:45.031771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:42:45.031777Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T09:42:45.031816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:42:45.032424Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T09:42:45.032472Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T09:42:45.032559Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:42:45.032678Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:42:45.032714Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:42:45.034977Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-04-07T09:42:45.035411Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-07T09:42:48.718631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.446007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:48.718753Z","caller":"traceutil/trace.go:171","msg":"trace[2081251086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:193; }","duration":"134.635972ms","start":"2026-04-07T09:42:48.584094Z","end":"2026-04-07T09:42:48.71873Z","steps":["trace[2081251086] 'range keys from in-memory index tree' (duration: 134.278703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:48.718796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.945886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-04-07T09:42:48.719601Z","caller":"traceutil/trace.go:171","msg":"trace[1327481549] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:193; }","duration":"398.9071ms","start":"2026-04-07T09:42:48.320662Z","end":"2026-04-07T09:42:48.719569Z","steps":["trace[1327481549] 'range keys from in-memory index tree' (duration: 397.738611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:48.719688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:48.320631Z","time spent":"399.038364ms","remote":"127.0.0.1:49182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":136,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-07T09:42:48.855308Z","caller":"traceutil/trace.go:171","msg":"trace[2038368334] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"134.105479ms","start":"2026-04-07T09:42:48.721172Z","end":"2026-04-07T09:42:48.855278Z","steps":["trace[2038368334] 'process raft request' (duration: 133.909724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:48.904692Z","caller":"traceutil/trace.go:171","msg":"trace[988675779] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"179.578668ms","start":"2026-04-07T09:42:48.72508Z","end":"2026-04-07T09:42:48.904659Z","steps":["trace[988675779] 'process raft request' (duration: 179.385613ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:49.080633Z","caller":"traceutil/trace.go:171","msg":"trace[1855980924] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"170.246442ms","start":"2026-04-07T09:42:48.910358Z","end":"2026-04-07T09:42:49.080605Z","steps":["trace[1855980924] 'process raft request' (duration: 170.19393ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:49.080818Z","caller":"traceutil/trace.go:171","msg":"trace[492369727] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"221.895327ms","start":"2026-04-07T09:42:48.85878Z","end":"2026-04-07T09:42:49.080676Z","steps":["trace[492369727] 'process raft request' (duration: 212.997503ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:17.243562Z","caller":"traceutil/trace.go:171","msg":"trace[756020797] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"129.609761ms","start":"2026-04-07T09:43:17.11392Z","end":"2026-04-07T09:43:17.243529Z","steps":["trace[756020797] 'read index received' (duration: 64.8059ms)","trace[756020797] 'applied index is now lower than readState.Index' (duration: 64.802701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:17.243735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.789935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:17.243792Z","caller":"traceutil/trace.go:171","msg":"trace[1144827216] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:471; }","duration":"129.903578ms","start":"2026-04-07T09:43:17.113875Z","end":"2026-04-07T09:43:17.243779Z","steps":["trace[1144827216] 'agreement among raft nodes before linearized reading' (duration: 129.771315ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:17.243833Z","caller":"traceutil/trace.go:171","msg":"trace[1986921441] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"135.423285ms","start":"2026-04-07T09:43:17.108384Z","end":"2026-04-07T09:43:17.243807Z","steps":["trace[1986921441] 'process raft request' (duration: 70.327017ms)","trace[1986921441] 'compare' (duration: 64.679686ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:17.408899Z","caller":"traceutil/trace.go:171","msg":"trace[1985282970] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"114.941505ms","start":"2026-04-07T09:43:17.293914Z","end":"2026-04-07T09:43:17.408856Z","steps":["trace[1985282970] 'read index received' (duration: 71.982187ms)","trace[1985282970] 'applied index is now lower than readState.Index' (duration: 42.957778ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:17.408976Z","caller":"traceutil/trace.go:171","msg":"trace[1374582753] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"144.269155ms","start":"2026-04-07T09:43:17.264662Z","end":"2026-04-07T09:43:17.408932Z","steps":["trace[1374582753] 'process raft request' (duration: 101.348948ms)","trace[1374582753] 'compare' (duration: 42.658499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:17.409085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.1407ms","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-04-07T09:43:17.409124Z","caller":"traceutil/trace.go:171","msg":"trace[1074331731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"115.265724ms","start":"2026-04-07T09:43:17.293846Z","end":"2026-04-07T09:43:17.409112Z","steps":["trace[1074331731] 'agreement among raft nodes before linearized reading' (duration: 115.146251ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:23.584511Z","caller":"traceutil/trace.go:171","msg":"trace[618003536] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"137.342272ms","start":"2026-04-07T09:43:23.447127Z","end":"2026-04-07T09:43:23.584469Z","steps":["trace[618003536] 'process raft request' (duration: 137.141047ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:23.92142Z","caller":"traceutil/trace.go:171","msg":"trace[2075688801] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"194.143163ms","start":"2026-04-07T09:43:23.727237Z","end":"2026-04-07T09:43:23.92138Z","steps":["trace[2075688801] 'process raft request' (duration: 193.20092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:23.92165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.371652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cjwbl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T09:43:23.921735Z","caller":"traceutil/trace.go:171","msg":"trace[1181836158] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cjwbl; range_end:; response_count:1; response_revision:580; }","duration":"145.546426ms","start":"2026-04-07T09:43:23.776174Z","end":"2026-04-07T09:43:23.921721Z","steps":["trace[1181836158] 'agreement among raft nodes before linearized reading' (duration: 145.28345ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:23.921409Z","caller":"traceutil/trace.go:171","msg":"trace[1685682805] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"145.094914ms","start":"2026-04-07T09:43:23.776234Z","end":"2026-04-07T09:43:23.921329Z","steps":["trace[1685682805] 'read index received' (duration: 144.017058ms)","trace[1685682805] 'applied index is now lower than readState.Index' (duration: 1.076416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:24.676875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:24.158061Z","time spent":"518.804078ms","remote":"127.0.0.1:49916","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T09:43:24.685333Z","caller":"traceutil/trace.go:171","msg":"trace[1215657163] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"235.098171ms","start":"2026-04-07T09:43:24.450203Z","end":"2026-04-07T09:43:24.685301Z","steps":["trace[1215657163] 'process raft request' (duration: 232.893736ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:26.045735Z","caller":"traceutil/trace.go:171","msg":"trace[1537572788] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:604; }","duration":"100.005184ms","start":"2026-04-07T09:43:25.945696Z","end":"2026-04-07T09:43:26.045701Z","steps":["trace[1537572788] 'count revisions from in-memory index tree' (duration: 99.84635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:26.047164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.516617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:26.047297Z","caller":"traceutil/trace.go:171","msg":"trace[1440288367] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:604; }","duration":"100.709202ms","start":"2026-04-07T09:43:25.946573Z","end":"2026-04-07T09:43:26.047282Z","steps":["trace[1440288367] 'range keys from in-memory index tree' (duration: 100.461325ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:39.228928Z","caller":"traceutil/trace.go:171","msg":"trace[1774785376] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"347.328079ms","start":"2026-04-07T09:43:38.881563Z","end":"2026-04-07T09:43:39.228891Z","steps":["trace[1774785376] 'process raft request' (duration: 273.379541ms)","trace[1774785376] 'compare' (duration: 73.526617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:39.229838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:38.881549Z","time spent":"347.472792ms","remote":"127.0.0.1:49306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:43:50.672128Z","caller":"traceutil/trace.go:171","msg":"trace[1271828856] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"360.279169ms","start":"2026-04-07T09:43:50.311818Z","end":"2026-04-07T09:43:50.672097Z","steps":["trace[1271828856] 'process raft request' (duration: 360.118665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:50.672351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:50.311799Z","time spent":"360.439204ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:51.121613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.845855ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-07T09:43:51.122029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.233717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:51.122161Z","caller":"traceutil/trace.go:171","msg":"trace[1426416671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"116.433162ms","start":"2026-04-07T09:43:51.005703Z","end":"2026-04-07T09:43:51.122136Z","steps":["trace[1426416671] 'agreement among raft nodes before linearized reading' (duration: 116.212427ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:51.122177Z","caller":"traceutil/trace.go:171","msg":"trace[1321303037] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"115.97873ms","start":"2026-04-07T09:43:51.005763Z","end":"2026-04-07T09:43:51.121741Z","steps":["trace[1321303037] 'read index received' (duration: 6.725466ms)","trace[1321303037] 'applied index is now lower than readState.Index' (duration: 109.250874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:51.945921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842674,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T09:43:52.202187Z","caller":"traceutil/trace.go:171","msg":"trace[309321323] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"871.183402ms","start":"2026-04-07T09:43:51.330969Z","end":"2026-04-07T09:43:52.202152Z","steps":["trace[309321323] 'process raft request' (duration: 870.984037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:52.202397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:51.330949Z","time spent":"871.334756ms","remote":"127.0.0.1:49354","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-04-07T09:43:52.339453Z","caller":"traceutil/trace.go:171","msg":"trace[1057431283] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"894.460232ms","start":"2026-04-07T09:43:51.444936Z","end":"2026-04-07T09:43:52.339396Z","steps":["trace[1057431283] 'read index received' (duration: 757.053218ms)","trace[1057431283] 'applied index is now lower than readState.Index' (duration: 137.406014ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:52.339898Z","caller":"traceutil/trace.go:171","msg":"trace[966722823] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"434.515188ms","start":"2026-04-07T09:43:51.905347Z","end":"2026-04-07T09:43:52.339863Z","steps":["trace[966722823] 'process raft request' (duration: 398.254937ms)","trace[966722823] 'compare' (duration: 35.537402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:52.339934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.981474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:43:52.339982Z","caller":"traceutil/trace.go:171","msg":"trace[243077993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:869; }","duration":"895.094967ms","start":"2026-04-07T09:43:51.444873Z","end":"2026-04-07T09:43:52.339967Z","steps":["trace[243077993] 'agreement among raft nodes before linearized reading' (duration: 894.924533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:52.34002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:51.444848Z","time spent":"895.162229ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T09:43:52.340032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:51.905313Z","time spent":"434.656392ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:52.340319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.818005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T09:43:52.340371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.126362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:43:52.340419Z","caller":"traceutil/trace.go:171","msg":"trace[307964362] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:869; }","duration":"449.221205ms","start":"2026-04-07T09:43:51.891188Z","end":"2026-04-07T09:43:52.340409Z","steps":["trace[307964362] 'agreement among raft nodes before linearized reading' (duration: 449.080361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:52.340425Z","caller":"traceutil/trace.go:171","msg":"trace[1876540042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"453.00206ms","start":"2026-04-07T09:43:51.887405Z","end":"2026-04-07T09:43:52.340407Z","steps":["trace[1876540042] 'agreement among raft nodes before linearized reading' (duration: 452.570829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:52.340516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.45569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:43:52.340572Z","caller":"traceutil/trace.go:171","msg":"trace[1131521166] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:869; }","duration":"449.524632ms","start":"2026-04-07T09:43:51.891032Z","end":"2026-04-07T09:43:52.340557Z","steps":["trace[1131521166] 'agreement among raft nodes before linearized reading' (duration: 449.455361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:52.340608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:51.891025Z","time spent":"449.573373ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:43:52.339797Z","caller":"traceutil/trace.go:171","msg":"trace[1470587380] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"317.455783ms","start":"2026-04-07T09:43:52.022249Z","end":"2026-04-07T09:43:52.339705Z","steps":["trace[1470587380] 'process raft request' (duration: 317.100385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:52.340884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:51.887375Z","time spent":"453.194575ms","remote":"127.0.0.1:49354","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-04-07T09:43:52.340937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:52.022217Z","time spent":"318.58224ms","remote":"127.0.0.1:49354","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-04-07T09:43:52.340449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:51.891165Z","time spent":"449.276066ms","remote":"127.0.0.1:49206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":92,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:43:52.340437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.554628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:52.340425Z","caller":"traceutil/trace.go:171","msg":"trace[79234434] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"373.93392ms","start":"2026-04-07T09:43:51.966453Z","end":"2026-04-07T09:43:52.340387Z","steps":["trace[79234434] 'process raft request' (duration: 372.837573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:52.34126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:51.966421Z","time spent":"374.801392ms","remote":"127.0.0.1:49296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:43:52.341222Z","caller":"traceutil/trace.go:171","msg":"trace[2060887317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"286.377728ms","start":"2026-04-07T09:43:52.054829Z","end":"2026-04-07T09:43:52.341206Z","steps":["trace[2060887317] 'agreement among raft nodes before linearized reading' (duration: 285.562008ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:14.63951Z","caller":"traceutil/trace.go:171","msg":"trace[565465669] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"177.349405ms","start":"2026-04-07T09:44:14.462114Z","end":"2026-04-07T09:44:14.639463Z","steps":["trace[565465669] 'read index received' (duration: 177.085008ms)","trace[565465669] 'applied index is now lower than readState.Index' (duration: 262.637µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:14.639613Z","caller":"traceutil/trace.go:171","msg":"trace[220268916] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"215.953498ms","start":"2026-04-07T09:44:14.423621Z","end":"2026-04-07T09:44:14.639575Z","steps":["trace[220268916] 'process raft request' (duration: 215.611539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:14.639793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.662583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:44:14.63983Z","caller":"traceutil/trace.go:171","msg":"trace[750164196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"177.762376ms","start":"2026-04-07T09:44:14.462056Z","end":"2026-04-07T09:44:14.639818Z","steps":["trace[750164196] 'agreement among raft nodes before linearized reading' (duration: 177.597042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:14.639984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.980282ms","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-04-07T09:44:14.640084Z","caller":"traceutil/trace.go:171","msg":"trace[689469185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"144.135795ms","start":"2026-04-07T09:44:14.495924Z","end":"2026-04-07T09:44:14.64006Z","steps":["trace[689469185] 'agreement among raft nodes before linearized reading' (duration: 143.89352ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:14.744927Z","caller":"traceutil/trace.go:171","msg":"trace[319922299] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"100.687834ms","start":"2026-04-07T09:44:14.644202Z","end":"2026-04-07T09:44:14.744889Z","steps":["trace[319922299] 'process raft request' (duration: 97.939786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:17.24961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842920,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:17.750661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842920,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:18.039677Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.621470013s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T09:44:18.040201Z","caller":"traceutil/trace.go:171","msg":"trace[1372685694] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.622101968s","start":"2026-04-07T09:44:16.418076Z","end":"2026-04-07T09:44:18.040178Z","steps":["trace[1372685694] 'process raft request' (duration: 1.621888103s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:18.040331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:16.418046Z","time spent":"1.622195041s","remote":"127.0.0.1:49354","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-04-07T09:44:18.045288Z","caller":"traceutil/trace.go:171","msg":"trace[1007139434] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:997; }","duration":"1.296077425s","start":"2026-04-07T09:44:16.749198Z","end":"2026-04-07T09:44:18.045275Z","steps":["trace[1007139434] 'read index received' (duration: 1.290740723s)","trace[1007139434] 'applied index is now lower than readState.Index' (duration: 5.335532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:18.045576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294837454s","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-04-07T09:44:18.045605Z","caller":"traceutil/trace.go:171","msg":"trace[1340154647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"1.294884726s","start":"2026-04-07T09:44:16.750712Z","end":"2026-04-07T09:44:18.045597Z","steps":["trace[1340154647] 'agreement among raft nodes before linearized reading' (duration: 1.294772893s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:18.045627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:16.750706Z","time spent":"1.294915836s","remote":"127.0.0.1:49354","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-04-07T09:44:18.045708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.018657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:18.045726Z","caller":"traceutil/trace.go:171","msg":"trace[387459059] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:961; }","duration":"205.035008ms","start":"2026-04-07T09:44:17.840683Z","end":"2026-04-07T09:44:18.045718Z","steps":["trace[387459059] 'agreement among raft nodes before linearized reading' (duration: 204.994917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:18.045734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296488276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T09:44:18.045852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.112675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:18.045846Z","caller":"traceutil/trace.go:171","msg":"trace[542705473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"1.296624869s","start":"2026-04-07T09:44:16.749189Z","end":"2026-04-07T09:44:18.045814Z","steps":["trace[542705473] 'agreement among raft nodes before linearized reading' (duration: 1.29624529s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:18.04588Z","caller":"traceutil/trace.go:171","msg":"trace[352516684] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:961; }","duration":"814.193307ms","start":"2026-04-07T09:44:17.231681Z","end":"2026-04-07T09:44:18.045874Z","steps":["trace[352516684] 'agreement among raft nodes before linearized reading' (duration: 814.151806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:18.045904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:17.231644Z","time spent":"814.253378ms","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:18.045911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:16.749132Z","time spent":"1.296765062s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T09:44:18.046021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174028334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T09:44:18.04608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23831098s","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-04-07T09:44:18.046092Z","caller":"traceutil/trace.go:171","msg":"trace[902408588] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:961; }","duration":"1.174147776s","start":"2026-04-07T09:44:16.871924Z","end":"2026-04-07T09:44:18.046072Z","steps":["trace[902408588] 'agreement among raft nodes before linearized reading' (duration: 1.174001193s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:18.046104Z","caller":"traceutil/trace.go:171","msg":"trace[1806098236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"1.238351301s","start":"2026-04-07T09:44:16.807747Z","end":"2026-04-07T09:44:18.046098Z","steps":["trace[1806098236] 'agreement among raft nodes before linearized reading' (duration: 1.238282029s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:18.046126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:16.80774Z","time spent":"1.238380542s","remote":"127.0.0.1:49354","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-04-07T09:44:18.046132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:16.871901Z","time spent":"1.174223199s","remote":"127.0.0.1:49218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:18.046293Z","caller":"traceutil/trace.go:171","msg":"trace[1614645818] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.400238195s","start":"2026-04-07T09:44:16.646018Z","end":"2026-04-07T09:44:18.046256Z","steps":["trace[1614645818] 'process raft request' (duration: 1.39920463s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:18.046382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.564823ms","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-04-07T09:44:18.046404Z","caller":"traceutil/trace.go:171","msg":"trace[1197799284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"989.606774ms","start":"2026-04-07T09:44:17.056791Z","end":"2026-04-07T09:44:18.046397Z","steps":["trace[1197799284] 'agreement among raft nodes before linearized reading' (duration: 989.496352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:18.046422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:17.056781Z","time spent":"989.637005ms","remote":"127.0.0.1:49354","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-04-07T09:44:18.046501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.344408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:18.046521Z","caller":"traceutil/trace.go:171","msg":"trace[761567902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"991.396128ms","start":"2026-04-07T09:44:17.055118Z","end":"2026-04-07T09:44:18.046514Z","steps":["trace[761567902] 'agreement among raft nodes before linearized reading' (duration: 991.363897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:18.046555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:17.055096Z","time spent":"991.45382ms","remote":"127.0.0.1:49116","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-04-07T09:44:18.04641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:16.645985Z","time spent":"1.400343368s","remote":"127.0.0.1:49354","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-04-07T09:44:23.553554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:24.054327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:24.554513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:25.053658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000400161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:25.053771Z","caller":"traceutil/trace.go:171","msg":"trace[1757247870] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000592097s","start":"2026-04-07T09:44:23.053155Z","end":"2026-04-07T09:44:25.053747Z","steps":["trace[1757247870] 'agreement among raft nodes before linearized reading' (duration: 2.000439392s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:25.053835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.053129Z","time spent":"2.000688739s","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:44:25.055037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:25.555612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:26.055916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:26.556611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:27.054592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000287959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:27.054686Z","caller":"traceutil/trace.go:171","msg":"trace[353227941] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000432663s","start":"2026-04-07T09:44:25.054231Z","end":"2026-04-07T09:44:27.054663Z","steps":["trace[353227941] 'agreement among raft nodes before linearized reading' (duration: 2.00031341s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:27.054739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:25.054214Z","time spent":"2.000512144s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:27.05676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:27.150313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:22.152496Z","time spent":"4.99780874s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/07 09:44:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:27.150694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:22.152787Z","time spent":"4.997904463s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/07 09:44:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:27.557335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:28.058439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:28.066557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000548115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:28.066676Z","caller":"traceutil/trace.go:171","msg":"trace[848904166] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0007398s","start":"2026-04-07T09:44:26.065912Z","end":"2026-04-07T09:44:28.066652Z","steps":["trace[848904166] 'agreement among raft nodes before linearized reading' (duration: 2.000578276s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:28.066737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:26.065886Z","time spent":"2.000839052s","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:28.558989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:29.059704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:29.061977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000445863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:29.062085Z","caller":"traceutil/trace.go:171","msg":"trace[789089950] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000615858s","start":"2026-04-07T09:44:27.061447Z","end":"2026-04-07T09:44:29.062063Z","steps":["trace[789089950] 'agreement among raft nodes before linearized reading' (duration: 2.000462364s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:29.062144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:27.061438Z","time spent":"2.000695469s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:29.108919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:24.111098Z","time spent":"4.99781705s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/07 09:44:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:29.151737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999731647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:29.151822Z","caller":"traceutil/trace.go:171","msg":"trace[354019237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999843799s","start":"2026-04-07T09:44:24.151957Z","end":"2026-04-07T09:44:29.151801Z","steps":["trace[354019237] 'agreement among raft nodes before linearized reading' (duration: 4.999739297s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:29.151879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:24.151952Z","time spent":"4.999912901s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/07 09:44:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:29.152526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:22.152164Z","time spent":"7.000351215s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:29.152885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:22.152792Z","time spent":"7.000091459s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:29.560324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:29.941127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:22.940086Z","time spent":"7.001032252s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:30.054213Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-04-07T09:44:30.427547Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38a69d6752ab0bd7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-07T09:44:30.555417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:31.0564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:31.067876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000035793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:31.067964Z","caller":"traceutil/trace.go:171","msg":"trace[942985995] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000178706s","start":"2026-04-07T09:44:29.067765Z","end":"2026-04-07T09:44:31.067944Z","steps":["trace[942985995] 'agreement among raft nodes before linearized reading' (duration: 2.000057523s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:31.06801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:29.067745Z","time spent":"2.000255949s","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:31 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:31.556982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:32.057712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:32.062579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.90995355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:32.062645Z","caller":"traceutil/trace.go:171","msg":"trace[307455187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.910053682s","start":"2026-04-07T09:44:29.152559Z","end":"2026-04-07T09:44:32.062613Z","steps":["trace[307455187] 'agreement among raft nodes before linearized reading' (duration: 2.90997167s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:32.062694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:29.152546Z","time spent":"2.910140515s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/07 09:44:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:32.064063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.857963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:32.064097Z","caller":"traceutil/trace.go:171","msg":"trace[1056479055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"906.899334ms","start":"2026-04-07T09:44:31.157191Z","end":"2026-04-07T09:44:32.06409Z","steps":["trace[1056479055] 'agreement among raft nodes before linearized reading' (duration: 906.857933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:32.064122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:31.157168Z","time spent":"906.949156ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/04/07 09:44:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:32.076765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.823803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:32.076801Z","caller":"traceutil/trace.go:171","msg":"trace[1988506211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"919.905975ms","start":"2026-04-07T09:44:31.156887Z","end":"2026-04-07T09:44:32.076793Z","steps":["trace[1988506211] 'agreement among raft nodes before linearized reading' (duration: 919.865214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:32.076826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:31.156864Z","time spent":"919.955957ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/04/07 09:44:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:32.07681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.923568777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:32.076921Z","caller":"traceutil/trace.go:171","msg":"trace[450550961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.923754811s","start":"2026-04-07T09:44:29.153141Z","end":"2026-04-07T09:44:32.076896Z","steps":["trace[450550961] 'agreement among raft nodes before linearized reading' (duration: 2.923593138s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:32.076983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:29.153124Z","time spent":"2.923847493s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/07 09:44:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:32.518863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000501134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:32.51903Z","caller":"traceutil/trace.go:171","msg":"trace[1455118569] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000751851s","start":"2026-04-07T09:44:30.518246Z","end":"2026-04-07T09:44:32.518997Z","steps":["trace[1455118569] 'agreement among raft nodes before linearized reading' (duration: 2.000536366s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:32.519112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:30.518225Z","time spent":"2.000870973s","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:32.558268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:33.059428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:33.068785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000237027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:33.068877Z","caller":"traceutil/trace.go:171","msg":"trace[1938013644] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000387611s","start":"2026-04-07T09:44:31.068463Z","end":"2026-04-07T09:44:33.068851Z","steps":["trace[1938013644] 'agreement among raft nodes before linearized reading' (duration: 2.000263488s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:33.068946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:31.068439Z","time spent":"2.000493284s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:33 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:33.560307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:34.057533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:27.057066Z","time spent":"7.000456247s","remote":"127.0.0.1:49206","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:34.060797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:34.109641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999607255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:34.109752Z","caller":"traceutil/trace.go:171","msg":"trace[1601650971] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.999767168s","start":"2026-04-07T09:44:29.10996Z","end":"2026-04-07T09:44:34.109727Z","steps":["trace[1601650971] 'agreement among raft nodes before linearized reading' (duration: 4.999622325s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:34.109814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:29.109952Z","time spent":"4.99984983s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/04/07 09:44:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:34.151655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997609423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:34.151731Z","caller":"traceutil/trace.go:171","msg":"trace[1459348601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.997781097s","start":"2026-04-07T09:44:31.15393Z","end":"2026-04-07T09:44:34.151711Z","steps":["trace[1459348601] 'agreement among raft nodes before linearized reading' (duration: 2.997628114s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:34.15179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:31.15391Z","time spent":"2.99785429s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/07 09:44:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:34.561493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:35.061984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:35.078244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000934315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:35.078345Z","caller":"traceutil/trace.go:171","msg":"trace[855279338] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001059067s","start":"2026-04-07T09:44:33.077238Z","end":"2026-04-07T09:44:35.078297Z","steps":["trace[855279338] 'agreement among raft nodes before linearized reading' (duration: 2.000953275s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:35.078437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:33.077225Z","time spent":"2.0011659s","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:35.56312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:36.063735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:36.101425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:29.100916Z","time spent":"7.000501158s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:36.564076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:36.945632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:29.945299Z","time spent":"7.000324654s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:37.054708Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-04-07T09:44:37.054885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.047757704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-04-07T09:44:37.054948Z","caller":"traceutil/trace.go:171","msg":"trace[1138701218] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; }","duration":"7.047892008s","start":"2026-04-07T09:44:30.007035Z","end":"2026-04-07T09:44:37.054927Z","steps":["trace[1138701218] 'agreement among raft nodes before linearized reading' (duration: 7.047798375s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:37.054994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:30.007005Z","time spent":"7.047977s","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":0,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:37.055043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.977130037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-07T09:44:37.055115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.730003527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-07T09:44:37.055085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.811366397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-04-07T09:44:37.055159Z","caller":"traceutil/trace.go:171","msg":"trace[326803852] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; }","duration":"8.73010204s","start":"2026-04-07T09:44:28.325042Z","end":"2026-04-07T09:44:37.055144Z","steps":["trace[326803852] 'agreement among raft nodes before linearized reading' (duration: 8.730049469s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:37.055116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.911295291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-07T09:44:37.055195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:28.325013Z","time spent":"8.730173751s","remote":"127.0.0.1:49144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"info","ts":"2026-04-07T09:44:37.055192Z","caller":"traceutil/trace.go:171","msg":"trace[817409251] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; }","duration":"11.811567911s","start":"2026-04-07T09:44:25.2436Z","end":"2026-04-07T09:44:37.055168Z","steps":["trace[817409251] 'agreement among raft nodes before linearized reading' (duration: 11.811402827s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:37.055158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.176646045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-04-07T09:44:37.055235Z","caller":"traceutil/trace.go:171","msg":"trace[1885422425] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; }","duration":"12.911454045s","start":"2026-04-07T09:44:24.14375Z","end":"2026-04-07T09:44:37.055204Z","steps":["trace[1885422425] 'agreement among raft nodes before linearized reading' (duration: 12.911285241s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:37.055264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:25.243577Z","time spent":"11.811661284s","remote":"127.0.0.1:49234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:37.055274Z","caller":"traceutil/trace.go:171","msg":"trace[683865852] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; }","duration":"8.176836789s","start":"2026-04-07T09:44:28.878413Z","end":"2026-04-07T09:44:37.05525Z","steps":["trace[683865852] 'agreement among raft nodes before linearized reading' (duration: 8.176667685s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:37.055297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:24.143701Z","time spent":"12.911583769s","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":0,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:37.055151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.171962242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-07T09:44:37.055344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:28.878392Z","time spent":"8.176923841s","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:37.055361Z","caller":"traceutil/trace.go:171","msg":"trace[1494459756] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; }","duration":"12.172237769s","start":"2026-04-07T09:44:24.883116Z","end":"2026-04-07T09:44:37.055354Z","steps":["trace[1494459756] 'agreement among raft nodes before linearized reading' (duration: 12.171998433s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:37.055382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:24.883095Z","time spent":"12.172280779s","remote":"127.0.0.1:49478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:37.055844Z","caller":"traceutil/trace.go:171","msg":"trace[2102591661] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; }","duration":"13.977163799s","start":"2026-04-07T09:44:23.077892Z","end":"2026-04-07T09:44:37.055056Z","steps":["trace[2102591661] 'agreement among raft nodes before linearized reading' (duration: 13.977144458s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:37.055878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:23.077883Z","time spent":"13.977984438s","remote":"127.0.0.1:49540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:37.06236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:32.06308Z","time spent":"4.999275885s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:37.076986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:32.077886Z","time spent":"4.999096782s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/07 09:44:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:37.078547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999995711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:37.078585Z","caller":"traceutil/trace.go:171","msg":"trace[2139796327] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000087484s","start":"2026-04-07T09:44:35.078487Z","end":"2026-04-07T09:44:37.078574Z","steps":["trace[2139796327] 'agreement among raft nodes before linearized reading' (duration: 2.000032782s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:37.078612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:35.078465Z","time spent":"2.000141905s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:37.436402Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38a69d6752ab0bd7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-07T09:44:37.555779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:38.056235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:38.556949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:39.057137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:39.066387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:32.066072Z","time spent":"7.000306403s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:39.072267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:32.076956Z","time spent":"6.995298709s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/07 09:44:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:39.085568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000441232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:39.085656Z","caller":"traceutil/trace.go:171","msg":"trace[549192503] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000589306s","start":"2026-04-07T09:44:37.085047Z","end":"2026-04-07T09:44:39.085636Z","steps":["trace[549192503] 'agreement among raft nodes before linearized reading' (duration: 2.000467443s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:39.085713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:37.085032Z","time spent":"2.000672738s","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:39.557275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:40.058135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:40.439403Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38a69d6752ab0c33","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-07T09:44:40.558492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:41.058874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:41.086589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000569865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:41.086726Z","caller":"traceutil/trace.go:171","msg":"trace[1892658156] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00077368s","start":"2026-04-07T09:44:39.085921Z","end":"2026-04-07T09:44:41.086694Z","steps":["trace[1892658156] 'agreement among raft nodes before linearized reading' (duration: 2.000597376s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:41.086794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:39.085903Z","time spent":"2.000879583s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:41.113545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.113169Z","time spent":"7.000367105s","remote":"127.0.0.1:49206","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:41.559783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:42.059981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:42.561099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:43.005814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999888219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:44:43.005925Z","caller":"traceutil/trace.go:171","msg":"trace[1617435970] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000057394s","start":"2026-04-07T09:44:41.005842Z","end":"2026-04-07T09:44:43.0059Z","steps":["trace[1617435970] 'agreement among raft nodes before linearized reading' (duration: 1.99991566s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.005981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.005814Z","time spent":"2.000154945s","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:44:43.062048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495216206842983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:44:43.095423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999846038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T09:44:43.095525Z","caller":"traceutil/trace.go:171","msg":"trace[2038579899] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000091414s","start":"2026-04-07T09:44:41.095408Z","end":"2026-04-07T09:44:43.0955Z","steps":["trace[2038579899] 'agreement among raft nodes before linearized reading' (duration: 1.999951341s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.095583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.095383Z","time spent":"2.000189566s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 09:44:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T09:44:43.105982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:36.105177Z","time spent":"7.000795856s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:43.147543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:38.818336Z","time spent":"4.329195564s","remote":"127.0.0.1:33138","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T09:44:43.148922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.558335254s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.149034Z","caller":"traceutil/trace.go:171","msg":"trace[2054766771] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:975; }","duration":"12.558468027s","start":"2026-04-07T09:44:30.590548Z","end":"2026-04-07T09:44:43.149016Z","steps":["trace[2054766771] 'range keys from in-memory index tree' (duration: 12.558317594s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.150012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"20.941586475s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-07T09:44:43.190978Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"20.982693199s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T09:44:43.506539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.868913ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-07T09:44:43.507285Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"72.272µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-04-07T09:44:43.588347Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"46.261µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"info","ts":"2026-04-07T09:44:43.58838Z","caller":"traceutil/trace.go:171","msg":"trace[174605854] transaction","detail":"{read_only:false; number_of_response:1; response_revision:985; }","duration":"6.63818305s","start":"2026-04-07T09:44:36.950167Z","end":"2026-04-07T09:44:43.58835Z","steps":["trace[174605854] 'process raft request' (duration: 6.638058897s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.588444Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"6.35µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-04-07T09:44:43.588555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:36.950141Z","time spent":"6.638312893s","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:44:43.588571Z","caller":"traceutil/trace.go:171","msg":"trace[302148128] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1015; }","duration":"6.533751553s","start":"2026-04-07T09:44:37.054799Z","end":"2026-04-07T09:44:43.588551Z","steps":["trace[302148128] 'read index received' (duration: 6.136431741s)","trace[302148128] 'applied index is now lower than readState.Index' (duration: 397.318772ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:43.588661Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38a69d6752ab0bd7","error":"lease not found"} {"level":"warn","ts":"2026-04-07T09:44:43.588684Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"38a69d6752ab0c33","error":"lease not found"} {"level":"info","ts":"2026-04-07T09:44:43.588713Z","caller":"traceutil/trace.go:171","msg":"trace[1298647374] transaction","detail":"{read_only:false; number_of_response:1; response_revision:985; }","duration":"479.469639ms","start":"2026-04-07T09:44:43.109231Z","end":"2026-04-07T09:44:43.588701Z","steps":["trace[1298647374] 'process raft request' (duration: 479.264824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.588825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.630969662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.588828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:43.10921Z","time spent":"479.5327ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":720,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:44:43.588849Z","caller":"traceutil/trace.go:171","msg":"trace[140385274] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:985; }","duration":"8.631038754s","start":"2026-04-07T09:44:34.957803Z","end":"2026-04-07T09:44:43.588842Z","steps":["trace[140385274] 'agreement among raft nodes before linearized reading' (duration: 8.630988643s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.588867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.957768Z","time spent":"8.631094255s","remote":"127.0.0.1:49262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.588857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.848327256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.588931Z","caller":"traceutil/trace.go:171","msg":"trace[311829236] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:985; }","duration":"9.84847481s","start":"2026-04-07T09:44:33.740439Z","end":"2026-04-07T09:44:43.588914Z","steps":["trace[311829236] 'agreement among raft nodes before linearized reading' (duration: 9.848277375s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.588973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.580716302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T09:44:43.588972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:33.740416Z","time spent":"9.848547722s","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:43.588995Z","caller":"traceutil/trace.go:171","msg":"trace[1024487797] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:985; }","duration":"7.580732072s","start":"2026-04-07T09:44:36.008251Z","end":"2026-04-07T09:44:43.588983Z","steps":["trace[1024487797] 'agreement among raft nodes before linearized reading' (duration: 7.580686331s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.58901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:36.008188Z","time spent":"7.580817935s","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.589101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.982420892s","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-04-07T09:44:43.589115Z","caller":"traceutil/trace.go:171","msg":"trace[2040873390] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:985; }","duration":"7.982482974s","start":"2026-04-07T09:44:35.606627Z","end":"2026-04-07T09:44:43.58911Z","steps":["trace[2040873390] 'agreement among raft nodes before linearized reading' (duration: 7.982458183s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.589131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:35.6066Z","time spent":"7.982526385s","remote":"127.0.0.1:49398","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-04-07T09:44:43.589137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.516796067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-04-07T09:44:43.589167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.840522073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.589203Z","caller":"traceutil/trace.go:171","msg":"trace[51399123] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:985; }","duration":"10.516869339s","start":"2026-04-07T09:44:33.072296Z","end":"2026-04-07T09:44:43.589165Z","steps":["trace[51399123] 'agreement among raft nodes before linearized reading' (duration: 10.516792557s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.589214Z","caller":"traceutil/trace.go:171","msg":"trace[1101132102] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:985; }","duration":"8.840621785s","start":"2026-04-07T09:44:34.748586Z","end":"2026-04-07T09:44:43.589208Z","steps":["trace[1101132102] 'agreement among raft nodes before linearized reading' (duration: 8.840556673s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.589235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.748553Z","time spent":"8.840677136s","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.589237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:33.072279Z","time spent":"10.516951181s","remote":"127.0.0.1:49306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7134,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-04-07T09:44:43.589261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.115223549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:43.58928Z","caller":"traceutil/trace.go:171","msg":"trace[704993007] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:985; }","duration":"8.115303402s","start":"2026-04-07T09:44:35.473972Z","end":"2026-04-07T09:44:43.589275Z","steps":["trace[704993007] 'agreement among raft nodes before linearized reading' (duration: 8.11525144s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.589294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:35.473936Z","time spent":"8.115354322s","remote":"127.0.0.1:49172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.589463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.004134629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.589488Z","caller":"traceutil/trace.go:171","msg":"trace[1292604005] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:985; }","duration":"9.004207951s","start":"2026-04-07T09:44:34.585273Z","end":"2026-04-07T09:44:43.589481Z","steps":["trace[1292604005] 'agreement among raft nodes before linearized reading' (duration: 9.004162849s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.589506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.585241Z","time spent":"9.004260032s","remote":"127.0.0.1:49410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.589829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.264622322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.589847Z","caller":"traceutil/trace.go:171","msg":"trace[1666746056] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:0; response_revision:985; }","duration":"5.264640952s","start":"2026-04-07T09:44:38.325201Z","end":"2026-04-07T09:44:43.589842Z","steps":["trace[1666746056] 'agreement among raft nodes before linearized reading' (duration: 5.264611502s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.58986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:38.325121Z","time spent":"5.264735445s","remote":"127.0.0.1:49144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-04-07T09:44:43.589939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.152723618s","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-04-07T09:44:43.589952Z","caller":"traceutil/trace.go:171","msg":"trace[78594989] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:985; }","duration":"12.152775989s","start":"2026-04-07T09:44:31.437172Z","end":"2026-04-07T09:44:43.589948Z","steps":["trace[78594989] 'agreement among raft nodes before linearized reading' (duration: 12.152750688s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.589967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:31.437148Z","time spent":"12.152815239s","remote":"127.0.0.1:49966","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-04-07T09:44:43.590072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.146000932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:43.590095Z","caller":"traceutil/trace.go:171","msg":"trace[1197503852] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:985; }","duration":"13.146058083s","start":"2026-04-07T09:44:30.444026Z","end":"2026-04-07T09:44:43.590084Z","steps":["trace[1197503852] 'agreement among raft nodes before linearized reading' (duration: 13.146022222s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.590113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:30.444004Z","time spent":"13.146103314s","remote":"127.0.0.1:49506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.590225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.311465643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T09:44:43.590216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.840837122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:44:43.590229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.528374543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a409ed1af3533c\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-07T09:44:43.590244Z","caller":"traceutil/trace.go:171","msg":"trace[788905098] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:985; }","duration":"13.311538125s","start":"2026-04-07T09:44:30.2787Z","end":"2026-04-07T09:44:43.590238Z","steps":["trace[788905098] 'agreement among raft nodes before linearized reading' (duration: 13.311500094s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.590262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:30.27865Z","time spent":"13.311606517s","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:44:43.590263Z","caller":"traceutil/trace.go:171","msg":"trace[190551265] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a409ed1af3533c; range_end:; response_count:1; response_revision:985; }","duration":"9.528463844s","start":"2026-04-07T09:44:34.061792Z","end":"2026-04-07T09:44:43.590256Z","steps":["trace[190551265] 'agreement among raft nodes before linearized reading' (duration: 9.527799638s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.590263Z","caller":"traceutil/trace.go:171","msg":"trace[1586388986] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:985; }","duration":"10.840937784s","start":"2026-04-07T09:44:32.74931Z","end":"2026-04-07T09:44:43.590248Z","steps":["trace[1586388986] 'agreement among raft nodes before linearized reading' (duration: 10.840452922s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.590338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.509821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.590352Z","caller":"traceutil/trace.go:171","msg":"trace[2001750906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"486.570553ms","start":"2026-04-07T09:44:43.103777Z","end":"2026-04-07T09:44:43.590348Z","steps":["trace[2001750906] 'agreement among raft nodes before linearized reading' (duration: 486.548422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.590376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:43.103757Z","time spent":"486.615865ms","remote":"127.0.0.1:49116","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-04-07T09:44:43.590449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.199215087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:43.590467Z","caller":"traceutil/trace.go:171","msg":"trace[890677129] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:985; }","duration":"2.199234347s","start":"2026-04-07T09:44:41.391229Z","end":"2026-04-07T09:44:43.590463Z","steps":["trace[890677129] 'agreement among raft nodes before linearized reading' (duration: 2.199204397s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.590481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.391154Z","time spent":"2.19932335s","remote":"127.0.0.1:49284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.590576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.295117223s","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-04-07T09:44:43.590596Z","caller":"traceutil/trace.go:171","msg":"trace[934352682] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:985; }","duration":"2.295193145s","start":"2026-04-07T09:44:41.295399Z","end":"2026-04-07T09:44:43.590592Z","steps":["trace[934352682] 'agreement among raft nodes before linearized reading' (duration: 2.295157724s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.590611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.295372Z","time spent":"2.295234666s","remote":"127.0.0.1:49954","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-04-07T09:44:43.590329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:32.749284Z","time spent":"10.841035546s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.590987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.453176732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:43.591034Z","caller":"traceutil/trace.go:171","msg":"trace[826786121] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:985; }","duration":"2.453226004s","start":"2026-04-07T09:44:41.137796Z","end":"2026-04-07T09:44:43.591022Z","steps":["trace[826786121] 'agreement among raft nodes before linearized reading' (duration: 2.453084061s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.591071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:41.137736Z","time spent":"2.453326676s","remote":"127.0.0.1:49596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.591135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.022839878s","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":"info","ts":"2026-04-07T09:44:43.591173Z","caller":"traceutil/trace.go:171","msg":"trace[511201453] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:985; }","duration":"5.022924539s","start":"2026-04-07T09:44:38.568239Z","end":"2026-04-07T09:44:43.591163Z","steps":["trace[511201453] 'agreement among raft nodes before linearized reading' (duration: 5.022873118s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.591199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:38.568214Z","time spent":"5.022978381s","remote":"127.0.0.1:50086","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":"warn","ts":"2026-04-07T09:44:43.591293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.555016115s","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":"info","ts":"2026-04-07T09:44:43.591312Z","caller":"traceutil/trace.go:171","msg":"trace[2062572510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:985; }","duration":"3.555095227s","start":"2026-04-07T09:44:40.036212Z","end":"2026-04-07T09:44:43.591307Z","steps":["trace[2062572510] 'agreement among raft nodes before linearized reading' (duration: 3.555066736s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.591326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:40.036181Z","time spent":"3.555141718s","remote":"127.0.0.1:32920","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":"warn","ts":"2026-04-07T09:44:43.591415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.565143697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:44:43.591499Z","caller":"traceutil/trace.go:171","msg":"trace[1141952255] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:985; }","duration":"4.56530628s","start":"2026-04-07T09:44:39.026172Z","end":"2026-04-07T09:44:43.591479Z","steps":["trace[1141952255] 'agreement among raft nodes before linearized reading' (duration: 4.565151466s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.591549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:39.026133Z","time spent":"4.565405203s","remote":"127.0.0.1:49466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:44:43.590285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:44:34.061766Z","time spent":"9.528512856s","remote":"127.0.0.1:49206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a409ed1af3533c\" "} {"level":"warn","ts":"2026-04-07T09:44:43.786727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.734582ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-07T09:44:43.786854Z","caller":"traceutil/trace.go:171","msg":"trace[308486692] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"193.72981ms","start":"2026-04-07T09:44:43.5931Z","end":"2026-04-07T09:44:43.78683Z","steps":["trace[308486692] 'read index received' (duration: 63.947378ms)","trace[308486692] 'applied index is now lower than readState.Index' (duration: 129.780552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:43.787311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.173412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-07T09:44:43.787393Z","caller":"traceutil/trace.go:171","msg":"trace[1935983832] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:985; }","duration":"194.306444ms","start":"2026-04-07T09:44:43.593067Z","end":"2026-04-07T09:44:43.787373Z","steps":["trace[1935983832] 'agreement among raft nodes before linearized reading' (duration: 194.029658ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.843729Z","caller":"traceutil/trace.go:171","msg":"trace[406358627] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"244.554874ms","start":"2026-04-07T09:44:43.599139Z","end":"2026-04-07T09:44:43.843694Z","steps":["trace[406358627] 'process raft request' (duration: 244.219125ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.843769Z","caller":"traceutil/trace.go:171","msg":"trace[1983692517] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"243.052457ms","start":"2026-04-07T09:44:43.600684Z","end":"2026-04-07T09:44:43.843736Z","steps":["trace[1983692517] 'process raft request' (duration: 242.874242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:43.844051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.649735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:686"} {"level":"warn","ts":"2026-04-07T09:44:43.844097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.793597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:43.844151Z","caller":"traceutil/trace.go:171","msg":"trace[919784600] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:987; }","duration":"244.83025ms","start":"2026-04-07T09:44:43.599296Z","end":"2026-04-07T09:44:43.844127Z","steps":["trace[919784600] 'agreement among raft nodes before linearized reading' (duration: 244.495832ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.844165Z","caller":"traceutil/trace.go:171","msg":"trace[1419272057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"250.943061ms","start":"2026-04-07T09:44:43.593208Z","end":"2026-04-07T09:44:43.844152Z","steps":["trace[1419272057] 'agreement among raft nodes before linearized reading' (duration: 250.418388ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.945286Z","caller":"traceutil/trace.go:171","msg":"trace[1000392331] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"154.249965ms","start":"2026-04-07T09:44:43.79101Z","end":"2026-04-07T09:44:43.94526Z","steps":["trace[1000392331] 'process raft request' (duration: 153.920587ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.945437Z","caller":"traceutil/trace.go:171","msg":"trace[246295430] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"157.518945ms","start":"2026-04-07T09:44:43.787884Z","end":"2026-04-07T09:44:43.945403Z","steps":["trace[246295430] 'process raft request' (duration: 113.869029ms)","trace[246295430] 'compare' (duration: 43.011331ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:46.141711Z","caller":"traceutil/trace.go:171","msg":"trace[73120307] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"180.844021ms","start":"2026-04-07T09:44:45.960827Z","end":"2026-04-07T09:44:46.141671Z","steps":["trace[73120307] 'process raft request' (duration: 180.201136ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:07.720351Z","caller":"traceutil/trace.go:171","msg":"trace[1882210283] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"105.432623ms","start":"2026-04-07T09:45:07.614893Z","end":"2026-04-07T09:45:07.720326Z","steps":["trace[1882210283] 'process raft request' (duration: 94.254308ms)","trace[1882210283] 'compare' (duration: 10.95809ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:45:07.720602Z","caller":"traceutil/trace.go:171","msg":"trace[1939577798] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"105.415781ms","start":"2026-04-07T09:45:07.615133Z","end":"2026-04-07T09:45:07.720549Z","steps":["trace[1939577798] 'process raft request' (duration: 105.153655ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:07.720664Z","caller":"traceutil/trace.go:171","msg":"trace[1725340343] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1160; }","duration":"104.799287ms","start":"2026-04-07T09:45:07.615838Z","end":"2026-04-07T09:45:07.720638Z","steps":["trace[1725340343] 'read index received' (duration: 46.678707ms)","trace[1725340343] 'applied index is now lower than readState.Index' (duration: 58.11795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:07.720999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.144425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-07T09:45:07.721092Z","caller":"traceutil/trace.go:171","msg":"trace[291797859] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:1105; }","duration":"105.276318ms","start":"2026-04-07T09:45:07.615801Z","end":"2026-04-07T09:45:07.721078Z","steps":["trace[291797859] 'agreement among raft nodes before linearized reading' (duration: 105.111334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:13.909658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.839434ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-07T09:45:24.10843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.183832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:45:24.108952Z","caller":"traceutil/trace.go:171","msg":"trace[51380637] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"718.311874ms","start":"2026-04-07T09:45:23.390589Z","end":"2026-04-07T09:45:24.108901Z","steps":["trace[51380637] 'process raft request' (duration: 136.568388ms)","trace[51380637] 'compare' (duration: 580.964547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:24.109177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:23.390573Z","time spent":"718.478569ms","remote":"127.0.0.1:49206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":862,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:45:24.192729Z","caller":"traceutil/trace.go:171","msg":"trace[947311963] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1243; }","duration":"604.045674ms","start":"2026-04-07T09:45:23.588634Z","end":"2026-04-07T09:45:24.19268Z","steps":["trace[947311963] 'read index received' (duration: 342.473772ms)","trace[947311963] 'applied index is now lower than readState.Index' (duration: 261.569712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:24.192912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.255129ms","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-04-07T09:45:24.192983Z","caller":"traceutil/trace.go:171","msg":"trace[620398020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1182; }","duration":"604.372522ms","start":"2026-04-07T09:45:23.588594Z","end":"2026-04-07T09:45:24.192966Z","steps":["trace[620398020] 'agreement among raft nodes before linearized reading' (duration: 604.129026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:24.193014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:23.588577Z","time spent":"604.433043ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T09:45:24.193278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.684461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:45:24.193301Z","caller":"traceutil/trace.go:171","msg":"trace[1792398460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"138.759442ms","start":"2026-04-07T09:45:24.054535Z","end":"2026-04-07T09:45:24.193295Z","steps":["trace[1792398460] 'agreement among raft nodes before linearized reading' (duration: 138.724801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:24.193518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.099026ms","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-04-07T09:45:24.193552Z","caller":"traceutil/trace.go:171","msg":"trace[689349077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"591.22874ms","start":"2026-04-07T09:45:23.602312Z","end":"2026-04-07T09:45:24.19354Z","steps":["trace[689349077] 'agreement among raft nodes before linearized reading' (duration: 590.606774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:24.193569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:45:23.602286Z","time spent":"591.277872ms","remote":"127.0.0.1:49354","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-04-07T09:45:24.350099Z","caller":"traceutil/trace.go:171","msg":"trace[452132419] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"154.672504ms","start":"2026-04-07T09:45:24.195401Z","end":"2026-04-07T09:45:24.350074Z","steps":["trace[452132419] 'process raft request' (duration: 154.50832ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:24.450153Z","caller":"traceutil/trace.go:171","msg":"trace[1074352299] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"252.333045ms","start":"2026-04-07T09:45:24.19778Z","end":"2026-04-07T09:45:24.450113Z","steps":["trace[1074352299] 'process raft request' (duration: 252.177992ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:24.450428Z","caller":"traceutil/trace.go:171","msg":"trace[1096410617] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"252.329585ms","start":"2026-04-07T09:45:24.198067Z","end":"2026-04-07T09:45:24.450396Z","steps":["trace[1096410617] 'process raft request' (duration: 251.943136ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:24.450695Z","caller":"traceutil/trace.go:171","msg":"trace[1547855337] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"231.908452ms","start":"2026-04-07T09:45:24.218776Z","end":"2026-04-07T09:45:24.450685Z","steps":["trace[1547855337] 'process raft request' (duration: 231.294997ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:24.450767Z","caller":"traceutil/trace.go:171","msg":"trace[199000752] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1245; }","duration":"189.666314ms","start":"2026-04-07T09:45:24.261093Z","end":"2026-04-07T09:45:24.450759Z","steps":["trace[199000752] 'read index received' (duration: 88.912236ms)","trace[199000752] 'applied index is now lower than readState.Index' (duration: 100.753118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:24.450924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.815457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T09:45:24.450945Z","caller":"traceutil/trace.go:171","msg":"trace[620801602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1188; }","duration":"189.90656ms","start":"2026-04-07T09:45:24.261033Z","end":"2026-04-07T09:45:24.450939Z","steps":["trace[620801602] 'agreement among raft nodes before linearized reading' (duration: 189.781286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:24.451058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.364293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-07T09:45:24.451121Z","caller":"traceutil/trace.go:171","msg":"trace[690818410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1188; }","duration":"149.490457ms","start":"2026-04-07T09:45:24.301618Z","end":"2026-04-07T09:45:24.451108Z","steps":["trace[690818410] 'agreement among raft nodes before linearized reading' (duration: 149.243351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:24.450147Z","caller":"traceutil/trace.go:171","msg":"trace[596180330] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"253.188866ms","start":"2026-04-07T09:45:24.196861Z","end":"2026-04-07T09:45:24.450049Z","steps":["trace[596180330] 'process raft request' (duration: 227.248368ms)","trace[596180330] 'compare' (duration: 25.667741ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:45:24.928482Z","caller":"traceutil/trace.go:171","msg":"trace[656486368] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"124.364389ms","start":"2026-04-07T09:45:24.804088Z","end":"2026-04-07T09:45:24.928453Z","steps":["trace[656486368] 'process raft request' (duration: 124.150134ms)"],"step_count":1}