{"level":"warn","ts":"2026-01-19T16:07:52.401569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-19T16:07:52.401644Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.233:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.233:2380","--initial-cluster=instance=https://199.19.213.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.233: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-01-19T16:07:52.401699Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-19T16:07:52.401706Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-01-19T16:07:52.401722Z","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-01-19T16:07:52.402256Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"]} {"level":"info","ts":"2026-01-19T16:07:52.402338Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.233: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-01-19T16:07:52.406844Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.328762ms"} {"level":"info","ts":"2026-01-19T16:07:52.424641Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de5c2ed1f829ccc5","cluster-id":"1c2360c7fdb72bdb"} {"level":"info","ts":"2026-01-19T16:07:52.424725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=()"} {"level":"info","ts":"2026-01-19T16:07:52.424754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 0"} {"level":"info","ts":"2026-01-19T16:07:52.424768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de5c2ed1f829ccc5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-19T16:07:52.42478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 1"} {"level":"info","ts":"2026-01-19T16:07:52.42483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"warn","ts":"2026-01-19T16:07:52.438876Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-19T16:07:52.440668Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-19T16:07:52.443159Z","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-01-19T16:07:52.446893Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de5c2ed1f829ccc5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-19T16:07:52.447694Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de5c2ed1f829ccc5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-19T16:07:52.447855Z","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-01-19T16:07:52.447883Z","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-01-19T16:07:52.447894Z","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-01-19T16:07:52.44811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"info","ts":"2026-01-19T16:07:52.448219Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","added-peer-id":"de5c2ed1f829ccc5","added-peer-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-01-19T16:07:52.459715Z","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-01-19T16:07:52.45987Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de5c2ed1f829ccc5","initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-19T16:07:52.45989Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-19T16:07:52.459943Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-01-19T16:07:52.459951Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-01-19T16:07:52.825335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 is starting a new election at term 1"} {"level":"info","ts":"2026-01-19T16:07:52.825485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-19T16:07:52.825561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgPreVoteResp from de5c2ed1f829ccc5 at term 1"} {"level":"info","ts":"2026-01-19T16:07:52.825624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became candidate at term 2"} {"level":"info","ts":"2026-01-19T16:07:52.825659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgVoteResp from de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-01-19T16:07:52.825716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became leader at term 2"} {"level":"info","ts":"2026-01-19T16:07:52.825752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de5c2ed1f829ccc5 elected leader de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-01-19T16:07:52.829694Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:07:52.831919Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de5c2ed1f829ccc5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.233:2379]}","request-path":"/0/members/de5c2ed1f829ccc5/attributes","cluster-id":"1c2360c7fdb72bdb","publish-timeout":"7s"} {"level":"info","ts":"2026-01-19T16:07:52.832126Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T16:07:52.832441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T16:07:52.832686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-19T16:07:52.832741Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-19T16:07:52.832529Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:07:52.834525Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:07:52.834594Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:07:52.835938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-19T16:07:52.83841Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.233:2379"} {"level":"warn","ts":"2026-01-19T16:08:25.972441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.589048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:25.972526Z","caller":"traceutil/trace.go:171","msg":"trace[1848120700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"106.707862ms","start":"2026-01-19T16:08:25.865805Z","end":"2026-01-19T16:08:25.972513Z","steps":["trace[1848120700] 'range keys from in-memory index tree' (duration: 106.532337ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:30.762731Z","caller":"traceutil/trace.go:171","msg":"trace[853577132] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"388.80889ms","start":"2026-01-19T16:08:30.373904Z","end":"2026-01-19T16:08:30.762713Z","steps":["trace[853577132] 'process raft request' (duration: 388.709087ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:30.763062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:30.373891Z","time spent":"388.890492ms","remote":"127.0.0.1:44116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:31.107888Z","caller":"traceutil/trace.go:171","msg":"trace[976442430] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"121.82882ms","start":"2026-01-19T16:08:30.986037Z","end":"2026-01-19T16:08:31.107866Z","steps":["trace[976442430] 'process raft request' (duration: 121.704256ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:37.021696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.173203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-01-19T16:08:37.021755Z","caller":"traceutil/trace.go:171","msg":"trace[1371561785] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:596; }","duration":"105.325516ms","start":"2026-01-19T16:08:36.916418Z","end":"2026-01-19T16:08:37.021744Z","steps":["trace[1371561785] 'range keys from in-memory index tree' (duration: 105.046389ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:37.021912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.293947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:37.02193Z","caller":"traceutil/trace.go:171","msg":"trace[1185833695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"123.344829ms","start":"2026-01-19T16:08:36.898581Z","end":"2026-01-19T16:08:37.021926Z","steps":["trace[1185833695] 'range keys from in-memory index tree' (duration: 123.254456ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:57.82796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.628146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:09:57.828282Z","caller":"traceutil/trace.go:171","msg":"trace[1354842757] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"237.039393ms","start":"2026-01-19T16:09:57.591218Z","end":"2026-01-19T16:09:57.828258Z","steps":["trace[1354842757] 'read index received' (duration: 102.833429ms)","trace[1354842757] 'applied index is now lower than readState.Index' (duration: 134.204024ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:09:57.828457Z","caller":"traceutil/trace.go:171","msg":"trace[196667251] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"245.13647ms","start":"2026-01-19T16:09:57.583308Z","end":"2026-01-19T16:09:57.828444Z","steps":["trace[196667251] 'process raft request' (duration: 110.807035ms)","trace[196667251] 'compare' (duration: 133.503714ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:09:57.828964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.723682ms","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-01-19T16:09:57.829039Z","caller":"traceutil/trace.go:171","msg":"trace[1158240455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"237.828244ms","start":"2026-01-19T16:09:57.591194Z","end":"2026-01-19T16:09:57.829022Z","steps":["trace[1158240455] 'agreement among raft nodes before linearized reading' (duration: 237.688873ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:59.133592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194130275s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:09:59.133979Z","caller":"traceutil/trace.go:171","msg":"trace[36056351] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"1.299380554s","start":"2026-01-19T16:09:57.834578Z","end":"2026-01-19T16:09:59.133959Z","steps":["trace[36056351] 'process raft request' (duration: 104.817214ms)","trace[36056351] 'compare' (duration: 1.193462036s)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:09:59.134181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:09:57.834557Z","time spent":"1.299502636s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:09:59.170167Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.230877415s","expected-duration":"1s"} {"level":"info","ts":"2026-01-19T16:09:59.17076Z","caller":"traceutil/trace.go:171","msg":"trace[521986642] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:988; }","duration":"1.301989055s","start":"2026-01-19T16:09:57.868748Z","end":"2026-01-19T16:09:59.170737Z","steps":["trace[521986642] 'read index received' (duration: 70.658064ms)","trace[521986642] 'applied index is now lower than readState.Index' (duration: 1.231329951s)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:09:59.171165Z","caller":"traceutil/trace.go:171","msg":"trace[234426863] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.335262994s","start":"2026-01-19T16:09:57.835885Z","end":"2026-01-19T16:09:59.171148Z","steps":["trace[234426863] 'process raft request' (duration: 1.334813328s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:59.171614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302843966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:09:59.171782Z","caller":"traceutil/trace.go:171","msg":"trace[511356410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"1.303034038s","start":"2026-01-19T16:09:57.868724Z","end":"2026-01-19T16:09:59.171758Z","steps":["trace[511356410] 'agreement among raft nodes before linearized reading' (duration: 1.302790095s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:59.171928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:09:57.868716Z","time spent":"1.30316717s","remote":"127.0.0.1:43954","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-01-19T16:09:59.172192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:09:57.835861Z","time spent":"1.335346615s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:09:59.172415Z","caller":"traceutil/trace.go:171","msg":"trace[1133322250] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.337131168s","start":"2026-01-19T16:09:57.835264Z","end":"2026-01-19T16:09:59.172395Z","steps":["trace[1133322250] 'process raft request' (duration: 1.335311474s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:59.172555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:09:57.835248Z","time spent":"1.337201699s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:09:59.311954Z","caller":"traceutil/trace.go:171","msg":"trace[52275987] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"141.104193ms","start":"2026-01-19T16:09:59.170825Z","end":"2026-01-19T16:09:59.31193Z","steps":["trace[52275987] 'read index received' (duration: 70.509141ms)","trace[52275987] 'applied index is now lower than readState.Index' (duration: 70.593382ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:09:59.312449Z","caller":"traceutil/trace.go:171","msg":"trace[1970539786] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.212940763s","start":"2026-01-19T16:09:58.099493Z","end":"2026-01-19T16:09:59.312434Z","steps":["trace[1970539786] 'process raft request' (duration: 1.141960556s)","trace[1970539786] 'compare' (duration: 70.184976ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:09:59.312742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:09:58.099466Z","time spent":"1.213066624s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:09:59.313469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.279388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2575"} {"level":"info","ts":"2026-01-19T16:09:59.313559Z","caller":"traceutil/trace.go:171","msg":"trace[416011285] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:949; }","duration":"158.402399ms","start":"2026-01-19T16:09:59.155144Z","end":"2026-01-19T16:09:59.313546Z","steps":["trace[416011285] 'agreement among raft nodes before linearized reading' (duration: 157.956753ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:59.313963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.269706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:09:59.314109Z","caller":"traceutil/trace.go:171","msg":"trace[801068415] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:949; }","duration":"252.453558ms","start":"2026-01-19T16:09:59.061643Z","end":"2026-01-19T16:09:59.314097Z","steps":["trace[801068415] 'agreement among raft nodes before linearized reading' (duration: 252.282956ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:59.314491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.728649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:09:59.314567Z","caller":"traceutil/trace.go:171","msg":"trace[1356085839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"140.83657ms","start":"2026-01-19T16:09:59.173713Z","end":"2026-01-19T16:09:59.314549Z","steps":["trace[1356085839] 'agreement among raft nodes before linearized reading' (duration: 140.267202ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:59.918889Z","caller":"traceutil/trace.go:171","msg":"trace[1469755584] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"223.945615ms","start":"2026-01-19T16:09:59.694917Z","end":"2026-01-19T16:09:59.918862Z","steps":["trace[1469755584] 'process raft request' (duration: 223.789973ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:00.183198Z","caller":"traceutil/trace.go:171","msg":"trace[125343498] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"170.884238ms","start":"2026-01-19T16:10:00.012292Z","end":"2026-01-19T16:10:00.183177Z","steps":["trace[125343498] 'read index received' (duration: 156.111427ms)","trace[125343498] 'applied index is now lower than readState.Index' (duration: 14.771741ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:00.18346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.148001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2575"} {"level":"info","ts":"2026-01-19T16:10:00.183513Z","caller":"traceutil/trace.go:171","msg":"trace[144070757] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:952; }","duration":"171.258153ms","start":"2026-01-19T16:10:00.012243Z","end":"2026-01-19T16:10:00.183501Z","steps":["trace[144070757] 'agreement among raft nodes before linearized reading' (duration: 171.01503ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:00.183716Z","caller":"traceutil/trace.go:171","msg":"trace[1236985724] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"254.186576ms","start":"2026-01-19T16:09:59.929512Z","end":"2026-01-19T16:10:00.183699Z","steps":["trace[1236985724] 'process raft request' (duration: 238.932478ms)","trace[1236985724] 'compare' (duration: 13.697258ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:10:00.698309Z","caller":"traceutil/trace.go:171","msg":"trace[1420430565] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"445.96106ms","start":"2026-01-19T16:10:00.252302Z","end":"2026-01-19T16:10:00.698263Z","steps":["trace[1420430565] 'process raft request' (duration: 445.862729ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:00.6984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:00.252279Z","time spent":"446.075951ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:10:01.061894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.427992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:10:01.061964Z","caller":"traceutil/trace.go:171","msg":"trace[1809081428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"191.568713ms","start":"2026-01-19T16:10:00.870379Z","end":"2026-01-19T16:10:01.061948Z","steps":["trace[1809081428] 'range keys from in-memory index tree' (duration: 191.349311ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:01.499356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.77057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:10:01.499733Z","caller":"traceutil/trace.go:171","msg":"trace[1960691746] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"276.074674ms","start":"2026-01-19T16:10:01.223645Z","end":"2026-01-19T16:10:01.49972Z","steps":["trace[1960691746] 'process raft request' (duration: 276.028293ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:01.499922Z","caller":"traceutil/trace.go:171","msg":"trace[851344339] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"276.469509ms","start":"2026-01-19T16:10:01.223427Z","end":"2026-01-19T16:10:01.499896Z","steps":["trace[851344339] 'process raft request' (duration: 86.078741ms)","trace[851344339] 'compare' (duration: 189.567608ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:01.833779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.485705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-19T16:10:01.833937Z","caller":"traceutil/trace.go:171","msg":"trace[1615491404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"230.660078ms","start":"2026-01-19T16:10:01.603205Z","end":"2026-01-19T16:10:01.833865Z","steps":["trace[1615491404] 'range keys from in-memory index tree' (duration: 230.251702ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:02.038228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.728098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-19T16:10:02.038735Z","caller":"traceutil/trace.go:171","msg":"trace[782750137] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"199.146089ms","start":"2026-01-19T16:10:01.839572Z","end":"2026-01-19T16:10:02.038718Z","steps":["trace[782750137] 'process raft request' (duration: 199.033588ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:02.039109Z","caller":"traceutil/trace.go:171","msg":"trace[803814115] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"248.617357ms","start":"2026-01-19T16:10:01.790458Z","end":"2026-01-19T16:10:02.039075Z","steps":["trace[803814115] 'read index received' (duration: 35.501µs)","trace[803814115] 'applied index is now lower than readState.Index' (duration: 248.577906ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:02.03959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.106314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T16:10:02.03964Z","caller":"traceutil/trace.go:171","msg":"trace[1337772019] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:959; }","duration":"249.201385ms","start":"2026-01-19T16:10:01.790419Z","end":"2026-01-19T16:10:02.03962Z","steps":["trace[1337772019] 'agreement among raft nodes before linearized reading' (duration: 249.079744ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:02.428318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.126663ms","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-01-19T16:10:02.42839Z","caller":"traceutil/trace.go:171","msg":"trace[664832871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"241.240305ms","start":"2026-01-19T16:10:02.187132Z","end":"2026-01-19T16:10:02.428372Z","steps":["trace[664832871] 'range keys from in-memory index tree' (duration: 240.973232ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:03.947337Z","caller":"traceutil/trace.go:171","msg":"trace[569513779] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"442.884302ms","start":"2026-01-19T16:10:03.504433Z","end":"2026-01-19T16:10:03.947318Z","steps":["trace[569513779] 'read index received' (duration: 442.502926ms)","trace[569513779] 'applied index is now lower than readState.Index' (duration: 380.506µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:03.947788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.329567ms","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-01-19T16:10:03.947856Z","caller":"traceutil/trace.go:171","msg":"trace[2095917248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"443.44438ms","start":"2026-01-19T16:10:03.504393Z","end":"2026-01-19T16:10:03.947837Z","steps":["trace[2095917248] 'agreement among raft nodes before linearized reading' (duration: 443.222898ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:03.948014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:03.504384Z","time spent":"443.563332ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T16:10:03.948131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.623992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-19T16:10:03.948218Z","caller":"traceutil/trace.go:171","msg":"trace[543715607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"443.740813ms","start":"2026-01-19T16:10:03.504464Z","end":"2026-01-19T16:10:03.948205Z","steps":["trace[543715607] 'agreement among raft nodes before linearized reading' (duration: 443.310117ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:03.948307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:03.5043Z","time spent":"443.994637ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T16:10:03.948539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.298768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T16:10:03.948626Z","caller":"traceutil/trace.go:171","msg":"trace[1578697361] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:961; }","duration":"327.423579ms","start":"2026-01-19T16:10:03.621188Z","end":"2026-01-19T16:10:03.948612Z","steps":["trace[1578697361] 'agreement among raft nodes before linearized reading' (duration: 327.296087ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:03.94871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:03.621168Z","time spent":"327.52947ms","remote":"127.0.0.1:44308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-01-19T16:10:03.948562Z","caller":"traceutil/trace.go:171","msg":"trace[1414057476] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"719.225589ms","start":"2026-01-19T16:10:03.229325Z","end":"2026-01-19T16:10:03.94855Z","steps":["trace[1414057476] 'process raft request' (duration: 717.875412ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:03.949118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:03.229295Z","time spent":"719.654565ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:10:04.234774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.876523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:10:04.234855Z","caller":"traceutil/trace.go:171","msg":"trace[962640523] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"192.159628ms","start":"2026-01-19T16:10:04.04268Z","end":"2026-01-19T16:10:04.23484Z","steps":["trace[962640523] 'read index received' (duration: 34.147773ms)","trace[962640523] 'applied index is now lower than readState.Index' (duration: 158.010865ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:04.235064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.377301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-19T16:10:04.235108Z","caller":"traceutil/trace.go:171","msg":"trace[1973944947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"192.451922ms","start":"2026-01-19T16:10:04.042646Z","end":"2026-01-19T16:10:04.235098Z","steps":["trace[1973944947] 'agreement among raft nodes before linearized reading' (duration: 192.23378ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:04.235339Z","caller":"traceutil/trace.go:171","msg":"trace[777690002] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"280.674265ms","start":"2026-01-19T16:10:03.954654Z","end":"2026-01-19T16:10:04.235328Z","steps":["trace[777690002] 'process raft request' (duration: 122.177994ms)","trace[777690002] 'compare' (duration: 157.707861ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:32.645017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:33.146452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:33.646757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:34.147262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:34.648099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:34.868819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000023224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-19T16:10:34.868903Z","caller":"traceutil/trace.go:171","msg":"trace[620673757] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000227796s","start":"2026-01-19T16:10:32.868656Z","end":"2026-01-19T16:10:34.868884Z","steps":["trace[620673757] 'agreement among raft nodes before linearized reading' (duration: 2.000060224s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:34.868948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.868637Z","time spent":"2.000302057s","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/19 16:10:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:35.149066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:35.650212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:36.151254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:36.652026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:36.869336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999829041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-19T16:10:36.869448Z","caller":"traceutil/trace.go:171","msg":"trace[461685830] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000210905s","start":"2026-01-19T16:10:34.869215Z","end":"2026-01-19T16:10:36.869426Z","steps":["trace[461685830] 'agreement among raft nodes before linearized reading' (duration: 2.000057814s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:36.870004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:34.869196Z","time spent":"2.000789361s","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/19 16:10:36 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:37.153152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:37.188518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000028464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-19T16:10:37.188924Z","caller":"traceutil/trace.go:171","msg":"trace[27735766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000259796s","start":"2026-01-19T16:10:32.188417Z","end":"2026-01-19T16:10:37.188677Z","steps":["trace[27735766] 'agreement among raft nodes before linearized reading' (duration: 5.000057804s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:37.18912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.188405Z","time spent":"5.000601419s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T16:10:37.446694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999720423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-19T16:10:37.446994Z","caller":"traceutil/trace.go:171","msg":"trace[193047182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999814934s","start":"2026-01-19T16:10:32.446937Z","end":"2026-01-19T16:10:37.446752Z","steps":["trace[193047182] 'agreement among raft nodes before linearized reading' (duration: 4.999716973s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:37.447224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.44687Z","time spent":"5.000177898s","remote":"127.0.0.1:44206","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/01/19 16:10:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:37.637106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000017925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-19T16:10:37.637189Z","caller":"traceutil/trace.go:171","msg":"trace[1577209547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000184707s","start":"2026-01-19T16:10:32.636982Z","end":"2026-01-19T16:10:37.637167Z","steps":["trace[1577209547] 'agreement among raft nodes before linearized reading' (duration: 5.000054475s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:37.637872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.636962Z","time spent":"5.000636602s","remote":"127.0.0.1:44206","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/01/19 16:10:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:37.654379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:38.154572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:38.655758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:38.878896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000765488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-19T16:10:38.87896Z","caller":"traceutil/trace.go:171","msg":"trace[476376291] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00089334s","start":"2026-01-19T16:10:36.878051Z","end":"2026-01-19T16:10:38.878945Z","steps":["trace[476376291] 'agreement among raft nodes before linearized reading' (duration: 2.000802479s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:38.878992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:36.878042Z","time spent":"2.00094296s","remote":"127.0.0.1:43954","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-01-19T16:10:39.10848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.108054Z","time spent":"7.000419456s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-19T16:10:39.144197Z","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-01-19T16:10:39.144336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000137892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-01-19T16:10:39.144384Z","caller":"traceutil/trace.go:171","msg":"trace[1073889257] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; }","duration":"7.000237853s","start":"2026-01-19T16:10:32.144129Z","end":"2026-01-19T16:10:39.144366Z","steps":["trace[1073889257] 'agreement among raft nodes before linearized reading' (duration: 7.000174763s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:39.144426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.144113Z","time spent":"7.000304464s","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":0,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-01-19T16:10:39.64508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:39.903865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000678782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-19T16:10:39.904427Z","caller":"traceutil/trace.go:171","msg":"trace[1389555970] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001331389s","start":"2026-01-19T16:10:37.903073Z","end":"2026-01-19T16:10:39.904405Z","steps":["trace[1389555970] 'agreement among raft nodes before linearized reading' (duration: 2.000739273s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:39.904682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:37.903046Z","time spent":"2.001617172s","remote":"127.0.0.1:43954","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-01-19T16:10:40.146045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:40.647026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:40.879507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000441822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-19T16:10:40.879578Z","caller":"traceutil/trace.go:171","msg":"trace[1960179507] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000568664s","start":"2026-01-19T16:10:38.878993Z","end":"2026-01-19T16:10:40.879562Z","steps":["trace[1960179507] 'agreement among raft nodes before linearized reading' (duration: 2.000476193s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:40.879633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:38.878972Z","time spent":"2.000652305s","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/19 16:10:40 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:41.147931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:41.649108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:42.099924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.309264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-19T16:10:42.100033Z","caller":"traceutil/trace.go:171","msg":"trace[1777128488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"987.498277ms","start":"2026-01-19T16:10:41.112516Z","end":"2026-01-19T16:10:42.100015Z","steps":["trace[1777128488] 'agreement among raft nodes before linearized reading' (duration: 987.341875ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:42.10011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:41.112497Z","time spent":"987.601608ms","remote":"127.0.0.1:44206","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/01/19 16:10:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:42.149357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:42.189918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997882155s","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-01-19T16:10:42.189984Z","caller":"traceutil/trace.go:171","msg":"trace[796881924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997984377s","start":"2026-01-19T16:10:39.191984Z","end":"2026-01-19T16:10:42.189968Z","steps":["trace[796881924] 'agreement among raft nodes before linearized reading' (duration: 2.997908276s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:42.19039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:39.191966Z","time spent":"2.998205899s","remote":"127.0.0.1:44206","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/01/19 16:10:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:42.352965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000360681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-19T16:10:42.35307Z","caller":"traceutil/trace.go:171","msg":"trace[732120622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.000541563s","start":"2026-01-19T16:10:32.352508Z","end":"2026-01-19T16:10:42.353049Z","steps":["trace[732120622] 'agreement among raft nodes before linearized reading' (duration: 10.000391772s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:42.353172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.352488Z","time spent":"10.000671155s","remote":"127.0.0.1:44206","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/01/19 16:10:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:42.4468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.999180311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-19T16:10:42.446865Z","caller":"traceutil/trace.go:171","msg":"trace[1921723149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.999291292s","start":"2026-01-19T16:10:39.447558Z","end":"2026-01-19T16:10:42.446849Z","steps":["trace[1921723149] 'agreement among raft nodes before linearized reading' (duration: 2.999205071s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:42.446928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:39.44754Z","time spent":"2.999348992s","remote":"127.0.0.1:44206","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/01/19 16:10:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:42.637503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998054294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-19T16:10:42.637611Z","caller":"traceutil/trace.go:171","msg":"trace[1363852666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.998220666s","start":"2026-01-19T16:10:39.639357Z","end":"2026-01-19T16:10:42.637578Z","steps":["trace[1363852666] 'agreement among raft nodes before linearized reading' (duration: 2.998095315s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:42.637678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:39.639338Z","time spent":"2.998322837s","remote":"127.0.0.1:44206","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/01/19 16:10:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:42.650046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:42.887921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000706931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-19T16:10:42.888032Z","caller":"traceutil/trace.go:171","msg":"trace[902551077] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000881553s","start":"2026-01-19T16:10:40.887123Z","end":"2026-01-19T16:10:42.888005Z","steps":["trace[902551077] 'agreement among raft nodes before linearized reading' (duration: 2.000730942s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:42.888105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:40.887107Z","time spent":"2.000979224s","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/19 16:10:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-19T16:10:43.15035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:43.651182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:43.874521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:36.873709Z","time spent":"7.000807494s","remote":"127.0.0.1:44020","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-19T16:10:44.152137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:44.652585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:44.888603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000076955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-19T16:10:44.88869Z","caller":"traceutil/trace.go:171","msg":"trace[1553020526] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000230628s","start":"2026-01-19T16:10:42.888442Z","end":"2026-01-19T16:10:44.888673Z","steps":["trace[1553020526] 'agreement among raft nodes before linearized reading' (duration: 2.000113266s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:44.888735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:42.888421Z","time spent":"2.000305028s","remote":"127.0.0.1:43958","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-01-19T16:10:45.15354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:45.654055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755371101923931550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:10:46.144977Z","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-01-19T16:10:46.145293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.29891332s","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":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-01-19T16:10:46.145415Z","caller":"traceutil/trace.go:171","msg":"trace[2147073201] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; }","duration":"11.299069891s","start":"2026-01-19T16:10:34.846332Z","end":"2026-01-19T16:10:46.145402Z","steps":["trace[2147073201] 'agreement among raft nodes before linearized reading' (duration: 11.29894982s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.145602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:34.846312Z","time spent":"11.299229983s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":0,"response size":0,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:10:46.145711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.504541817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-01-19T16:10:46.145775Z","caller":"traceutil/trace.go:171","msg":"trace[1965783946] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; }","duration":"9.504672229s","start":"2026-01-19T16:10:36.641096Z","end":"2026-01-19T16:10:46.145768Z","steps":["trace[1965783946] 'agreement among raft nodes before linearized reading' (duration: 9.504605978s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.145817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:36.641064Z","time spent":"9.504744279s","remote":"127.0.0.1:51622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:10:46.145886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.667137771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-01-19T16:10:46.145937Z","caller":"traceutil/trace.go:171","msg":"trace[971197347] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; }","duration":"9.667191781s","start":"2026-01-19T16:10:36.478738Z","end":"2026-01-19T16:10:46.14593Z","steps":["trace[971197347] 'agreement among raft nodes before linearized reading' (duration: 9.667138321s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.14598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:36.478645Z","time spent":"9.667327903s","remote":"127.0.0.1:43980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"warn","ts":"2026-01-19T16:10:46.146439Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"14.038272917s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-19T16:10:46.146576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.285327865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-01-19T16:10:46.14664Z","caller":"traceutil/trace.go:171","msg":"trace[2123097750] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; }","duration":"7.286725451s","start":"2026-01-19T16:10:38.859906Z","end":"2026-01-19T16:10:46.146632Z","steps":["trace[2123097750] 'agreement among raft nodes before linearized reading' (duration: 7.285326845s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.147323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.340351734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-01-19T16:10:46.147404Z","caller":"traceutil/trace.go:171","msg":"trace[1985930725] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; }","duration":"13.340473504s","start":"2026-01-19T16:10:32.806923Z","end":"2026-01-19T16:10:46.147397Z","steps":["trace[1985930725] 'agreement among raft nodes before linearized reading' (duration: 13.340388163s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.147568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.806904Z","time spent":"13.340653046s","remote":"127.0.0.1:44270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":0,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:10:46.147652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.555214142s","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-01-19T16:10:46.147718Z","caller":"traceutil/trace.go:171","msg":"trace[216642201] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; }","duration":"12.555321473s","start":"2026-01-19T16:10:33.592389Z","end":"2026-01-19T16:10:46.147711Z","steps":["trace[216642201] 'agreement among raft nodes before linearized reading' (duration: 12.555248732s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.147789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:33.592373Z","time spent":"12.555407833s","remote":"127.0.0.1:44124","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-01-19T16:10:46.148345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.94943505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-01-19T16:10:46.148496Z","caller":"traceutil/trace.go:171","msg":"trace[263835774] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; }","duration":"13.949601392s","start":"2026-01-19T16:10:32.198887Z","end":"2026-01-19T16:10:46.148489Z","steps":["trace[263835774] 'agreement among raft nodes before linearized reading' (duration: 13.949448211s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.148578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:32.198881Z","time spent":"13.949688973s","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":0,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-01-19T16:10:46.146681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:38.859866Z","time spent":"7.286805142s","remote":"127.0.0.1:44104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":0,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-01-19T16:10:46.332664Z","caller":"traceutil/trace.go:171","msg":"trace[1917730731] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"6.381193182s","start":"2026-01-19T16:10:39.95145Z","end":"2026-01-19T16:10:46.332643Z","steps":["trace[1917730731] 'process raft request' (duration: 6.381084581s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.332812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:39.951425Z","time spent":"6.381303864s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:10:46.332929Z","caller":"traceutil/trace.go:171","msg":"trace[1455328886] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"4.805521744s","start":"2026-01-19T16:10:41.527388Z","end":"2026-01-19T16:10:46.33291Z","steps":["trace[1455328886] 'process raft request' (duration: 4.805219151s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.33302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:41.527367Z","time spent":"4.805605925s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:10:46.365811Z","caller":"traceutil/trace.go:171","msg":"trace[322082273] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1048; }","duration":"4.260037528s","start":"2026-01-19T16:10:42.105749Z","end":"2026-01-19T16:10:46.365786Z","steps":["trace[322082273] 'process raft request' (duration: 4.259801996s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.366169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:42.105732Z","time spent":"4.260358411s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":48,"response count":0,"response size":469,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:10:46.366603Z","caller":"traceutil/trace.go:171","msg":"trace[351230879] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1094; }","duration":"221.371846ms","start":"2026-01-19T16:10:46.145213Z","end":"2026-01-19T16:10:46.366585Z","steps":["trace[351230879] 'read index received' (duration: 1.502066ms)","trace[351230879] 'applied index is now lower than readState.Index' (duration: 219.86874ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:46.366894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.763438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T16:10:46.367069Z","caller":"traceutil/trace.go:171","msg":"trace[1094327703] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1051; }","duration":"872.94239ms","start":"2026-01-19T16:10:45.494113Z","end":"2026-01-19T16:10:46.367055Z","steps":["trace[1094327703] 'agreement among raft nodes before linearized reading' (duration: 872.733648ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.367276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:45.494062Z","time spent":"873.162392ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-01-19T16:10:46.367717Z","caller":"traceutil/trace.go:171","msg":"trace[1173081627] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"4.172669196s","start":"2026-01-19T16:10:42.195034Z","end":"2026-01-19T16:10:46.367704Z","steps":["trace[1173081627] 'process raft request' (duration: 4.170595845s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.367902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:42.195013Z","time spent":"4.172786418s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:10:46.368289Z","caller":"traceutil/trace.go:171","msg":"trace[1981292107] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"4.010619509s","start":"2026-01-19T16:10:42.357656Z","end":"2026-01-19T16:10:46.368276Z","steps":["trace[1981292107] 'process raft request' (duration: 4.008297684s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.36848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:42.35763Z","time spent":"4.0107382s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:10:46.368669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077262776s","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-01-19T16:10:46.369018Z","caller":"traceutil/trace.go:171","msg":"trace[673370402] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"3.729940547s","start":"2026-01-19T16:10:42.639062Z","end":"2026-01-19T16:10:46.369003Z","steps":["trace[673370402] 'process raft request' (duration: 3.727329469s)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:46.369218Z","caller":"traceutil/trace.go:171","msg":"trace[1308039755] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1051; }","duration":"1.07768975s","start":"2026-01-19T16:10:45.291025Z","end":"2026-01-19T16:10:46.368715Z","steps":["trace[1308039755] 'agreement among raft nodes before linearized reading' (duration: 1.077271706s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.369239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:42.63904Z","time spent":"3.730083148s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:10:46.369717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:43.879509Z","time spent":"2.490205518s","remote":"127.0.0.1:44020","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-19T16:10:46.369893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:45.291005Z","time spent":"1.078263987s","remote":"127.0.0.1:44258","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-01-19T16:10:46.370172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.515031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T16:10:46.370328Z","caller":"traceutil/trace.go:171","msg":"trace[1142036645] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1051; }","duration":"227.703222ms","start":"2026-01-19T16:10:46.142613Z","end":"2026-01-19T16:10:46.370316Z","steps":["trace[1142036645] 'agreement among raft nodes before linearized reading' (duration: 227.51835ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.371851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.948026579s","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":"info","ts":"2026-01-19T16:10:46.371947Z","caller":"traceutil/trace.go:171","msg":"trace[1605772593] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1051; }","duration":"3.948180572s","start":"2026-01-19T16:10:42.423758Z","end":"2026-01-19T16:10:46.371938Z","steps":["trace[1605772593] 'agreement among raft nodes before linearized reading' (duration: 3.94803335s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.372011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:42.423743Z","time spent":"3.948236882s","remote":"127.0.0.1:44468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"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-01-19T16:10:46.372257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47464545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:10:46.372327Z","caller":"traceutil/trace.go:171","msg":"trace[114039834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"1.47471838s","start":"2026-01-19T16:10:44.897582Z","end":"2026-01-19T16:10:46.3723Z","steps":["trace[114039834] 'agreement among raft nodes before linearized reading' (duration: 1.474631649s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.372363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:44.897571Z","time spent":"1.474785111s","remote":"127.0.0.1:43954","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-01-19T16:10:46.372541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481402162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"info","ts":"2026-01-19T16:10:46.372603Z","caller":"traceutil/trace.go:171","msg":"trace[1708318811] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1051; }","duration":"1.481490772s","start":"2026-01-19T16:10:44.891103Z","end":"2026-01-19T16:10:46.372594Z","steps":["trace[1708318811] 'agreement among raft nodes before linearized reading' (duration: 1.481399181s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.372656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:44.891089Z","time spent":"1.481560763s","remote":"127.0.0.1:44116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7068,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-01-19T16:10:46.372844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.673529794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:10:46.372925Z","caller":"traceutil/trace.go:171","msg":"trace[1807050942] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1051; }","duration":"3.673648695s","start":"2026-01-19T16:10:42.699267Z","end":"2026-01-19T16:10:46.372916Z","steps":["trace[1807050942] 'agreement among raft nodes before linearized reading' (duration: 3.673550734s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.373071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:42.699247Z","time spent":"3.673813286s","remote":"127.0.0.1:43996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:10:46.373661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.721658279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T16:10:46.373792Z","caller":"traceutil/trace.go:171","msg":"trace[2017190753] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1051; }","duration":"5.72176939s","start":"2026-01-19T16:10:40.651961Z","end":"2026-01-19T16:10:46.37373Z","steps":["trace[2017190753] 'agreement among raft nodes before linearized reading' (duration: 5.721679779s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.3739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:40.651943Z","time spent":"5.721945372s","remote":"127.0.0.1:44386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-01-19T16:10:46.37412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.155100833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-01-19T16:10:46.374731Z","caller":"traceutil/trace.go:171","msg":"trace[588640162] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:1051; }","duration":"7.155161513s","start":"2026-01-19T16:10:39.21899Z","end":"2026-01-19T16:10:46.374152Z","steps":["trace[588640162] 'agreement among raft nodes before linearized reading' (duration: 7.155099292s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:46.374873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:39.21898Z","time spent":"7.155780469s","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} 2026/01/19 16:10:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"