{"level":"warn","ts":"2026-02-13T02:02:00.076509Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-13T02:02:00.076593Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-02-13T02:02:00.076662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-13T02:02:00.076676Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-02-13T02:02:00.076696Z","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-02-13T02:02:00.08549Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-02-13T02:02:00.086448Z","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.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-02-13T02:02:00.093317Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.677438ms"} {"level":"info","ts":"2026-02-13T02:02:00.111706Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-02-13T02:02:00.111802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T02:02:00.111822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-02-13T02:02:00.111833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T02:02:00.111843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-02-13T02:02:00.111874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-02-13T02:02:00.124281Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T02:02:00.131729Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T02:02:00.140014Z","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-02-13T02:02:00.144881Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T02:02:00.145946Z","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-02-13T02:02:00.14598Z","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-02-13T02:02:00.145993Z","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-02-13T02:02:00.147215Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T02:02:00.165326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-02-13T02:02:00.165524Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-02-13T02:02:00.166299Z","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-02-13T02:02:00.166629Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T02:02:00.16646Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-02-13T02:02:00.167536Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-02-13T02:02:00.167696Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T02:02:00.212232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T02:02:00.212344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T02:02:00.212428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-02-13T02:02:00.212487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-02-13T02:02:00.212525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-02-13T02:02:00.212582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-02-13T02:02:00.212618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-02-13T02:02:00.214452Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T02:02:00.21452Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T02:02:00.214922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T02:02:00.21457Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T02:02:00.216497Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T02:02:00.216557Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T02:02:00.219237Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-02-13T02:02:00.22394Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T02:02:00.224118Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T02:02:00.224232Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T02:02:00.22475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-13T02:02:11.19533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.536386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:02:11.195461Z","caller":"traceutil/trace.go:171","msg":"trace[288856075] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:368; }","duration":"316.833247ms","start":"2026-02-13T02:02:10.878617Z","end":"2026-02-13T02:02:11.195451Z","steps":["trace[288856075] 'read index received' (duration: 160.639983ms)","trace[288856075] 'applied index is now lower than readState.Index' (duration: 156.192574ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:02:11.195649Z","caller":"traceutil/trace.go:171","msg":"trace[613540727] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"353.288354ms","start":"2026-02-13T02:02:10.842352Z","end":"2026-02-13T02:02:11.19564Z","steps":["trace[613540727] 'process raft request' (duration: 196.944726ms)","trace[613540727] 'compare' (duration: 153.094756ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:11.195694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:10.842332Z","time spent":"353.339384ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:02:11.195812Z","caller":"traceutil/trace.go:171","msg":"trace[627568623] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"351.705409ms","start":"2026-02-13T02:02:10.844101Z","end":"2026-02-13T02:02:11.195807Z","steps":["trace[627568623] 'process raft request' (duration: 351.30585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.195846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:10.844067Z","time spent":"351.74389ms","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T02:02:11.195963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.344168ms","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-02-13T02:02:11.195988Z","caller":"traceutil/trace.go:171","msg":"trace[775657094] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:359; }","duration":"317.404749ms","start":"2026-02-13T02:02:10.878576Z","end":"2026-02-13T02:02:11.195981Z","steps":["trace[775657094] 'agreement among raft nodes before linearized reading' (duration: 317.359978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.196009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:10.878558Z","time spent":"317.447521ms","remote":"127.0.0.1:58548","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\" "} {"level":"warn","ts":"2026-02-13T02:02:11.196192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.770558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-13T02:02:11.196217Z","caller":"traceutil/trace.go:171","msg":"trace[580957013] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:359; }","duration":"308.82567ms","start":"2026-02-13T02:02:10.887385Z","end":"2026-02-13T02:02:11.196211Z","steps":["trace[580957013] 'agreement among raft nodes before linearized reading' (duration: 308.771119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.196239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:10.887377Z","time spent":"308.857341ms","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-13T02:02:11.19655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.194574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-13T02:02:11.196575Z","caller":"traceutil/trace.go:171","msg":"trace[794887033] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:359; }","duration":"118.254005ms","start":"2026-02-13T02:02:11.078315Z","end":"2026-02-13T02:02:11.196569Z","steps":["trace[794887033] 'agreement among raft nodes before linearized reading' (duration: 118.217924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.196653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.22078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-13T02:02:11.196669Z","caller":"traceutil/trace.go:171","msg":"trace[690222212] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:359; }","duration":"168.267832ms","start":"2026-02-13T02:02:11.028396Z","end":"2026-02-13T02:02:11.196664Z","steps":["trace[690222212] 'agreement among raft nodes before linearized reading' (duration: 168.239511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.197197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.032594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-13T02:02:11.197243Z","caller":"traceutil/trace.go:171","msg":"trace[209297314] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:359; }","duration":"219.101995ms","start":"2026-02-13T02:02:10.978127Z","end":"2026-02-13T02:02:11.197229Z","steps":["trace[209297314] 'agreement among raft nodes before linearized reading' (duration: 219.042634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.197405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.717741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-13T02:02:11.197428Z","caller":"traceutil/trace.go:171","msg":"trace[284852344] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:359; }","duration":"234.762012ms","start":"2026-02-13T02:02:10.962661Z","end":"2026-02-13T02:02:11.197423Z","steps":["trace[284852344] 'agreement among raft nodes before linearized reading' (duration: 234.707581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.197602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.949936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-13T02:02:11.197626Z","caller":"traceutil/trace.go:171","msg":"trace[1291029269] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:359; }","duration":"234.999498ms","start":"2026-02-13T02:02:10.962621Z","end":"2026-02-13T02:02:11.197621Z","steps":["trace[1291029269] 'agreement among raft nodes before linearized reading' (duration: 234.955307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.197777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.153201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4597"} {"level":"info","ts":"2026-02-13T02:02:11.1978Z","caller":"traceutil/trace.go:171","msg":"trace[1566342593] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:359; }","duration":"235.207732ms","start":"2026-02-13T02:02:10.962586Z","end":"2026-02-13T02:02:11.197794Z","steps":["trace[1566342593] 'agreement among raft nodes before linearized reading' (duration: 235.159481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.197952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.370276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-13T02:02:11.197975Z","caller":"traceutil/trace.go:171","msg":"trace[622089167] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:359; }","duration":"235.394886ms","start":"2026-02-13T02:02:10.962575Z","end":"2026-02-13T02:02:11.19797Z","steps":["trace[622089167] 'agreement among raft nodes before linearized reading' (duration: 235.334645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.198176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.603421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-13T02:02:11.198206Z","caller":"traceutil/trace.go:171","msg":"trace[2023614931] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:359; }","duration":"235.680132ms","start":"2026-02-13T02:02:10.962516Z","end":"2026-02-13T02:02:11.198196Z","steps":["trace[2023614931] 'agreement among raft nodes before linearized reading' (duration: 235.58867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.198368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.760449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-13T02:02:11.198392Z","caller":"traceutil/trace.go:171","msg":"trace[1902811661] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:359; }","duration":"237.84479ms","start":"2026-02-13T02:02:10.960542Z","end":"2026-02-13T02:02:11.198387Z","steps":["trace[1902811661] 'agreement among raft nodes before linearized reading' (duration: 237.804329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:11.198666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.731998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-13T02:02:11.198691Z","caller":"traceutil/trace.go:171","msg":"trace[248446256] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:359; }","duration":"270.787759ms","start":"2026-02-13T02:02:10.927897Z","end":"2026-02-13T02:02:11.198685Z","steps":["trace[248446256] 'agreement among raft nodes before linearized reading' (duration: 270.744898ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:16.793629Z","caller":"traceutil/trace.go:171","msg":"trace[1470870391] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"279.535832ms","start":"2026-02-13T02:02:16.514077Z","end":"2026-02-13T02:02:16.793613Z","steps":["trace[1470870391] 'read index received' (duration: 279.405949ms)","trace[1470870391] 'applied index is now lower than readState.Index' (duration: 129.203µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:02:16.79379Z","caller":"traceutil/trace.go:171","msg":"trace[1363258421] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"279.808438ms","start":"2026-02-13T02:02:16.513974Z","end":"2026-02-13T02:02:16.793782Z","steps":["trace[1363258421] 'process raft request' (duration: 279.559853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:16.793924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.830819ms","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-02-13T02:02:16.793946Z","caller":"traceutil/trace.go:171","msg":"trace[874313991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"279.872019ms","start":"2026-02-13T02:02:16.514067Z","end":"2026-02-13T02:02:16.793939Z","steps":["trace[874313991] 'agreement among raft nodes before linearized reading' (duration: 279.824628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:16.794039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.860916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-13T02:02:16.794057Z","caller":"traceutil/trace.go:171","msg":"trace[933611160] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:417; }","duration":"103.899818ms","start":"2026-02-13T02:02:16.690151Z","end":"2026-02-13T02:02:16.794051Z","steps":["trace[933611160] 'agreement among raft nodes before linearized reading' (duration: 103.868277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:17.304363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436569748,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T02:02:17.514007Z","caller":"traceutil/trace.go:171","msg":"trace[1614856209] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"709.862109ms","start":"2026-02-13T02:02:16.804117Z","end":"2026-02-13T02:02:17.513979Z","steps":["trace[1614856209] 'read index received' (duration: 703.873847ms)","trace[1614856209] 'applied index is now lower than readState.Index' (duration: 5.986622ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:02:17.514124Z","caller":"traceutil/trace.go:171","msg":"trace[1505547958] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"715.685388ms","start":"2026-02-13T02:02:16.798422Z","end":"2026-02-13T02:02:17.514107Z","steps":["trace[1505547958] 'process raft request' (duration: 709.618224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:17.514169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.034653ms","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-02-13T02:02:17.514205Z","caller":"traceutil/trace.go:171","msg":"trace[349718634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"710.128815ms","start":"2026-02-13T02:02:16.804066Z","end":"2026-02-13T02:02:17.514195Z","steps":["trace[349718634] 'agreement among raft nodes before linearized reading' (duration: 710.009352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:17.514219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:16.798401Z","time spent":"715.76767ms","remote":"127.0.0.1:58602","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-02-13T02:02:17.514239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:16.804054Z","time spent":"710.175246ms","remote":"127.0.0.1:58602","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-02-13T02:02:18.121893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.621092ms","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-02-13T02:02:18.121994Z","caller":"traceutil/trace.go:171","msg":"trace[1862250054] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:418; }","duration":"429.732304ms","start":"2026-02-13T02:02:17.692241Z","end":"2026-02-13T02:02:18.121973Z","steps":["trace[1862250054] 'range keys from in-memory index tree' (duration: 429.601981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:18.122505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.01243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:02:18.122987Z","caller":"traceutil/trace.go:171","msg":"trace[1983677397] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"601.495654ms","start":"2026-02-13T02:02:17.52147Z","end":"2026-02-13T02:02:18.122965Z","steps":["trace[1983677397] 'process raft request' (duration: 208.981783ms)","trace[1983677397] 'compare' (duration: 391.798654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:18.123032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:17.521455Z","time spent":"601.552775ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:02:18.291056Z","caller":"traceutil/trace.go:171","msg":"trace[1628533810] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"114.594875ms","start":"2026-02-13T02:02:18.176439Z","end":"2026-02-13T02:02:18.291034Z","steps":["trace[1628533810] 'read index received' (duration: 114.347139ms)","trace[1628533810] 'applied index is now lower than readState.Index' (duration: 246.806µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:18.291197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.737668ms","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-02-13T02:02:18.29124Z","caller":"traceutil/trace.go:171","msg":"trace[1176191288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:420; }","duration":"114.82534ms","start":"2026-02-13T02:02:18.176394Z","end":"2026-02-13T02:02:18.291219Z","steps":["trace[1176191288] 'agreement among raft nodes before linearized reading' (duration: 114.724468ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:18.291565Z","caller":"traceutil/trace.go:171","msg":"trace[6358810] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"155.026358ms","start":"2026-02-13T02:02:18.136525Z","end":"2026-02-13T02:02:18.291551Z","steps":["trace[6358810] 'process raft request' (duration: 154.320753ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:23.777051Z","caller":"traceutil/trace.go:171","msg":"trace[829909897] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"155.409357ms","start":"2026-02-13T02:02:23.621621Z","end":"2026-02-13T02:02:23.77703Z","steps":["trace[829909897] 'process raft request' (duration: 155.239593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:33.390258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436569931,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T02:02:33.529982Z","caller":"traceutil/trace.go:171","msg":"trace[1569492112] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"776.227209ms","start":"2026-02-13T02:02:32.75373Z","end":"2026-02-13T02:02:33.529957Z","steps":["trace[1569492112] 'process raft request' (duration: 776.025623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:33.530197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:32.753721Z","time spent":"776.371683ms","remote":"127.0.0.1:58534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:02:33.537578Z","caller":"traceutil/trace.go:171","msg":"trace[2075548306] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"648.038343ms","start":"2026-02-13T02:02:32.889523Z","end":"2026-02-13T02:02:33.537562Z","steps":["trace[2075548306] 'read index received' (duration: 640.309782ms)","trace[2075548306] 'applied index is now lower than readState.Index' (duration: 7.727921ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:33.537677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.138755ms","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-02-13T02:02:33.5377Z","caller":"traceutil/trace.go:171","msg":"trace[1892668846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"648.214697ms","start":"2026-02-13T02:02:32.889478Z","end":"2026-02-13T02:02:33.537692Z","steps":["trace[1892668846] 'agreement among raft nodes before linearized reading' (duration: 648.141165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:33.537723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:32.889461Z","time spent":"648.257288ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-13T02:02:33.537924Z","caller":"traceutil/trace.go:171","msg":"trace[1396938752] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"366.906785ms","start":"2026-02-13T02:02:33.17101Z","end":"2026-02-13T02:02:33.537917Z","steps":["trace[1396938752] 'process raft request' (duration: 366.480726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:33.537982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:33.170984Z","time spent":"366.957196ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:02:34.052216Z","caller":"traceutil/trace.go:171","msg":"trace[1036167735] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"138.980414ms","start":"2026-02-13T02:02:33.913221Z","end":"2026-02-13T02:02:34.052201Z","steps":["trace[1036167735] 'read index received' (duration: 138.860572ms)","trace[1036167735] 'applied index is now lower than readState.Index' (duration: 119.372µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:34.052306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.068696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T02:02:34.052322Z","caller":"traceutil/trace.go:171","msg":"trace[202573888] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:518; }","duration":"139.126627ms","start":"2026-02-13T02:02:33.91319Z","end":"2026-02-13T02:02:34.052317Z","steps":["trace[202573888] 'agreement among raft nodes before linearized reading' (duration: 139.065465ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:34.05255Z","caller":"traceutil/trace.go:171","msg":"trace[1143860572] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"224.366092ms","start":"2026-02-13T02:02:33.828177Z","end":"2026-02-13T02:02:34.052543Z","steps":["trace[1143860572] 'process raft request' (duration: 223.946983ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:36.334806Z","caller":"traceutil/trace.go:171","msg":"trace[1736007087] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"109.006131ms","start":"2026-02-13T02:02:36.225782Z","end":"2026-02-13T02:02:36.334788Z","steps":["trace[1736007087] 'read index received' (duration: 108.911479ms)","trace[1736007087] 'applied index is now lower than readState.Index' (duration: 93.792µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:02:36.335087Z","caller":"traceutil/trace.go:171","msg":"trace[691708936] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"276.737211ms","start":"2026-02-13T02:02:36.058326Z","end":"2026-02-13T02:02:36.335064Z","steps":["trace[691708936] 'process raft request' (duration: 276.347942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:36.335323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.525122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-13T02:02:36.335393Z","caller":"traceutil/trace.go:171","msg":"trace[1412064483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"109.622955ms","start":"2026-02-13T02:02:36.225754Z","end":"2026-02-13T02:02:36.335377Z","steps":["trace[1412064483] 'agreement among raft nodes before linearized reading' (duration: 109.104293ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:36.876585Z","caller":"traceutil/trace.go:171","msg":"trace[1540792366] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"272.835513ms","start":"2026-02-13T02:02:36.603727Z","end":"2026-02-13T02:02:36.876563Z","steps":["trace[1540792366] 'read index received' (duration: 220.216709ms)","trace[1540792366] 'applied index is now lower than readState.Index' (duration: 52.617874ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:36.876722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.977096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:02:36.876755Z","caller":"traceutil/trace.go:171","msg":"trace[1671728850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"273.052638ms","start":"2026-02-13T02:02:36.603691Z","end":"2026-02-13T02:02:36.876744Z","steps":["trace[1671728850] 'agreement among raft nodes before linearized reading' (duration: 272.953156ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:36.877Z","caller":"traceutil/trace.go:171","msg":"trace[1683297744] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"473.401549ms","start":"2026-02-13T02:02:36.403585Z","end":"2026-02-13T02:02:36.876986Z","steps":["trace[1683297744] 'process raft request' (duration: 420.422148ms)","trace[1683297744] 'compare' (duration: 52.47914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:36.87708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:36.403562Z","time spent":"473.466981ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:02:37.697034Z","caller":"traceutil/trace.go:171","msg":"trace[2070169558] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"122.083521ms","start":"2026-02-13T02:02:37.57493Z","end":"2026-02-13T02:02:37.697014Z","steps":["trace[2070169558] 'process raft request' (duration: 121.980299ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:38.755179Z","caller":"traceutil/trace.go:171","msg":"trace[1595952579] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"380.255961ms","start":"2026-02-13T02:02:38.374903Z","end":"2026-02-13T02:02:38.755159Z","steps":["trace[1595952579] 'read index received' (duration: 380.102517ms)","trace[1595952579] 'applied index is now lower than readState.Index' (duration: 152.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:02:38.755424Z","caller":"traceutil/trace.go:171","msg":"trace[1143055946] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"403.119746ms","start":"2026-02-13T02:02:38.352294Z","end":"2026-02-13T02:02:38.755413Z","steps":["trace[1143055946] 'process raft request' (duration: 402.766078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:38.755523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:38.352283Z","time spent":"403.176307ms","remote":"127.0.0.1:58602","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-02-13T02:02:38.755695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.787312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-13T02:02:38.755726Z","caller":"traceutil/trace.go:171","msg":"trace[1953091646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"380.835753ms","start":"2026-02-13T02:02:38.374881Z","end":"2026-02-13T02:02:38.755717Z","steps":["trace[1953091646] 'agreement among raft nodes before linearized reading' (duration: 380.782472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:38.755752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:38.374871Z","time spent":"380.875473ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T02:02:38.75587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.298043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:02:38.755924Z","caller":"traceutil/trace.go:171","msg":"trace[1752822351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"154.375094ms","start":"2026-02-13T02:02:38.601541Z","end":"2026-02-13T02:02:38.755916Z","steps":["trace[1752822351] 'agreement among raft nodes before linearized reading' (duration: 154.310973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:41.978139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.026184ms","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-02-13T02:02:41.978198Z","caller":"traceutil/trace.go:171","msg":"trace[304220450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"271.121065ms","start":"2026-02-13T02:02:41.707063Z","end":"2026-02-13T02:02:41.978184Z","steps":["trace[304220450] 'range keys from in-memory index tree' (duration: 270.934571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:41.978203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01194552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wl629\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-13T02:02:41.978232Z","caller":"traceutil/trace.go:171","msg":"trace[279583045] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wl629; range_end:; response_count:1; response_revision:537; }","duration":"1.012001172s","start":"2026-02-13T02:02:40.966223Z","end":"2026-02-13T02:02:41.978224Z","steps":["trace[279583045] 'range keys from in-memory index tree' (duration: 1.011831648s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:41.978252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:40.966208Z","time spent":"1.012039172s","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-wl629\" "} {"level":"warn","ts":"2026-02-13T02:02:41.978414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.228199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:02:41.978432Z","caller":"traceutil/trace.go:171","msg":"trace[1043064157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"375.28326ms","start":"2026-02-13T02:02:41.603143Z","end":"2026-02-13T02:02:41.978427Z","steps":["trace[1043064157] 'range keys from in-memory index tree' (duration: 375.156037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:41.978448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:41.603123Z","time spent":"375.320301ms","remote":"127.0.0.1:58390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T02:02:42.23231Z","caller":"traceutil/trace.go:171","msg":"trace[1047620428] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"249.241662ms","start":"2026-02-13T02:02:41.983046Z","end":"2026-02-13T02:02:42.232288Z","steps":["trace[1047620428] 'process raft request' (duration: 249.098009ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:42.316623Z","caller":"traceutil/trace.go:171","msg":"trace[801079472] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"322.688917ms","start":"2026-02-13T02:02:41.993907Z","end":"2026-02-13T02:02:42.316596Z","steps":["trace[801079472] 'process raft request' (duration: 322.562444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:42.316757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:41.993889Z","time spent":"322.797129ms","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:02:44.711834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.439822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:02:44.711897Z","caller":"traceutil/trace.go:171","msg":"trace[1904575830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"110.533224ms","start":"2026-02-13T02:02:44.601349Z","end":"2026-02-13T02:02:44.711882Z","steps":["trace[1904575830] 'range keys from in-memory index tree' (duration: 110.393861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:44.714094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.476713ms","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-02-13T02:02:44.714128Z","caller":"traceutil/trace.go:171","msg":"trace[842951594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"477.163333ms","start":"2026-02-13T02:02:44.236955Z","end":"2026-02-13T02:02:44.714118Z","steps":["trace[842951594] 'range keys from in-memory index tree' (duration: 474.295169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:44.714155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:44.236938Z","time spent":"477.205924ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-13T02:02:47.356424Z","caller":"traceutil/trace.go:171","msg":"trace[2069864215] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"206.480607ms","start":"2026-02-13T02:02:47.149898Z","end":"2026-02-13T02:02:47.356379Z","steps":["trace[2069864215] 'process raft request' (duration: 205.993486ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:48.185402Z","caller":"traceutil/trace.go:171","msg":"trace[832825380] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"213.84287ms","start":"2026-02-13T02:02:47.971536Z","end":"2026-02-13T02:02:48.185379Z","steps":["trace[832825380] 'process raft request' (duration: 213.692226ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:48.185625Z","caller":"traceutil/trace.go:171","msg":"trace[462949682] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:629; }","duration":"162.003673ms","start":"2026-02-13T02:02:48.023599Z","end":"2026-02-13T02:02:48.185603Z","steps":["trace[462949682] 'read index received' (duration: 161.995003ms)","trace[462949682] 'applied index is now lower than readState.Index' (duration: 6.92µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:48.185906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.291059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wl629\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-13T02:02:48.186151Z","caller":"traceutil/trace.go:171","msg":"trace[573865838] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wl629; range_end:; response_count:1; response_revision:609; }","duration":"162.559935ms","start":"2026-02-13T02:02:48.023564Z","end":"2026-02-13T02:02:48.186124Z","steps":["trace[573865838] 'agreement among raft nodes before linearized reading' (duration: 162.136416ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:48.187538Z","caller":"traceutil/trace.go:171","msg":"trace[2125816455] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"161.814779ms","start":"2026-02-13T02:02:48.025704Z","end":"2026-02-13T02:02:48.187518Z","steps":["trace[2125816455] 'process raft request' (duration: 161.326189ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:49.161212Z","caller":"traceutil/trace.go:171","msg":"trace[359740123] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"115.222709ms","start":"2026-02-13T02:02:49.045906Z","end":"2026-02-13T02:02:49.161129Z","steps":["trace[359740123] 'process raft request' (duration: 114.37181ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:49.263377Z","caller":"traceutil/trace.go:171","msg":"trace[2043531442] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"122.893308ms","start":"2026-02-13T02:02:49.140463Z","end":"2026-02-13T02:02:49.263356Z","steps":["trace[2043531442] 'process raft request' (duration: 117.924028ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:49.266056Z","caller":"traceutil/trace.go:171","msg":"trace[463237269] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"122.131051ms","start":"2026-02-13T02:02:49.143909Z","end":"2026-02-13T02:02:49.26604Z","steps":["trace[463237269] 'read index received' (duration: 18.51189ms)","trace[463237269] 'applied index is now lower than readState.Index' (duration: 103.617041ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:49.278486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.558456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-13T02:02:49.278528Z","caller":"traceutil/trace.go:171","msg":"trace[1605273541] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:628; }","duration":"134.613797ms","start":"2026-02-13T02:02:49.143903Z","end":"2026-02-13T02:02:49.278517Z","steps":["trace[1605273541] 'agreement among raft nodes before linearized reading' (duration: 122.273914ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:53.974282Z","caller":"traceutil/trace.go:171","msg":"trace[1961117594] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"183.323345ms","start":"2026-02-13T02:02:53.790944Z","end":"2026-02-13T02:02:53.974267Z","steps":["trace[1961117594] 'process raft request' (duration: 183.221412ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:53.975279Z","caller":"traceutil/trace.go:171","msg":"trace[274079646] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"171.899672ms","start":"2026-02-13T02:02:53.803373Z","end":"2026-02-13T02:02:53.975272Z","steps":["trace[274079646] 'process raft request' (duration: 171.700408ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:57.13424Z","caller":"traceutil/trace.go:171","msg":"trace[2116414990] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"328.555937ms","start":"2026-02-13T02:02:56.805668Z","end":"2026-02-13T02:02:57.134224Z","steps":["trace[2116414990] 'read index received' (duration: 328.370982ms)","trace[2116414990] 'applied index is now lower than readState.Index' (duration: 183.975µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:02:57.134321Z","caller":"traceutil/trace.go:171","msg":"trace[1313630164] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"520.253616ms","start":"2026-02-13T02:02:56.614062Z","end":"2026-02-13T02:02:57.134315Z","steps":["trace[1313630164] 'process raft request' (duration: 520.039011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.134402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:56.614047Z","time spent":"520.288007ms","remote":"127.0.0.1:58602","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-02-13T02:02:57.13457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.901185ms","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-02-13T02:02:57.134598Z","caller":"traceutil/trace.go:171","msg":"trace[1934368568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:709; }","duration":"328.967076ms","start":"2026-02-13T02:02:56.805625Z","end":"2026-02-13T02:02:57.134592Z","steps":["trace[1934368568] 'agreement among raft nodes before linearized reading' (duration: 328.851404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.134613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:56.805613Z","time spent":"328.996427ms","remote":"127.0.0.1:58602","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-02-13T02:02:57.134688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.843773ms","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-02-13T02:02:57.134701Z","caller":"traceutil/trace.go:171","msg":"trace[722937639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"295.906965ms","start":"2026-02-13T02:02:56.83879Z","end":"2026-02-13T02:02:57.134697Z","steps":["trace[722937639] 'agreement among raft nodes before linearized reading' (duration: 295.857794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.134793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.947946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-13T02:02:57.134818Z","caller":"traceutil/trace.go:171","msg":"trace[1466933206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"177.986587ms","start":"2026-02-13T02:02:56.956814Z","end":"2026-02-13T02:02:57.134801Z","steps":["trace[1466933206] 'agreement among raft nodes before linearized reading' (duration: 177.937856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.135005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.187065ms","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-02-13T02:02:57.135072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.554458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-13T02:02:57.135072Z","caller":"traceutil/trace.go:171","msg":"trace[461999984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"293.297016ms","start":"2026-02-13T02:02:56.841757Z","end":"2026-02-13T02:02:57.135054Z","steps":["trace[461999984] 'agreement among raft nodes before linearized reading' (duration: 293.122653ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:57.135092Z","caller":"traceutil/trace.go:171","msg":"trace[591960449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:709; }","duration":"281.589218ms","start":"2026-02-13T02:02:56.853496Z","end":"2026-02-13T02:02:57.135085Z","steps":["trace[591960449] 'agreement among raft nodes before linearized reading' (duration: 281.530457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.865687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.65169ms","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-02-13T02:02:57.865771Z","caller":"traceutil/trace.go:171","msg":"trace[895680310] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:709; }","duration":"173.741842ms","start":"2026-02-13T02:02:57.692014Z","end":"2026-02-13T02:02:57.865756Z","steps":["trace[895680310] 'range keys from in-memory index tree' (duration: 173.63925ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:57.866074Z","caller":"traceutil/trace.go:171","msg":"trace[554055195] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"726.443287ms","start":"2026-02-13T02:02:57.139619Z","end":"2026-02-13T02:02:57.866062Z","steps":["trace[554055195] 'process raft request' (duration: 661.886419ms)","trace[554055195] 'compare' (duration: 64.478696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:57.866153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:57.139603Z","time spent":"726.505998ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:02:57.944834Z","caller":"traceutil/trace.go:171","msg":"trace[1745284853] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"804.803009ms","start":"2026-02-13T02:02:57.140016Z","end":"2026-02-13T02:02:57.944819Z","steps":["trace[1745284853] 'process raft request' (duration: 804.764328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.944935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:57.140009Z","time spent":"804.873041ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:02:57.945013Z","caller":"traceutil/trace.go:171","msg":"trace[659501384] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"805.115676ms","start":"2026-02-13T02:02:57.139893Z","end":"2026-02-13T02:02:57.945009Z","steps":["trace[659501384] 'process raft request' (duration: 804.754088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.945037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:57.139873Z","time spent":"805.151647ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:02:57.945126Z","caller":"traceutil/trace.go:171","msg":"trace[404677967] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"805.108735ms","start":"2026-02-13T02:02:57.140013Z","end":"2026-02-13T02:02:57.945121Z","steps":["trace[404677967] 'process raft request' (duration: 804.732567ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:02:57.945105Z","caller":"traceutil/trace.go:171","msg":"trace[1417205585] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:732; }","duration":"343.083909ms","start":"2026-02-13T02:02:57.602003Z","end":"2026-02-13T02:02:57.945086Z","steps":["trace[1417205585] 'read index received' (duration: 199.573465ms)","trace[1417205585] 'applied index is now lower than readState.Index' (duration: 143.509594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:02:57.945153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:57.140005Z","time spent":"805.134207ms","remote":"127.0.0.1:58602","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-02-13T02:02:57.945201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.187511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:02:57.945244Z","caller":"traceutil/trace.go:171","msg":"trace[1250474013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"343.257572ms","start":"2026-02-13T02:02:57.601974Z","end":"2026-02-13T02:02:57.945232Z","steps":["trace[1250474013] 'agreement among raft nodes before linearized reading' (duration: 343.17453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.945271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:57.601951Z","time spent":"343.314483ms","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T02:02:57.945391Z","caller":"traceutil/trace.go:171","msg":"trace[47865756] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"802.105699ms","start":"2026-02-13T02:02:57.143278Z","end":"2026-02-13T02:02:57.945384Z","steps":["trace[47865756] 'process raft request' (duration: 801.521326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:02:57.945426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:02:57.14327Z","time spent":"802.13799ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:03:04.101017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T02:03:04.309932Z","caller":"traceutil/trace.go:171","msg":"trace[757853452] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"709.438935ms","start":"2026-02-13T02:03:03.600475Z","end":"2026-02-13T02:03:04.309914Z","steps":["trace[757853452] 'read index received' (duration: 709.258811ms)","trace[757853452] 'applied index is now lower than readState.Index' (duration: 179.014µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:04.310025Z","caller":"traceutil/trace.go:171","msg":"trace[1909619169] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"757.495847ms","start":"2026-02-13T02:03:03.552521Z","end":"2026-02-13T02:03:04.310016Z","steps":["trace[1909619169] 'process raft request' (duration: 757.264062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.3101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:03.552505Z","time spent":"757.542948ms","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T02:03:04.310356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.878324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:04.310398Z","caller":"traceutil/trace.go:171","msg":"trace[2061917812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"709.956296ms","start":"2026-02-13T02:03:03.600432Z","end":"2026-02-13T02:03:04.310388Z","steps":["trace[2061917812] 'agreement among raft nodes before linearized reading' (duration: 709.874884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.31043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:03.600419Z","time spent":"710.005887ms","remote":"127.0.0.1:58380","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-02-13T02:03:04.310638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.775925ms","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-02-13T02:03:04.310673Z","caller":"traceutil/trace.go:171","msg":"trace[1288345105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:726; }","duration":"412.810956ms","start":"2026-02-13T02:03:03.897854Z","end":"2026-02-13T02:03:04.310665Z","steps":["trace[1288345105] 'agreement among raft nodes before linearized reading' (duration: 412.708854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.310704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:03.897812Z","time spent":"412.886088ms","remote":"127.0.0.1:58602","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-02-13T02:03:04.311153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.08581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-13T02:03:04.311183Z","caller":"traceutil/trace.go:171","msg":"trace[1385677345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"218.138032ms","start":"2026-02-13T02:03:04.093036Z","end":"2026-02-13T02:03:04.311174Z","steps":["trace[1385677345] 'agreement among raft nodes before linearized reading' (duration: 218.05767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.311341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.025233ms","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-02-13T02:03:04.311368Z","caller":"traceutil/trace.go:171","msg":"trace[448061974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"257.218267ms","start":"2026-02-13T02:03:04.054142Z","end":"2026-02-13T02:03:04.31136Z","steps":["trace[448061974] 'agreement among raft nodes before linearized reading' (duration: 257.144765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.311516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.233207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-13T02:03:04.31155Z","caller":"traceutil/trace.go:171","msg":"trace[330288662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"257.296198ms","start":"2026-02-13T02:03:04.054247Z","end":"2026-02-13T02:03:04.311543Z","steps":["trace[330288662] 'agreement among raft nodes before linearized reading' (duration: 257.221837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.312323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.569611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-13T02:03:04.312372Z","caller":"traceutil/trace.go:171","msg":"trace[1587429620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"312.643342ms","start":"2026-02-13T02:03:03.999718Z","end":"2026-02-13T02:03:04.312361Z","steps":["trace[1587429620] 'agreement among raft nodes before linearized reading' (duration: 312.529749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.312401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:03.999704Z","time spent":"312.689924ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T02:03:04.312514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.397878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T02:03:04.31254Z","caller":"traceutil/trace.go:171","msg":"trace[1699121774] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:726; }","duration":"398.446209ms","start":"2026-02-13T02:03:03.914085Z","end":"2026-02-13T02:03:04.312531Z","steps":["trace[1699121774] 'agreement among raft nodes before linearized reading' (duration: 398.370737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.312564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:03.914078Z","time spent":"398.48163ms","remote":"127.0.0.1:58398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.209\" "} {"level":"info","ts":"2026-02-13T02:03:04.873854Z","caller":"traceutil/trace.go:171","msg":"trace[705620054] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"552.400053ms","start":"2026-02-13T02:03:04.321429Z","end":"2026-02-13T02:03:04.873829Z","steps":["trace[705620054] 'process raft request' (duration: 454.360936ms)","trace[705620054] 'compare' (duration: 96.804091ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:04.874293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:04.321413Z","time spent":"552.808082ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:03:04.876665Z","caller":"traceutil/trace.go:171","msg":"trace[813706245] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:750; }","duration":"392.482198ms","start":"2026-02-13T02:03:04.484165Z","end":"2026-02-13T02:03:04.876647Z","steps":["trace[813706245] 'read index received' (duration: 291.543286ms)","trace[813706245] 'applied index is now lower than readState.Index' (duration: 100.938212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:04.876772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:04.321434Z","time spent":"555.334978ms","remote":"127.0.0.1:58398","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-13T02:03:04.877087Z","caller":"traceutil/trace.go:171","msg":"trace[1863499079] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"555.544692ms","start":"2026-02-13T02:03:04.321534Z","end":"2026-02-13T02:03:04.877079Z","steps":["trace[1863499079] 'process raft request' (duration: 554.942179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.877169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:04.321527Z","time spent":"555.583574ms","remote":"127.0.0.1:58602","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-02-13T02:03:04.877258Z","caller":"traceutil/trace.go:171","msg":"trace[134889100] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"553.969888ms","start":"2026-02-13T02:03:04.323282Z","end":"2026-02-13T02:03:04.877252Z","steps":["trace[134889100] 'process raft request' (duration: 553.264012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.877297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:04.323257Z","time spent":"554.021089ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:03:04.877375Z","caller":"traceutil/trace.go:171","msg":"trace[450580013] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"553.690852ms","start":"2026-02-13T02:03:04.323679Z","end":"2026-02-13T02:03:04.87737Z","steps":["trace[450580013] 'process raft request' (duration: 552.895774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.877414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:04.323673Z","time spent":"553.721903ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:03:04.877495Z","caller":"traceutil/trace.go:171","msg":"trace[374842328] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"552.719451ms","start":"2026-02-13T02:03:04.324762Z","end":"2026-02-13T02:03:04.877482Z","steps":["trace[374842328] 'process raft request' (duration: 551.836641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.877531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:04.324757Z","time spent":"552.756822ms","remote":"127.0.0.1:58602","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-02-13T02:03:04.877603Z","caller":"traceutil/trace.go:171","msg":"trace[1302347193] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"444.653961ms","start":"2026-02-13T02:03:04.432944Z","end":"2026-02-13T02:03:04.877598Z","steps":["trace[1302347193] 'process raft request' (duration: 443.678549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.877642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:04.432903Z","time spent":"444.718653ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:03:04.877729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.570082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:04.877757Z","caller":"traceutil/trace.go:171","msg":"trace[1569778776] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:732; }","duration":"393.631464ms","start":"2026-02-13T02:03:04.484118Z","end":"2026-02-13T02:03:04.877749Z","steps":["trace[1569778776] 'agreement among raft nodes before linearized reading' (duration: 393.589663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:04.877778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:04.484103Z","time spent":"393.670344ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:04.877881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.466507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:04.877903Z","caller":"traceutil/trace.go:171","msg":"trace[1162799559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"273.487357ms","start":"2026-02-13T02:03:04.60441Z","end":"2026-02-13T02:03:04.877897Z","steps":["trace[1162799559] 'agreement among raft nodes before linearized reading' (duration: 273.456406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:11.413723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:11.913825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:12.414701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:12.915208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:13.415555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:13.91638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:14.416726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:14.602357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000734613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:14.602421Z","caller":"traceutil/trace.go:171","msg":"trace[1033212711] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000830266s","start":"2026-02-13T02:03:12.601576Z","end":"2026-02-13T02:03:14.602406Z","steps":["trace[1033212711] 'agreement among raft nodes before linearized reading' (duration: 2.000755014s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:14.602471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:12.601553Z","time spent":"2.000911227s","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 02:03:14 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:14.917504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:15.418403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:20.022585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.109521062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:20.022647Z","caller":"traceutil/trace.go:171","msg":"trace[2114688206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"9.109611544s","start":"2026-02-13T02:03:10.913022Z","end":"2026-02-13T02:03:20.022634Z","steps":["trace[2114688206] 'agreement among raft nodes before linearized reading' (duration: 9.109534232s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.022674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:10.913016Z","time spent":"9.109653185s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T02:03:20.024608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:10.904646Z","time spent":"9.119957984s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T02:03:20.024868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.422135098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:20.024895Z","caller":"traceutil/trace.go:171","msg":"trace[2137998626] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"5.42219393s","start":"2026-02-13T02:03:14.602691Z","end":"2026-02-13T02:03:20.024885Z","steps":["trace[2137998626] 'agreement among raft nodes before linearized reading' (duration: 5.422156949s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.024918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:14.602681Z","time spent":"5.42223374s","remote":"127.0.0.1:58390","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-02-13T02:03:20.024992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.302542214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:20.025007Z","caller":"traceutil/trace.go:171","msg":"trace[873280877] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"5.302575034s","start":"2026-02-13T02:03:14.722428Z","end":"2026-02-13T02:03:20.025003Z","steps":["trace[873280877] 'agreement among raft nodes before linearized reading' (duration: 5.302559254s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.025024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:14.722415Z","time spent":"5.302605595s","remote":"127.0.0.1:58380","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-02-13T02:03:20.025486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.102006286s","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-02-13T02:03:20.025506Z","caller":"traceutil/trace.go:171","msg":"trace[1642080971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"9.102039106s","start":"2026-02-13T02:03:10.923462Z","end":"2026-02-13T02:03:20.025501Z","steps":["trace[1642080971] 'agreement among raft nodes before linearized reading' (duration: 9.102014886s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.025526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:10.923457Z","time spent":"9.102062907s","remote":"127.0.0.1:58602","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/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.029394Z","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-02-13T02:03:20.428201Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"9.523453853s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:03:20.614978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.968421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:20.615095Z","caller":"traceutil/trace.go:171","msg":"trace[1496778904] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"589.093414ms","start":"2026-02-13T02:03:20.025963Z","end":"2026-02-13T02:03:20.615056Z","steps":["trace[1496778904] 'agreement among raft nodes before linearized reading' (duration: 588.978412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.615143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.025957Z","time spent":"589.174606ms","remote":"127.0.0.1:58390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.903326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.254905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:20.903429Z","caller":"traceutil/trace.go:171","msg":"trace[462262219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"877.402828ms","start":"2026-02-13T02:03:20.026006Z","end":"2026-02-13T02:03:20.903408Z","steps":["trace[462262219] 'agreement among raft nodes before linearized reading' (duration: 877.258075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.903578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.026003Z","time spent":"877.517241ms","remote":"127.0.0.1:58602","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/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.912949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.886078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:20.913038Z","caller":"traceutil/trace.go:171","msg":"trace[1045958310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"887.004921ms","start":"2026-02-13T02:03:20.026019Z","end":"2026-02-13T02:03:20.913024Z","steps":["trace[1045958310] 'agreement among raft nodes before linearized reading' (duration: 886.890949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.913104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.026017Z","time spent":"887.079983ms","remote":"127.0.0.1:58602","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/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.924353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.001932962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:20.924393Z","caller":"traceutil/trace.go:171","msg":"trace[466280887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.001985473s","start":"2026-02-13T02:03:10.922397Z","end":"2026-02-13T02:03:20.924383Z","steps":["trace[466280887] 'agreement among raft nodes before linearized reading' (duration: 10.001941332s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.924419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:10.922392Z","time spent":"10.002021184s","remote":"127.0.0.1:58602","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/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.924474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.003137548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:20.924494Z","caller":"traceutil/trace.go:171","msg":"trace[884129521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.00316858s","start":"2026-02-13T02:03:10.921321Z","end":"2026-02-13T02:03:20.924489Z","steps":["trace[884129521] 'agreement among raft nodes before linearized reading' (duration: 10.003148459s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.92451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:10.921315Z","time spent":"10.00319113s","remote":"127.0.0.1:58602","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/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.924985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.979075ms","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-02-13T02:03:20.925011Z","caller":"traceutil/trace.go:171","msg":"trace[1105085634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"899.017376ms","start":"2026-02-13T02:03:20.025987Z","end":"2026-02-13T02:03:20.925005Z","steps":["trace[1105085634] 'agreement among raft nodes before linearized reading' (duration: 898.986315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.925032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.025984Z","time spent":"899.043106ms","remote":"127.0.0.1:58602","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/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.943554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.92809381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:20.943588Z","caller":"traceutil/trace.go:171","msg":"trace[1596254944] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; }","duration":"9.928144301s","start":"2026-02-13T02:03:11.015437Z","end":"2026-02-13T02:03:20.943582Z","steps":["trace[1596254944] 'agreement among raft nodes before linearized reading' (duration: 9.92810164s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.943611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:11.015431Z","time spent":"9.928174231s","remote":"127.0.0.1:50922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} 2026/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.943957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.935573465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:20.944007Z","caller":"traceutil/trace.go:171","msg":"trace[1469723878] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; }","duration":"9.935683047s","start":"2026-02-13T02:03:11.008296Z","end":"2026-02-13T02:03:20.943979Z","steps":["trace[1469723878] 'agreement among raft nodes before linearized reading' (duration: 9.935572795s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.944038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:11.008285Z","time spent":"9.935743229s","remote":"127.0.0.1:50916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} 2026/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:20.945153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.9466673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:20.945199Z","caller":"traceutil/trace.go:171","msg":"trace[1140475519] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; }","duration":"9.946738661s","start":"2026-02-13T02:03:10.998452Z","end":"2026-02-13T02:03:20.945191Z","steps":["trace[1140475519] 'agreement among raft nodes before linearized reading' (duration: 9.94668186s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:20.945233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:10.998439Z","time spent":"9.946784322s","remote":"127.0.0.1:50910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} 2026/02/13 02:03:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:21.900047Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.471680687s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:03:21.943393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:14.943287Z","time spent":"7.000100625s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T02:03:22.602394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.085753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-02-13T02:03:22.619671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000211822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:22.619728Z","caller":"traceutil/trace.go:171","msg":"trace[327365129] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000304224s","start":"2026-02-13T02:03:20.619411Z","end":"2026-02-13T02:03:22.619715Z","steps":["trace[327365129] 'agreement among raft nodes before linearized reading' (duration: 2.000230432s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:22.61976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.619397Z","time spent":"2.000356436s","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 02:03:22 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:23.778708Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.878505362s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:03:24.62025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000408547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:24.620388Z","caller":"traceutil/trace.go:171","msg":"trace[858289758] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00056014s","start":"2026-02-13T02:03:22.619805Z","end":"2026-02-13T02:03:24.620365Z","steps":["trace[858289758] 'agreement among raft nodes before linearized reading' (duration: 2.000403187s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:24.620453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:22.619765Z","time spent":"2.000672023s","remote":"127.0.0.1:58390","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-02-13T02:03:25.390993Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.612119102s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:03:25.60022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997764738s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-13T02:03:25.601939Z","caller":"traceutil/trace.go:171","msg":"trace[2145991094] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"5.576054532s","start":"2026-02-13T02:03:20.02587Z","end":"2026-02-13T02:03:25.601924Z","steps":["trace[2145991094] 'process raft request' (duration: 5.574434445s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:25.602011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.025858Z","time spent":"5.576106072s","remote":"127.0.0.1:58602","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-02-13T02:03:25.91674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.915174Z","time spent":"5.001559689s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/13 02:03:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:25.928066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.929304Z","time spent":"4.998759356s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T02:03:26.632762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000903939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:26.632818Z","caller":"traceutil/trace.go:171","msg":"trace[1455899628] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000981281s","start":"2026-02-13T02:03:24.631826Z","end":"2026-02-13T02:03:26.632807Z","steps":["trace[1455899628] 'agreement among raft nodes before linearized reading' (duration: 2.000915959s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:26.632864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:24.63182Z","time spent":"2.001029492s","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 02:03:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:27.039639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.038681Z","time spent":"7.000953314s","remote":"127.0.0.1:58436","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-13T02:03:27.927078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.926252Z","time spent":"7.000820721s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T02:03:27.927189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.927073Z","time spent":"7.000110836s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T02:03:28.633029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000308686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:28.633117Z","caller":"traceutil/trace.go:171","msg":"trace[711507695] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000445388s","start":"2026-02-13T02:03:26.632656Z","end":"2026-02-13T02:03:28.633101Z","steps":["trace[711507695] 'agreement among raft nodes before linearized reading' (duration: 2.000337756s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.633154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:26.63264Z","time spent":"2.000505791s","remote":"127.0.0.1:58390","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-02-13T02:03:28.662045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.193978ms","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-02-13T02:03:28.662111Z","caller":"traceutil/trace.go:171","msg":"trace[20604352] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:755; }","duration":"969.26385ms","start":"2026-02-13T02:03:27.692838Z","end":"2026-02-13T02:03:28.662102Z","steps":["trace[20604352] 'range keys from in-memory index tree' (duration: 969.185618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.662684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.06075634s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-13T02:03:28.66287Z","caller":"traceutil/trace.go:171","msg":"trace[1579815919] transaction","detail":"{read_only:false; number_of_response:1; response_revision:759; }","duration":"6.717124809s","start":"2026-02-13T02:03:21.945738Z","end":"2026-02-13T02:03:28.662863Z","steps":["trace[1579815919] 'process raft request' (duration: 6.717104279s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.662924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:21.945725Z","time spent":"6.71716235s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:03:28.663028Z","caller":"traceutil/trace.go:171","msg":"trace[1901159996] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:779; }","duration":"8.63358524s","start":"2026-02-13T02:03:20.029436Z","end":"2026-02-13T02:03:28.663021Z","steps":["trace[1901159996] 'read index received' (duration: 399.257367ms)","trace[1901159996] 'applied index is now lower than readState.Index' (duration: 8.234327213s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:28.663195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.41402838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.663214Z","caller":"traceutil/trace.go:171","msg":"trace[959967553] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:759; }","duration":"13.414070051s","start":"2026-02-13T02:03:15.249138Z","end":"2026-02-13T02:03:28.663208Z","steps":["trace[959967553] 'agreement among raft nodes before linearized reading' (duration: 13.41403769s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.663232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:15.249123Z","time spent":"13.414103572s","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.663317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"14.745695551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.663344Z","caller":"traceutil/trace.go:171","msg":"trace[748872167] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:759; }","duration":"14.745747383s","start":"2026-02-13T02:03:13.917592Z","end":"2026-02-13T02:03:28.663339Z","steps":["trace[748872167] 'agreement among raft nodes before linearized reading' (duration: 14.745709762s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.66336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:13.91758Z","time spent":"14.745775383s","remote":"127.0.0.1:58626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.66345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"14.749062967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.663471Z","caller":"traceutil/trace.go:171","msg":"trace[1066095075] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:0; response_revision:759; }","duration":"14.749082967s","start":"2026-02-13T02:03:13.914382Z","end":"2026-02-13T02:03:28.663465Z","steps":["trace[1066095075] 'agreement among raft nodes before linearized reading' (duration: 14.749051417s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.663491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:13.914338Z","time spent":"14.749146569s","remote":"127.0.0.1:58398","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.209\" "} {"level":"warn","ts":"2026-02-13T02:03:28.663601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"15.812654041s","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-02-13T02:03:28.663623Z","caller":"traceutil/trace.go:171","msg":"trace[257346924] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:759; }","duration":"15.812675402s","start":"2026-02-13T02:03:12.85094Z","end":"2026-02-13T02:03:28.663616Z","steps":["trace[257346924] 'agreement among raft nodes before linearized reading' (duration: 15.812636981s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.663659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:12.850903Z","time spent":"15.812749695s","remote":"127.0.0.1:58810","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-02-13T02:03:28.663763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"16.946447539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:03:28.663787Z","caller":"traceutil/trace.go:171","msg":"trace[1725808159] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:759; }","duration":"16.94650319s","start":"2026-02-13T02:03:11.717275Z","end":"2026-02-13T02:03:28.663778Z","steps":["trace[1725808159] 'agreement among raft nodes before linearized reading' (duration: 16.946462039s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.663809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:11.717259Z","time spent":"16.946544142s","remote":"127.0.0.1:58784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.663913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.575495646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.663936Z","caller":"traceutil/trace.go:171","msg":"trace[1706244654] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:759; }","duration":"17.575550108s","start":"2026-02-13T02:03:11.088377Z","end":"2026-02-13T02:03:28.663927Z","steps":["trace[1706244654] 'agreement among raft nodes before linearized reading' (duration: 17.575516387s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.663957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:11.088365Z","time spent":"17.575586308s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.665552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.648596893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.665573Z","caller":"traceutil/trace.go:171","msg":"trace[367416269] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:759; }","duration":"17.650112315s","start":"2026-02-13T02:03:11.015455Z","end":"2026-02-13T02:03:28.665567Z","steps":["trace[367416269] 'agreement among raft nodes before linearized reading' (duration: 17.648592142s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.665591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:11.015449Z","time spent":"17.650135506s","remote":"127.0.0.1:50922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-13T02:03:28.665683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.650216958s","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-02-13T02:03:28.665697Z","caller":"traceutil/trace.go:171","msg":"trace[915947251] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:759; }","duration":"17.650238838s","start":"2026-02-13T02:03:11.015454Z","end":"2026-02-13T02:03:28.665693Z","steps":["trace[915947251] 'agreement among raft nodes before linearized reading' (duration: 17.650213997s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.665711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:11.015452Z","time spent":"17.650255488s","remote":"127.0.0.1:50922","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-02-13T02:03:28.665764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.657447167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.665776Z","caller":"traceutil/trace.go:171","msg":"trace[1997199549] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:759; }","duration":"17.657470829s","start":"2026-02-13T02:03:11.008302Z","end":"2026-02-13T02:03:28.665772Z","steps":["trace[1997199549] 'agreement among raft nodes before linearized reading' (duration: 17.657451868s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.665788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:11.0083Z","time spent":"17.657484859s","remote":"127.0.0.1:50916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.665868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.657606322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.66588Z","caller":"traceutil/trace.go:171","msg":"trace[53465822] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:759; }","duration":"17.657637002s","start":"2026-02-13T02:03:11.00824Z","end":"2026-02-13T02:03:28.665877Z","steps":["trace[53465822] 'agreement among raft nodes before linearized reading' (duration: 17.657615941s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.665894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:11.008228Z","time spent":"17.657662142s","remote":"127.0.0.1:50916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-13T02:03:28.665956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.667434128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.665968Z","caller":"traceutil/trace.go:171","msg":"trace[40078893] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:759; }","duration":"17.667462799s","start":"2026-02-13T02:03:10.998502Z","end":"2026-02-13T02:03:28.665965Z","steps":["trace[40078893] 'agreement among raft nodes before linearized reading' (duration: 17.667441038s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.665983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:10.9985Z","time spent":"17.667479579s","remote":"127.0.0.1:50910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-13T02:03:28.666052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.667551151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.666064Z","caller":"traceutil/trace.go:171","msg":"trace[1801449141] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:759; }","duration":"17.667571511s","start":"2026-02-13T02:03:10.99849Z","end":"2026-02-13T02:03:28.666061Z","steps":["trace[1801449141] 'agreement among raft nodes before linearized reading' (duration: 17.66755166s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.666076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:10.998487Z","time spent":"17.667585871s","remote":"127.0.0.1:50910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.666133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.627647847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.666145Z","caller":"traceutil/trace.go:171","msg":"trace[1027375653] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:759; }","duration":"8.627669589s","start":"2026-02-13T02:03:20.038472Z","end":"2026-02-13T02:03:28.666142Z","steps":["trace[1027375653] 'agreement among raft nodes before linearized reading' (duration: 8.627649778s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.666157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.038464Z","time spent":"8.627689419s","remote":"127.0.0.1:58516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.666499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.8331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:03:28.666514Z","caller":"traceutil/trace.go:171","msg":"trace[820335599] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:759; }","duration":"584.889802ms","start":"2026-02-13T02:03:28.081619Z","end":"2026-02-13T02:03:28.666509Z","steps":["trace[820335599] 'agreement among raft nodes before linearized reading' (duration: 584.852061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.666528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:28.081601Z","time spent":"584.923122ms","remote":"127.0.0.1:58548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.666608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238488023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:03:28.666622Z","caller":"traceutil/trace.go:171","msg":"trace[1050589521] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:759; }","duration":"1.238526774s","start":"2026-02-13T02:03:27.428092Z","end":"2026-02-13T02:03:28.666619Z","steps":["trace[1050589521] 'agreement among raft nodes before linearized reading' (duration: 1.238490493s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.666634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:27.428058Z","time spent":"1.238572425s","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.66669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622289798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1893ac007e32e11b\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.666702Z","caller":"traceutil/trace.go:171","msg":"trace[1769661035] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1893ac007e32e11b; range_end:; response_count:0; response_revision:759; }","duration":"1.62234042s","start":"2026-02-13T02:03:27.044358Z","end":"2026-02-13T02:03:28.666698Z","steps":["trace[1769661035] 'agreement among raft nodes before linearized reading' (duration: 1.622320739s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.666713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:27.044339Z","time spent":"1.62237114s","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1893ac007e32e11b\" "} {"level":"warn","ts":"2026-02-13T02:03:28.66678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.040225536s","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-02-13T02:03:28.66679Z","caller":"traceutil/trace.go:171","msg":"trace[1926783631] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:759; }","duration":"4.040274868s","start":"2026-02-13T02:03:24.626512Z","end":"2026-02-13T02:03:28.666787Z","steps":["trace[1926783631] 'agreement among raft nodes before linearized reading' (duration: 4.040247647s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.666803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:24.626493Z","time spent":"4.040306649s","remote":"127.0.0.1:58536","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-02-13T02:03:28.667181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.857520195s","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-02-13T02:03:28.667197Z","caller":"traceutil/trace.go:171","msg":"trace[1323287240] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:759; }","duration":"4.857565306s","start":"2026-02-13T02:03:23.809627Z","end":"2026-02-13T02:03:28.667193Z","steps":["trace[1323287240] 'agreement among raft nodes before linearized reading' (duration: 4.857535656s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.667211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:23.809613Z","time spent":"4.857594507s","remote":"127.0.0.1:58792","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-02-13T02:03:28.667277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.917731387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:03:28.667289Z","caller":"traceutil/trace.go:171","msg":"trace[307556941] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:759; }","duration":"4.917770907s","start":"2026-02-13T02:03:23.749514Z","end":"2026-02-13T02:03:28.667285Z","steps":["trace[307556941] 'agreement among raft nodes before linearized reading' (duration: 4.917747287s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.6673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:23.749496Z","time spent":"4.917800599s","remote":"127.0.0.1:58470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.667368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.666509411s","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-02-13T02:03:28.667379Z","caller":"traceutil/trace.go:171","msg":"trace[1951700258] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:759; }","duration":"5.666557532s","start":"2026-02-13T02:03:23.000818Z","end":"2026-02-13T02:03:28.667376Z","steps":["trace[1951700258] 'agreement among raft nodes before linearized reading' (duration: 5.666525432s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.667392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:23.000799Z","time spent":"5.666590314s","remote":"127.0.0.1:58692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.667446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.615106042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:28.667458Z","caller":"traceutil/trace.go:171","msg":"trace[93315012] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:759; }","duration":"7.615180885s","start":"2026-02-13T02:03:21.052274Z","end":"2026-02-13T02:03:28.667454Z","steps":["trace[93315012] 'agreement among raft nodes before linearized reading' (duration: 7.615161665s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.667469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:21.052253Z","time spent":"7.615212755s","remote":"127.0.0.1:58490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:28.667526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.628890066s","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-02-13T02:03:28.667538Z","caller":"traceutil/trace.go:171","msg":"trace[336300186] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:759; }","duration":"8.628907876s","start":"2026-02-13T02:03:20.038627Z","end":"2026-02-13T02:03:28.667535Z","steps":["trace[336300186] 'agreement among raft nodes before linearized reading' (duration: 8.628887135s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.667549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.038623Z","time spent":"8.628922916s","remote":"127.0.0.1:58588","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-02-13T02:03:28.667642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.629019268s","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-02-13T02:03:28.667654Z","caller":"traceutil/trace.go:171","msg":"trace[1043361147] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:759; }","duration":"8.629038699s","start":"2026-02-13T02:03:20.038611Z","end":"2026-02-13T02:03:28.66765Z","steps":["trace[1043361147] 'agreement among raft nodes before linearized reading' (duration: 8.628991748s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:28.667667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:20.038608Z","time spent":"8.62905522s","remote":"127.0.0.1:58888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":99,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:29.500796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.933476ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-13T02:03:29.500859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:28.674228Z","time spent":"826.628146ms","remote":"127.0.0.1:58398","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-13T02:03:32.60429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000985659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:32.604372Z","caller":"traceutil/trace.go:171","msg":"trace[369371995] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001121322s","start":"2026-02-13T02:03:30.603231Z","end":"2026-02-13T02:03:32.604353Z","steps":["trace[369371995] 'agreement among raft nodes before linearized reading' (duration: 2.001014449s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:32.604427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:30.603214Z","time spent":"2.001195434s","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 02:03:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:33.668883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.731030568s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:03:33.669453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:28.679128Z","time spent":"4.990319981s","remote":"127.0.0.1:58436","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-13T02:03:33.669472Z","caller":"traceutil/trace.go:171","msg":"trace[962539083] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:788; }","duration":"4.982480887s","start":"2026-02-13T02:03:28.686974Z","end":"2026-02-13T02:03:33.669455Z","steps":["trace[962539083] 'read index received' (duration: 250.885906ms)","trace[962539083] 'applied index is now lower than readState.Index' (duration: 4.731594051s)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:33.669773Z","caller":"traceutil/trace.go:171","msg":"trace[2026303830] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"4.982857287s","start":"2026-02-13T02:03:28.686908Z","end":"2026-02-13T02:03:33.669765Z","steps":["trace[2026303830] 'process raft request' (duration: 4.982457697s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:33.669848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:28.686898Z","time spent":"4.982895267s","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:03:33.669985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.98299244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:687"} {"level":"info","ts":"2026-02-13T02:03:33.670165Z","caller":"traceutil/trace.go:171","msg":"trace[846889600] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:760; }","duration":"4.983196264s","start":"2026-02-13T02:03:28.686947Z","end":"2026-02-13T02:03:33.670143Z","steps":["trace[846889600] 'agreement among raft nodes before linearized reading' (duration: 4.982903747s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:33.670236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:28.686928Z","time spent":"4.983293105s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":710,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-13T02:03:34.170112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:34.604784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000204002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:34.604872Z","caller":"traceutil/trace.go:171","msg":"trace[1901441977] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000360155s","start":"2026-02-13T02:03:32.604494Z","end":"2026-02-13T02:03:34.604854Z","steps":["trace[1901441977] 'agreement among raft nodes before linearized reading' (duration: 2.000241473s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:34.604919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:32.604475Z","time spent":"2.000434897s","remote":"127.0.0.1:58390","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-02-13T02:03:34.671263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:35.171402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:35.395698Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.726612434s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:03:35.90566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.543365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:03:35.905886Z","caller":"traceutil/trace.go:171","msg":"trace[996607875] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:791; }","duration":"2.236317403s","start":"2026-02-13T02:03:33.669552Z","end":"2026-02-13T02:03:35.90587Z","steps":["trace[996607875] 'read index received' (duration: 1.726467931s)","trace[996607875] 'applied index is now lower than readState.Index' (duration: 509.848622ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:35.905953Z","caller":"traceutil/trace.go:171","msg":"trace[1689093992] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"6.4036997s","start":"2026-02-13T02:03:29.502241Z","end":"2026-02-13T02:03:35.905941Z","steps":["trace[1689093992] 'process raft request' (duration: 5.893770446s)","trace[1689093992] 'compare' (duration: 509.274599ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:35.90601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:29.502223Z","time spent":"6.40375432s","remote":"127.0.0.1:58398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:03:35.90619Z","caller":"traceutil/trace.go:171","msg":"trace[1244227718] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"2.231285362s","start":"2026-02-13T02:03:33.674896Z","end":"2026-02-13T02:03:35.906181Z","steps":["trace[1244227718] 'process raft request' (duration: 2.230857643s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:35.906253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:33.674879Z","time spent":"2.231331613s","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T02:03:35.90649Z","caller":"traceutil/trace.go:171","msg":"trace[870389647] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"2.229519452s","start":"2026-02-13T02:03:33.676961Z","end":"2026-02-13T02:03:35.906481Z","steps":["trace[870389647] 'process raft request' (duration: 2.228841628s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:35.906541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:33.676953Z","time spent":"2.229560443s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:03:35.906682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.464833035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:03:35.90671Z","caller":"traceutil/trace.go:171","msg":"trace[101626675] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:763; }","duration":"2.464907586s","start":"2026-02-13T02:03:33.441793Z","end":"2026-02-13T02:03:35.906701Z","steps":["trace[101626675] 'agreement among raft nodes before linearized reading' (duration: 2.464859065s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:35.906737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:33.441773Z","time spent":"2.464956188s","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:35.906866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.690537064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:35.906891Z","caller":"traceutil/trace.go:171","msg":"trace[1957388483] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:763; }","duration":"3.690597904s","start":"2026-02-13T02:03:32.216286Z","end":"2026-02-13T02:03:35.906884Z","steps":["trace[1957388483] 'agreement among raft nodes before linearized reading' (duration: 3.690557203s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:35.906918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:32.216272Z","time spent":"3.690637076s","remote":"127.0.0.1:58620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:35.907042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.202632455s","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-02-13T02:03:35.907067Z","caller":"traceutil/trace.go:171","msg":"trace[1231924657] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:763; }","duration":"4.202694967s","start":"2026-02-13T02:03:31.704365Z","end":"2026-02-13T02:03:35.90706Z","steps":["trace[1231924657] 'agreement among raft nodes before linearized reading' (duration: 4.202654446s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:35.907092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:31.704347Z","time spent":"4.202738378s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:35.907296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.708845587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:03:35.907323Z","caller":"traceutil/trace.go:171","msg":"trace[382081154] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:763; }","duration":"5.708901739s","start":"2026-02-13T02:03:30.198414Z","end":"2026-02-13T02:03:35.907315Z","steps":["trace[382081154] 'agreement among raft nodes before linearized reading' (duration: 5.708855448s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:35.907348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:30.1984Z","time spent":"5.708941589s","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:36.40693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:36.614008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000245924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:36.614089Z","caller":"traceutil/trace.go:171","msg":"trace[1144905693] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000373407s","start":"2026-02-13T02:03:34.613697Z","end":"2026-02-13T02:03:36.61407Z","steps":["trace[1144905693] 'agreement among raft nodes before linearized reading' (duration: 2.000269234s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:36.614164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:34.613682Z","time spent":"2.000448919s","remote":"127.0.0.1:58380","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-02-13T02:03:36.907501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:37.408315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:37.695217Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.833637028s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T02:03:37.695458Z","caller":"traceutil/trace.go:171","msg":"trace[602780228] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.885997167s","start":"2026-02-13T02:03:35.80944Z","end":"2026-02-13T02:03:37.695437Z","steps":["trace[602780228] 'process raft request' (duration: 1.885888495s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:37.695568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:35.809418Z","time spent":"1.886084769s","remote":"127.0.0.1:58602","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-02-13T02:03:38.615436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000517889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:03:38.615524Z","caller":"traceutil/trace.go:171","msg":"trace[357130985] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000621321s","start":"2026-02-13T02:03:36.614886Z","end":"2026-02-13T02:03:38.615507Z","steps":["trace[357130985] 'agreement among raft nodes before linearized reading' (duration: 2.000514839s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:38.615569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:36.614838Z","time spent":"2.000722783s","remote":"127.0.0.1:58390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 02:03:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T02:03:39.015798Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.320447984s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:03:39.777929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.902785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:03:39.778092Z","caller":"traceutil/trace.go:171","msg":"trace[1431089679] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"3.865159404s","start":"2026-02-13T02:03:35.912919Z","end":"2026-02-13T02:03:39.778078Z","steps":["trace[1431089679] 'process raft request' (duration: 3.103050074s)","trace[1431089679] 'compare' (duration: 761.136478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:39.778177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:35.912904Z","time spent":"3.865243506s","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T02:03:39.77859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.994361089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:03:39.778633Z","caller":"traceutil/trace.go:171","msg":"trace[1138566064] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:765; }","duration":"4.994442931s","start":"2026-02-13T02:03:34.784181Z","end":"2026-02-13T02:03:39.778623Z","steps":["trace[1138566064] 'agreement among raft nodes before linearized reading' (duration: 4.994338479s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:39.778667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:34.784163Z","time spent":"4.994494602s","remote":"127.0.0.1:58664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:39.780612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.103541382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-13T02:03:39.780661Z","caller":"traceutil/trace.go:171","msg":"trace[364592876] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:765; }","duration":"6.103614514s","start":"2026-02-13T02:03:33.677035Z","end":"2026-02-13T02:03:39.78065Z","steps":["trace[364592876] 'agreement among raft nodes before linearized reading' (duration: 6.10345785s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:39.780697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:33.677029Z","time spent":"6.103661424s","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7260,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-13T02:03:39.778039Z","caller":"traceutil/trace.go:171","msg":"trace[1886829685] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"3.872109397s","start":"2026-02-13T02:03:35.905914Z","end":"2026-02-13T02:03:39.778024Z","steps":["trace[1886829685] 'read index received' (duration: 1.789726778s)","trace[1886829685] 'applied index is now lower than readState.Index' (duration: 2.082381089s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:39.781056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.869642413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-13T02:03:39.781245Z","caller":"traceutil/trace.go:171","msg":"trace[754956151] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:765; }","duration":"3.869867417s","start":"2026-02-13T02:03:35.91136Z","end":"2026-02-13T02:03:39.781228Z","steps":["trace[754956151] 'agreement among raft nodes before linearized reading' (duration: 3.869609181s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:39.781355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:35.911345Z","time spent":"3.869998821s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-13T02:03:39.781692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159546247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:39.781782Z","caller":"traceutil/trace.go:171","msg":"trace[1592123006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"1.159636459s","start":"2026-02-13T02:03:38.622137Z","end":"2026-02-13T02:03:39.781773Z","steps":["trace[1592123006] 'agreement among raft nodes before linearized reading' (duration: 1.159525667s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:39.781899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:38.62207Z","time spent":"1.159820912s","remote":"127.0.0.1:58380","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-02-13T02:03:40.292932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570450,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T02:03:40.569542Z","caller":"traceutil/trace.go:171","msg":"trace[946106274] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"778.561312ms","start":"2026-02-13T02:03:39.790959Z","end":"2026-02-13T02:03:40.56952Z","steps":["trace[946106274] 'process raft request' (duration: 778.380028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:40.56967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:39.790947Z","time spent":"778.656835ms","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:03:41.793407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000743014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:41.793575Z","caller":"traceutil/trace.go:171","msg":"trace[2035132190] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000941998s","start":"2026-02-13T02:03:39.792613Z","end":"2026-02-13T02:03:41.793555Z","steps":["trace[2035132190] 'agreement among raft nodes before linearized reading' (duration: 2.000750054s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:41.793654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:39.792607Z","time spent":"2.001033461s","remote":"127.0.0.1:58380","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-02-13T02:03:42.211557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46680005s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:03:42.21165Z","caller":"traceutil/trace.go:171","msg":"trace[1032633982] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"2.419732778s","start":"2026-02-13T02:03:39.791901Z","end":"2026-02-13T02:03:42.211634Z","steps":["trace[1032633982] 'read index received' (duration: 778.218895ms)","trace[1032633982] 'applied index is now lower than readState.Index' (duration: 1.641512723s)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:42.211728Z","caller":"traceutil/trace.go:171","msg":"trace[368036537] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"2.420478374s","start":"2026-02-13T02:03:39.791239Z","end":"2026-02-13T02:03:42.211717Z","steps":["trace[368036537] 'process raft request' (duration: 953.418458ms)","trace[368036537] 'compare' (duration: 1.466240117s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:42.211783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:39.791228Z","time spent":"2.420524455s","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:03:42.212061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.420152307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T02:03:42.212128Z","caller":"traceutil/trace.go:171","msg":"trace[1623132269] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:767; }","duration":"2.420226679s","start":"2026-02-13T02:03:39.791891Z","end":"2026-02-13T02:03:42.212118Z","steps":["trace[1623132269] 'agreement among raft nodes before linearized reading' (duration: 2.420058605s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:42.21216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:39.791886Z","time spent":"2.420265959s","remote":"127.0.0.1:58398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.209\" "} {"level":"warn","ts":"2026-02-13T02:03:42.212346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.638065077s","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-02-13T02:03:42.212376Z","caller":"traceutil/trace.go:171","msg":"trace[1948038367] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:767; }","duration":"1.638130438s","start":"2026-02-13T02:03:40.574237Z","end":"2026-02-13T02:03:42.212367Z","steps":["trace[1948038367] 'agreement among raft nodes before linearized reading' (duration: 1.638045186s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:42.212406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:40.574217Z","time spent":"1.638182339s","remote":"127.0.0.1:58536","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-02-13T02:03:42.212547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.238457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:42.212573Z","caller":"traceutil/trace.go:171","msg":"trace[780509657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"418.294238ms","start":"2026-02-13T02:03:41.79427Z","end":"2026-02-13T02:03:42.212565Z","steps":["trace[780509657] 'agreement among raft nodes before linearized reading' (duration: 418.254638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:42.212553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.076156ms","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":"warn","ts":"2026-02-13T02:03:42.212602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:41.794232Z","time spent":"418.364409ms","remote":"127.0.0.1:58390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T02:03:42.212631Z","caller":"traceutil/trace.go:171","msg":"trace[1331828334] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:767; }","duration":"348.241379ms","start":"2026-02-13T02:03:41.864367Z","end":"2026-02-13T02:03:42.212608Z","steps":["trace[1331828334] 'agreement among raft nodes before linearized reading' (duration: 348.086186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:42.212677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:41.864344Z","time spent":"348.323451ms","remote":"127.0.0.1:58822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:03:42.21275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231228351s","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-02-13T02:03:42.212783Z","caller":"traceutil/trace.go:171","msg":"trace[690495697] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:767; }","duration":"1.231285662s","start":"2026-02-13T02:03:40.981487Z","end":"2026-02-13T02:03:42.212772Z","steps":["trace[690495697] 'agreement among raft nodes before linearized reading' (duration: 1.231227911s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:42.212815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:40.981475Z","time spent":"1.231332543s","remote":"127.0.0.1:58900","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-02-13T02:03:42.212946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318329727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:42.212971Z","caller":"traceutil/trace.go:171","msg":"trace[864595738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"1.318381098s","start":"2026-02-13T02:03:40.894582Z","end":"2026-02-13T02:03:42.212963Z","steps":["trace[864595738] 'agreement among raft nodes before linearized reading' (duration: 1.318344587s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:42.212995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:40.894551Z","time spent":"1.318439189s","remote":"127.0.0.1:58380","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-02-13T02:03:42.724449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570454,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:43.225126Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570454,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:43.360021Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.140121037s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:03:43.947668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.372325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:03:43.947877Z","caller":"traceutil/trace.go:171","msg":"trace[288227400] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:798; }","duration":"1.724122408s","start":"2026-02-13T02:03:42.223732Z","end":"2026-02-13T02:03:43.94786Z","steps":["trace[288227400] 'read index received' (duration: 1.136507097s)","trace[288227400] 'applied index is now lower than readState.Index' (duration: 587.614321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:43.947936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.724197201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:43.947963Z","caller":"traceutil/trace.go:171","msg":"trace[679494596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"1.724241982s","start":"2026-02-13T02:03:42.22371Z","end":"2026-02-13T02:03:43.947952Z","steps":["trace[679494596] 'agreement among raft nodes before linearized reading' (duration: 1.72419563s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:43.947992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:42.223701Z","time spent":"1.724284973s","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T02:03:43.948218Z","caller":"traceutil/trace.go:171","msg":"trace[1409146621] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.728378113s","start":"2026-02-13T02:03:42.219828Z","end":"2026-02-13T02:03:43.948206Z","steps":["trace[1409146621] 'process raft request' (duration: 1.140408984s)","trace[1409146621] 'compare' (duration: 586.847344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:43.948271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:42.219806Z","time spent":"1.728438814s","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T02:03:43.948388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:42.219978Z","time spent":"1.728408083s","remote":"127.0.0.1:58398","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-13T02:03:44.232506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.830072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:03:44.233031Z","caller":"traceutil/trace.go:171","msg":"trace[1224828376] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"276.123155ms","start":"2026-02-13T02:03:43.956893Z","end":"2026-02-13T02:03:44.233016Z","steps":["trace[1224828376] 'process raft request' (duration: 121.738301ms)","trace[1224828376] 'compare' (duration: 153.649698ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:44.233342Z","caller":"traceutil/trace.go:171","msg":"trace[1383264793] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"276.35544ms","start":"2026-02-13T02:03:43.956979Z","end":"2026-02-13T02:03:44.233334Z","steps":["trace[1383264793] 'read index received' (duration: 121.66072ms)","trace[1383264793] 'applied index is now lower than readState.Index' (duration: 154.69363ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:44.233379Z","caller":"traceutil/trace.go:171","msg":"trace[1504149684] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"276.352969ms","start":"2026-02-13T02:03:43.957021Z","end":"2026-02-13T02:03:44.233374Z","steps":["trace[1504149684] 'process raft request' (duration: 275.552112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:44.314476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.465463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:44.314553Z","caller":"traceutil/trace.go:171","msg":"trace[1425205187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"357.582485ms","start":"2026-02-13T02:03:43.95695Z","end":"2026-02-13T02:03:44.314532Z","steps":["trace[1425205187] 'agreement among raft nodes before linearized reading' (duration: 276.4123ms)","trace[1425205187] 'range keys from in-memory index tree' (duration: 81.059433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:44.314595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:43.956941Z","time spent":"357.645117ms","remote":"127.0.0.1:58390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T02:03:44.450091Z","caller":"traceutil/trace.go:171","msg":"trace[724028696] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"131.263352ms","start":"2026-02-13T02:03:44.318802Z","end":"2026-02-13T02:03:44.450065Z","steps":["trace[724028696] 'read index received' (duration: 131.037727ms)","trace[724028696] 'applied index is now lower than readState.Index' (duration: 224.565µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:44.450202Z","caller":"traceutil/trace.go:171","msg":"trace[1672814008] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"131.982558ms","start":"2026-02-13T02:03:44.318194Z","end":"2026-02-13T02:03:44.450176Z","steps":["trace[1672814008] 'process raft request' (duration: 131.739543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:44.450317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.492697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T02:03:44.450349Z","caller":"traceutil/trace.go:171","msg":"trace[1853797012] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:771; }","duration":"131.541628ms","start":"2026-02-13T02:03:44.318798Z","end":"2026-02-13T02:03:44.45034Z","steps":["trace[1853797012] 'agreement among raft nodes before linearized reading' (duration: 131.390295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:44.450556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.501983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:44.450605Z","caller":"traceutil/trace.go:171","msg":"trace[2050753808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"124.573594ms","start":"2026-02-13T02:03:44.326022Z","end":"2026-02-13T02:03:44.450596Z","steps":["trace[2050753808] 'agreement among raft nodes before linearized reading' (duration: 124.486503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:44.955739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.322448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:03:44.955847Z","caller":"traceutil/trace.go:171","msg":"trace[1833039605] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"269.760154ms","start":"2026-02-13T02:03:44.686068Z","end":"2026-02-13T02:03:44.955828Z","steps":["trace[1833039605] 'process raft request' (duration: 112.266052ms)","trace[1833039605] 'compare' (duration: 157.232246ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:45.334913Z","caller":"traceutil/trace.go:171","msg":"trace[1779705831] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"371.114696ms","start":"2026-02-13T02:03:44.963774Z","end":"2026-02-13T02:03:45.334889Z","steps":["trace[1779705831] 'process raft request' (duration: 293.604151ms)","trace[1779705831] 'compare' (duration: 77.411632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:45.335041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:44.963763Z","time spent":"371.212138ms","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:03:46.242883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.723931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:03:46.243002Z","caller":"traceutil/trace.go:171","msg":"trace[1055504764] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"902.241526ms","start":"2026-02-13T02:03:45.340737Z","end":"2026-02-13T02:03:46.242979Z","steps":["trace[1055504764] 'process raft request' (duration: 512.95081ms)","trace[1055504764] 'compare' (duration: 387.452256ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:46.243094Z","caller":"traceutil/trace.go:171","msg":"trace[944148783] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"172.683138ms","start":"2026-02-13T02:03:46.070394Z","end":"2026-02-13T02:03:46.243077Z","steps":["trace[944148783] 'process raft request' (duration: 172.594626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:46.243121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:45.340727Z","time spent":"902.322158ms","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":859,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:03:46.243454Z","caller":"traceutil/trace.go:171","msg":"trace[49318218] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"638.68647ms","start":"2026-02-13T02:03:45.604759Z","end":"2026-02-13T02:03:46.243446Z","steps":["trace[49318218] 'read index received' (duration: 248.889183ms)","trace[49318218] 'applied index is now lower than readState.Index' (duration: 389.796637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:03:46.243515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.764302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:03:46.243537Z","caller":"traceutil/trace.go:171","msg":"trace[640738490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"638.812452ms","start":"2026-02-13T02:03:45.604717Z","end":"2026-02-13T02:03:46.243529Z","steps":["trace[640738490] 'agreement among raft nodes before linearized reading' (duration: 638.778012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:46.243554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:45.604681Z","time spent":"638.869964ms","remote":"127.0.0.1:58380","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-02-13T02:03:46.243708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.344144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-bl85d\" ","response":"range_response_count:1 size:4628"} {"level":"info","ts":"2026-02-13T02:03:46.243727Z","caller":"traceutil/trace.go:171","msg":"trace[668935272] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-bl85d; range_end:; response_count:1; response_revision:779; }","duration":"103.393576ms","start":"2026-02-13T02:03:46.140328Z","end":"2026-02-13T02:03:46.243722Z","steps":["trace[668935272] 'agreement among raft nodes before linearized reading' (duration: 103.314284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:46.487654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.879213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T02:03:46.487765Z","caller":"traceutil/trace.go:171","msg":"trace[2059556776] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"237.939661ms","start":"2026-02-13T02:03:46.249806Z","end":"2026-02-13T02:03:46.487746Z","steps":["trace[2059556776] 'process raft request' (duration: 97.891894ms)","trace[2059556776] 'compare' (duration: 139.76662ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:03:46.488714Z","caller":"traceutil/trace.go:171","msg":"trace[1752490853] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"234.81158ms","start":"2026-02-13T02:03:46.253889Z","end":"2026-02-13T02:03:46.488701Z","steps":["trace[1752490853] 'process raft request' (duration: 234.737719ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:03:46.609363Z","caller":"traceutil/trace.go:171","msg":"trace[1046071003] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"113.569301ms","start":"2026-02-13T02:03:46.495775Z","end":"2026-02-13T02:03:46.609344Z","steps":["trace[1046071003] 'process raft request' (duration: 110.547263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:55.076613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:55.578041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:56.079109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:56.579365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:56.603604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000106269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:56.603682Z","caller":"traceutil/trace.go:171","msg":"trace[1529173680] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000212001s","start":"2026-02-13T02:03:54.603449Z","end":"2026-02-13T02:03:56.603661Z","steps":["trace[1529173680] 'agreement among raft nodes before linearized reading' (duration: 2.000119549s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:56.603745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:54.60344Z","time spent":"2.000296613s","remote":"127.0.0.1:58380","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-02-13T02:03:57.079688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:57.57983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:58.08044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:58.581455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:58.60466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999907686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:03:58.604721Z","caller":"traceutil/trace.go:171","msg":"trace[2103390729] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000006478s","start":"2026-02-13T02:03:56.604698Z","end":"2026-02-13T02:03:58.604704Z","steps":["trace[2103390729] 'agreement among raft nodes before linearized reading' (duration: 1.999931116s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:58.604775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:56.604683Z","time spent":"2.00007197s","remote":"127.0.0.1:58390","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-02-13T02:03:59.082338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:59.583156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:03:59.881688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000077535s","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-02-13T02:03:59.881765Z","caller":"traceutil/trace.go:171","msg":"trace[1660967533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000205778s","start":"2026-02-13T02:03:54.881541Z","end":"2026-02-13T02:03:59.881746Z","steps":["trace[1660967533] 'agreement among raft nodes before linearized reading' (duration: 5.000107286s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:03:59.881816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:54.881524Z","time spent":"5.000282789s","remote":"127.0.0.1:58602","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-02-13T02:04:00.009185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999607734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:04:00.009268Z","caller":"traceutil/trace.go:171","msg":"trace[978461760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999758108s","start":"2026-02-13T02:03:55.009492Z","end":"2026-02-13T02:04:00.00925Z","steps":["trace[978461760] 'agreement among raft nodes before linearized reading' (duration: 4.999637705s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:00.009316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:55.009474Z","time spent":"4.99983264s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T02:04:00.083537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:04:00.583912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:04:00.612131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000134677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:04:00.612176Z","caller":"traceutil/trace.go:171","msg":"trace[850994915] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000215919s","start":"2026-02-13T02:03:58.611947Z","end":"2026-02-13T02:04:00.612163Z","steps":["trace[850994915] 'agreement among raft nodes before linearized reading' (duration: 2.000152347s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:00.612207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:58.611937Z","time spent":"2.000261669s","remote":"127.0.0.1:58380","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-02-13T02:04:01.084979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:04:01.555077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:54.554489Z","time spent":"7.00058163s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-13T02:04:01.577382Z","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-02-13T02:04:01.577759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.00149244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-13T02:04:01.577965Z","caller":"traceutil/trace.go:171","msg":"trace[647549794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"7.001697405s","start":"2026-02-13T02:03:54.576202Z","end":"2026-02-13T02:04:01.5779Z","steps":["trace[647549794] 'agreement among raft nodes before linearized reading' (duration: 7.001513771s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:01.57807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:54.576189Z","time spent":"7.001868659s","remote":"127.0.0.1:58602","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\" "} {"level":"warn","ts":"2026-02-13T02:04:02.078776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:04:02.580236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590814436570616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T02:04:02.614008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000786264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:04:02.614048Z","caller":"traceutil/trace.go:171","msg":"trace[1817057118] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000862205s","start":"2026-02-13T02:04:00.613173Z","end":"2026-02-13T02:04:02.614036Z","steps":["trace[1817057118] 'agreement among raft nodes before linearized reading' (duration: 2.000811934s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:02.614086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:00.613158Z","time spent":"2.000913257s","remote":"127.0.0.1:58390","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-02-13T02:04:02.801023Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"8.246120765s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T02:04:02.896191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000873224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T02:04:02.896259Z","caller":"traceutil/trace.go:171","msg":"trace[81333809] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001001396s","start":"2026-02-13T02:04:00.895242Z","end":"2026-02-13T02:04:02.896243Z","steps":["trace[81333809] 'agreement among raft nodes before linearized reading' (duration: 2.000911964s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:02.896347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:00.89522Z","time spent":"2.001112438s","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 02:04:02 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-13T02:04:03.030243Z","caller":"traceutil/trace.go:171","msg":"trace[57083570] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"4.903105785s","start":"2026-02-13T02:03:58.12712Z","end":"2026-02-13T02:04:03.030226Z","steps":["trace[57083570] 'process raft request' (duration: 4.903057974s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.030369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:58.127085Z","time spent":"4.903211898s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T02:04:03.03059Z","caller":"traceutil/trace.go:171","msg":"trace[2027471887] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"6.662625085s","start":"2026-02-13T02:03:56.367956Z","end":"2026-02-13T02:04:03.030581Z","steps":["trace[2027471887] 'process raft request' (duration: 6.580746225s)","trace[2027471887] 'compare' (duration: 81.383039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:04:03.030634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:56.367932Z","time spent":"6.662682537s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:04:03.071164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999573649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T02:04:03.071244Z","caller":"traceutil/trace.go:171","msg":"trace[923846937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999700112s","start":"2026-02-13T02:03:58.071521Z","end":"2026-02-13T02:04:03.071221Z","steps":["trace[923846937] 'agreement among raft nodes before linearized reading' (duration: 4.99959816s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.071307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:58.071502Z","time spent":"4.999796674s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T02:04:03.502516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.285252ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-13T02:04:03.50263Z","caller":"traceutil/trace.go:171","msg":"trace[876856405] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:861; }","duration":"1.924948806s","start":"2026-02-13T02:04:01.577664Z","end":"2026-02-13T02:04:03.502612Z","steps":["trace[876856405] 'read index received' (duration: 1.223844037s)","trace[876856405] 'applied index is now lower than readState.Index' (duration: 701.103079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:04:03.503213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.139895058s","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-02-13T02:04:03.50326Z","caller":"traceutil/trace.go:171","msg":"trace[901318134] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:829; }","duration":"2.13999795s","start":"2026-02-13T02:04:01.363251Z","end":"2026-02-13T02:04:03.503249Z","steps":["trace[901318134] 'agreement among raft nodes before linearized reading' (duration: 2.139916028s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.503295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:01.363233Z","time spent":"2.140052232s","remote":"127.0.0.1:58552","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-02-13T02:04:03.503553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.110814481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-02-13T02:04:03.503589Z","caller":"traceutil/trace.go:171","msg":"trace[987902011] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:829; }","duration":"3.110877063s","start":"2026-02-13T02:04:00.392704Z","end":"2026-02-13T02:04:03.503581Z","steps":["trace[987902011] 'agreement among raft nodes before linearized reading' (duration: 3.1107524s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.503617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:00.392631Z","time spent":"3.110979805s","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4397,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-13T02:04:03.503796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.70965945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T02:04:03.503831Z","caller":"traceutil/trace.go:171","msg":"trace[1949441094] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:829; }","duration":"3.709728261s","start":"2026-02-13T02:03:59.794095Z","end":"2026-02-13T02:04:03.503823Z","steps":["trace[1949441094] 'agreement among raft nodes before linearized reading' (duration: 3.70964613s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.503862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:59.794079Z","time spent":"3.709777092s","remote":"127.0.0.1:58398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.209\" "} {"level":"warn","ts":"2026-02-13T02:04:03.504003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.705969377s","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-02-13T02:04:03.504037Z","caller":"traceutil/trace.go:171","msg":"trace[1952545082] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:829; }","duration":"4.706038159s","start":"2026-02-13T02:03:58.797991Z","end":"2026-02-13T02:04:03.504029Z","steps":["trace[1952545082] 'agreement among raft nodes before linearized reading' (duration: 4.705983328s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.504639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.799660979s","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-02-13T02:04:03.504685Z","caller":"traceutil/trace.go:171","msg":"trace[1683493759] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:829; }","duration":"4.799757151s","start":"2026-02-13T02:03:58.704917Z","end":"2026-02-13T02:04:03.504674Z","steps":["trace[1683493759] 'agreement among raft nodes before linearized reading' (duration: 4.799675719s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.504723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:58.704903Z","time spent":"4.799807772s","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"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-02-13T02:04:03.505019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.896787425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1893ac007e32e11b\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-13T02:04:03.505061Z","caller":"traceutil/trace.go:171","msg":"trace[250663506] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1893ac007e32e11b; range_end:; response_count:1; response_revision:829; }","duration":"4.896867857s","start":"2026-02-13T02:03:58.608181Z","end":"2026-02-13T02:04:03.505049Z","steps":["trace[250663506] 'agreement among raft nodes before linearized reading' (duration: 4.896761535s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.505095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:58.608159Z","time spent":"4.896929168s","remote":"127.0.0.1:58436","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.1893ac007e32e11b\" "} {"level":"warn","ts":"2026-02-13T02:04:03.505363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.219330313s","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-02-13T02:04:03.505403Z","caller":"traceutil/trace.go:171","msg":"trace[1559961892] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:829; }","duration":"6.219403875s","start":"2026-02-13T02:03:57.28599Z","end":"2026-02-13T02:04:03.505394Z","steps":["trace[1559961892] 'agreement among raft nodes before linearized reading' (duration: 6.219301102s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.505432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:57.285943Z","time spent":"6.219481026s","remote":"127.0.0.1:58746","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-02-13T02:04:03.505574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.746939537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:04:03.505651Z","caller":"traceutil/trace.go:171","msg":"trace[1034725969] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:829; }","duration":"8.74704288s","start":"2026-02-13T02:03:54.758598Z","end":"2026-02-13T02:04:03.505641Z","steps":["trace[1034725969] 'agreement among raft nodes before linearized reading' (duration: 8.746952848s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.505685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:54.758583Z","time spent":"8.747091691s","remote":"127.0.0.1:58844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:04:03.505984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620812952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-13T02:04:03.506035Z","caller":"traceutil/trace.go:171","msg":"trace[1100416305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:829; }","duration":"1.620896864s","start":"2026-02-13T02:04:01.885125Z","end":"2026-02-13T02:04:03.506022Z","steps":["trace[1100416305] 'agreement among raft nodes before linearized reading' (duration: 1.62070418s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.506096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:01.885108Z","time spent":"1.620980306s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T02:04:03.506922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:03:58.79796Z","time spent":"4.706102141s","remote":"127.0.0.1:58670","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":"warn","ts":"2026-02-13T02:04:03.506991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.905504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:04:03.507087Z","caller":"traceutil/trace.go:171","msg":"trace[805586078] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:829; }","duration":"710.107518ms","start":"2026-02-13T02:04:02.796955Z","end":"2026-02-13T02:04:03.507062Z","steps":["trace[805586078] 'agreement among raft nodes before linearized reading' (duration: 709.906104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.50715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:02.796933Z","time spent":"710.200372ms","remote":"127.0.0.1:55298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:04:03.507465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.713795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:04:03.507509Z","caller":"traceutil/trace.go:171","msg":"trace[1938970506] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:829; }","duration":"778.790837ms","start":"2026-02-13T02:04:02.728705Z","end":"2026-02-13T02:04:03.507496Z","steps":["trace[1938970506] 'agreement among raft nodes before linearized reading' (duration: 778.717216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.507549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:02.728694Z","time spent":"778.842798ms","remote":"127.0.0.1:58438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:04:03.507761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.346856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:04:03.507802Z","caller":"traceutil/trace.go:171","msg":"trace[1385875185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"886.412977ms","start":"2026-02-13T02:04:02.621376Z","end":"2026-02-13T02:04:03.507789Z","steps":["trace[1385875185] 'agreement among raft nodes before linearized reading' (duration: 886.349675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.507845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:02.621361Z","time spent":"886.468609ms","remote":"127.0.0.1:58380","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-02-13T02:04:03.50805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401053054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:04:03.508097Z","caller":"traceutil/trace.go:171","msg":"trace[580774287] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:829; }","duration":"1.401121915s","start":"2026-02-13T02:04:02.106968Z","end":"2026-02-13T02:04:03.50809Z","steps":["trace[580774287] 'agreement among raft nodes before linearized reading' (duration: 1.401054814s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.508046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215236908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T02:04:03.508118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:02.106924Z","time spent":"1.401187957s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-02-13T02:04:03.508144Z","caller":"traceutil/trace.go:171","msg":"trace[928169479] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:829; }","duration":"1.215369101s","start":"2026-02-13T02:04:02.29276Z","end":"2026-02-13T02:04:03.508129Z","steps":["trace[928169479] 'agreement among raft nodes before linearized reading' (duration: 1.215248339s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.508195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:02.292739Z","time spent":"1.215439962s","remote":"127.0.0.1:58582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-13T02:04:03.508245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497317934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-13T02:04:03.508265Z","caller":"traceutil/trace.go:171","msg":"trace[1554636286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:829; }","duration":"1.497374545s","start":"2026-02-13T02:04:02.010883Z","end":"2026-02-13T02:04:03.508257Z","steps":["trace[1554636286] 'agreement among raft nodes before linearized reading' (duration: 1.497321944s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:03.508281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:02.010862Z","time spent":"1.497414875s","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T02:04:07.835402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.246669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-13T02:04:07.838084Z","caller":"traceutil/trace.go:171","msg":"trace[2034793399] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:868; }","duration":"151.982629ms","start":"2026-02-13T02:04:07.686078Z","end":"2026-02-13T02:04:07.838061Z","steps":["trace[2034793399] 'range keys from in-memory index tree' (duration: 149.120356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:07.838605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.17688ms","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-02-13T02:04:07.838778Z","caller":"traceutil/trace.go:171","msg":"trace[820873937] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:868; }","duration":"146.358794ms","start":"2026-02-13T02:04:07.69241Z","end":"2026-02-13T02:04:07.838769Z","steps":["trace[820873937] 'range keys from in-memory index tree' (duration: 146.16633ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:08.236554Z","caller":"traceutil/trace.go:171","msg":"trace[1625642599] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:906; }","duration":"306.831401ms","start":"2026-02-13T02:04:07.929691Z","end":"2026-02-13T02:04:08.236522Z","steps":["trace[1625642599] 'read index received' (duration: 44.515324ms)","trace[1625642599] 'applied index is now lower than readState.Index' (duration: 262.314927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:04:08.236932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.22134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-13T02:04:08.236983Z","caller":"traceutil/trace.go:171","msg":"trace[706063924] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:870; }","duration":"307.282081ms","start":"2026-02-13T02:04:07.929689Z","end":"2026-02-13T02:04:08.236971Z","steps":["trace[706063924] 'agreement among raft nodes before linearized reading' (duration: 307.099997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:08.237015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:07.929671Z","time spent":"307.339503ms","remote":"127.0.0.1:58548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-13T02:04:08.237145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.383603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-13T02:04:08.237182Z","caller":"traceutil/trace.go:171","msg":"trace[668920972] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"311.875703ms","start":"2026-02-13T02:04:07.9253Z","end":"2026-02-13T02:04:08.237176Z","steps":["trace[668920972] 'process raft request' (duration: 311.052835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:08.241305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:07.925296Z","time spent":"315.922241ms","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T02:04:08.23729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.872427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-13T02:04:08.241929Z","caller":"traceutil/trace.go:171","msg":"trace[667507820] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:870; }","duration":"256.50347ms","start":"2026-02-13T02:04:07.98541Z","end":"2026-02-13T02:04:08.241914Z","steps":["trace[667507820] 'agreement among raft nodes before linearized reading' (duration: 251.856537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:08.237325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.361143ms","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-02-13T02:04:08.242392Z","caller":"traceutil/trace.go:171","msg":"trace[185897751] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:870; }","duration":"307.427685ms","start":"2026-02-13T02:04:07.934951Z","end":"2026-02-13T02:04:08.242379Z","steps":["trace[185897751] 'agreement among raft nodes before linearized reading' (duration: 302.360433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:08.242679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:07.934946Z","time spent":"307.717451ms","remote":"127.0.0.1:58548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-02-13T02:04:08.241022Z","caller":"traceutil/trace.go:171","msg":"trace[1127865954] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:870; }","duration":"311.28893ms","start":"2026-02-13T02:04:07.929716Z","end":"2026-02-13T02:04:08.241005Z","steps":["trace[1127865954] 'agreement among raft nodes before linearized reading' (duration: 307.241651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:08.244038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:07.929713Z","time spent":"314.307397ms","remote":"127.0.0.1:58548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-13T02:04:09.7964Z","caller":"traceutil/trace.go:171","msg":"trace[2060721698] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"107.144979ms","start":"2026-02-13T02:04:09.689236Z","end":"2026-02-13T02:04:09.796381Z","steps":["trace[2060721698] 'process raft request' (duration: 11.824422ms)","trace[2060721698] 'compare' (duration: 94.796974ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:04:09.918018Z","caller":"traceutil/trace.go:171","msg":"trace[1284045902] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"102.69995ms","start":"2026-02-13T02:04:09.815293Z","end":"2026-02-13T02:04:09.917993Z","steps":["trace[1284045902] 'process raft request' (duration: 91.750678ms)","trace[1284045902] 'compare' (duration: 10.87112ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:04:11.876016Z","caller":"traceutil/trace.go:171","msg":"trace[1603061776] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"180.783765ms","start":"2026-02-13T02:04:11.695211Z","end":"2026-02-13T02:04:11.875995Z","steps":["trace[1603061776] 'process raft request' (duration: 171.890039ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:14.083256Z","caller":"traceutil/trace.go:171","msg":"trace[262653515] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"172.82391ms","start":"2026-02-13T02:04:13.910412Z","end":"2026-02-13T02:04:14.083236Z","steps":["trace[262653515] 'process raft request' (duration: 73.822712ms)","trace[262653515] 'compare' (duration: 98.937567ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:04:15.017197Z","caller":"traceutil/trace.go:171","msg":"trace[1320556228] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"220.281077ms","start":"2026-02-13T02:04:14.7969Z","end":"2026-02-13T02:04:15.017181Z","steps":["trace[1320556228] 'process raft request' (duration: 220.184995ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:17.248657Z","caller":"traceutil/trace.go:171","msg":"trace[921685701] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"226.316932ms","start":"2026-02-13T02:04:17.022325Z","end":"2026-02-13T02:04:17.248642Z","steps":["trace[921685701] 'process raft request' (duration: 226.202229ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:20.284259Z","caller":"traceutil/trace.go:171","msg":"trace[557083092] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"152.226804ms","start":"2026-02-13T02:04:20.132008Z","end":"2026-02-13T02:04:20.284235Z","steps":["trace[557083092] 'process raft request' (duration: 152.0521ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:20.504757Z","caller":"traceutil/trace.go:171","msg":"trace[1831812728] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:965; }","duration":"363.512704ms","start":"2026-02-13T02:04:20.141229Z","end":"2026-02-13T02:04:20.504741Z","steps":["trace[1831812728] 'read index received' (duration: 142.925278ms)","trace[1831812728] 'applied index is now lower than readState.Index' (duration: 220.586906ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:04:20.504828Z","caller":"traceutil/trace.go:171","msg":"trace[300632712] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"372.442191ms","start":"2026-02-13T02:04:20.13238Z","end":"2026-02-13T02:04:20.504822Z","steps":["trace[300632712] 'process raft request' (duration: 343.496712ms)","trace[300632712] 'compare' (duration: 28.797876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:04:20.504889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:20.132374Z","time spent":"372.467312ms","remote":"127.0.0.1:58602","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-02-13T02:04:20.505042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.427044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:5020"} {"level":"info","ts":"2026-02-13T02:04:20.505106Z","caller":"traceutil/trace.go:171","msg":"trace[2123528727] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:925; }","duration":"102.526225ms","start":"2026-02-13T02:04:20.402563Z","end":"2026-02-13T02:04:20.505089Z","steps":["trace[2123528727] 'agreement among raft nodes before linearized reading' (duration: 102.358401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:20.505116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.886432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-13T02:04:20.505141Z","caller":"traceutil/trace.go:171","msg":"trace[1446707366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:925; }","duration":"363.923893ms","start":"2026-02-13T02:04:20.141209Z","end":"2026-02-13T02:04:20.505133Z","steps":["trace[1446707366] 'agreement among raft nodes before linearized reading' (duration: 363.845311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:20.505156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:20.141178Z","time spent":"363.975313ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T02:04:21.338436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.742946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:04:21.338505Z","caller":"traceutil/trace.go:171","msg":"trace[1021655449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"733.855438ms","start":"2026-02-13T02:04:20.604632Z","end":"2026-02-13T02:04:21.338488Z","steps":["trace[1021655449] 'range keys from in-memory index tree' (duration: 733.679025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:21.338558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:20.604616Z","time spent":"733.93386ms","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T02:04:21.59687Z","caller":"traceutil/trace.go:171","msg":"trace[1764677306] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"250.913335ms","start":"2026-02-13T02:04:21.345932Z","end":"2026-02-13T02:04:21.596846Z","steps":["trace[1764677306] 'process raft request' (duration: 250.782682ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:26.649092Z","caller":"traceutil/trace.go:171","msg":"trace[746855386] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"106.290838ms","start":"2026-02-13T02:04:26.542782Z","end":"2026-02-13T02:04:26.649073Z","steps":["trace[746855386] 'process raft request' (duration: 106.025692ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:26.83353Z","caller":"traceutil/trace.go:171","msg":"trace[26700295] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"180.496858ms","start":"2026-02-13T02:04:26.653018Z","end":"2026-02-13T02:04:26.833515Z","steps":["trace[26700295] 'process raft request' (duration: 180.467998ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:26.833748Z","caller":"traceutil/trace.go:171","msg":"trace[373097437] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"180.817555ms","start":"2026-02-13T02:04:26.652925Z","end":"2026-02-13T02:04:26.833742Z","steps":["trace[373097437] 'process raft request' (duration: 160.67622ms)","trace[373097437] 'compare' (duration: 19.631914ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:04:28.425067Z","caller":"traceutil/trace.go:171","msg":"trace[170216372] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"321.087985ms","start":"2026-02-13T02:04:28.103965Z","end":"2026-02-13T02:04:28.425053Z","steps":["trace[170216372] 'process raft request' (duration: 321.006543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:28.425154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:28.103947Z","time spent":"321.161167ms","remote":"127.0.0.1:58602","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-02-13T02:04:29.265041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.683119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:04:29.265124Z","caller":"traceutil/trace.go:171","msg":"trace[1279521408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"664.803882ms","start":"2026-02-13T02:04:28.600298Z","end":"2026-02-13T02:04:29.265102Z","steps":["trace[1279521408] 'range keys from in-memory index tree' (duration: 664.630899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:29.265166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:28.600285Z","time spent":"664.871613ms","remote":"127.0.0.1:58390","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-02-13T02:04:29.26569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.003175ms","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-02-13T02:04:29.265721Z","caller":"traceutil/trace.go:171","msg":"trace[1801563411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"612.065237ms","start":"2026-02-13T02:04:28.653647Z","end":"2026-02-13T02:04:29.265712Z","steps":["trace[1801563411] 'range keys from in-memory index tree' (duration: 611.714249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:29.265746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:28.653634Z","time spent":"612.106178ms","remote":"127.0.0.1:58602","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-02-13T02:04:29.265812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.520581ms","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-02-13T02:04:29.26587Z","caller":"traceutil/trace.go:171","msg":"trace[1087690750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"428.618542ms","start":"2026-02-13T02:04:28.837238Z","end":"2026-02-13T02:04:29.265856Z","steps":["trace[1087690750] 'range keys from in-memory index tree' (duration: 428.365087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:29.265897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:28.837225Z","time spent":"428.666494ms","remote":"127.0.0.1:58602","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-02-13T02:04:29.266248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.93969ms","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-02-13T02:04:29.266269Z","caller":"traceutil/trace.go:171","msg":"trace[304609523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"428.96856ms","start":"2026-02-13T02:04:28.837294Z","end":"2026-02-13T02:04:29.266263Z","steps":["trace[304609523] 'range keys from in-memory index tree' (duration: 428.890169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:29.266288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:28.837291Z","time spent":"428.992011ms","remote":"127.0.0.1:58602","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-02-13T02:04:30.302481Z","caller":"traceutil/trace.go:171","msg":"trace[584812382] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"265.382455ms","start":"2026-02-13T02:04:30.037075Z","end":"2026-02-13T02:04:30.302458Z","steps":["trace[584812382] 'read index received' (duration: 265.247162ms)","trace[584812382] 'applied index is now lower than readState.Index' (duration: 134.233µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:04:30.302516Z","caller":"traceutil/trace.go:171","msg":"trace[1318001389] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"290.356277ms","start":"2026-02-13T02:04:30.012115Z","end":"2026-02-13T02:04:30.302471Z","steps":["trace[1318001389] 'process raft request' (duration: 290.171883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:30.302653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.553679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:04:30.302689Z","caller":"traceutil/trace.go:171","msg":"trace[140975664] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:953; }","duration":"265.60891ms","start":"2026-02-13T02:04:30.037069Z","end":"2026-02-13T02:04:30.302678Z","steps":["trace[140975664] 'agreement among raft nodes before linearized reading' (duration: 265.473067ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:32.094154Z","caller":"traceutil/trace.go:171","msg":"trace[1905700332] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"284.127299ms","start":"2026-02-13T02:04:31.810008Z","end":"2026-02-13T02:04:32.094136Z","steps":["trace[1905700332] 'read index received' (duration: 283.954455ms)","trace[1905700332] 'applied index is now lower than readState.Index' (duration: 171.994µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T02:04:32.094408Z","caller":"traceutil/trace.go:171","msg":"trace[1192140468] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"361.609441ms","start":"2026-02-13T02:04:31.73279Z","end":"2026-02-13T02:04:32.0944Z","steps":["trace[1192140468] 'process raft request' (duration: 361.221682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:32.09448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:31.732781Z","time spent":"361.646542ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:04:32.094516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.087811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:04:32.09458Z","caller":"traceutil/trace.go:171","msg":"trace[1395152227] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:958; }","duration":"271.185282ms","start":"2026-02-13T02:04:31.823378Z","end":"2026-02-13T02:04:32.094563Z","steps":["trace[1395152227] 'agreement among raft nodes before linearized reading' (duration: 271.05471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:32.094708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.695091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:04:32.094735Z","caller":"traceutil/trace.go:171","msg":"trace[1757648674] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:958; }","duration":"284.745882ms","start":"2026-02-13T02:04:31.809982Z","end":"2026-02-13T02:04:32.094728Z","steps":["trace[1757648674] 'agreement among raft nodes before linearized reading' (duration: 284.697371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:36.28245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.145878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-13T02:04:36.282531Z","caller":"traceutil/trace.go:171","msg":"trace[189207724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"145.276571ms","start":"2026-02-13T02:04:36.137235Z","end":"2026-02-13T02:04:36.282512Z","steps":["trace[189207724] 'range keys from in-memory index tree' (duration: 144.948874ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:36.413739Z","caller":"traceutil/trace.go:171","msg":"trace[564683107] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"119.785897ms","start":"2026-02-13T02:04:36.293938Z","end":"2026-02-13T02:04:36.413724Z","steps":["trace[564683107] 'process raft request' (duration: 119.695346ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:40.940422Z","caller":"traceutil/trace.go:171","msg":"trace[856809618] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"150.973866ms","start":"2026-02-13T02:04:40.789424Z","end":"2026-02-13T02:04:40.940398Z","steps":["trace[856809618] 'process raft request' (duration: 150.817663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:45.248168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.02839ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-13T02:04:46.81559Z","caller":"traceutil/trace.go:171","msg":"trace[732216143] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"356.907207ms","start":"2026-02-13T02:04:46.458658Z","end":"2026-02-13T02:04:46.815565Z","steps":["trace[732216143] 'process raft request' (duration: 356.702112ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:46.816127Z","caller":"traceutil/trace.go:171","msg":"trace[937031480] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1082; }","duration":"212.472304ms","start":"2026-02-13T02:04:46.603644Z","end":"2026-02-13T02:04:46.816116Z","steps":["trace[937031480] 'read index received' (duration: 212.467544ms)","trace[937031480] 'applied index is now lower than readState.Index' (duration: 3.88µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T02:04:46.815979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:46.458647Z","time spent":"357.01478ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:04:46.816214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.565106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T02:04:46.816238Z","caller":"traceutil/trace.go:171","msg":"trace[915582158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"212.615938ms","start":"2026-02-13T02:04:46.603614Z","end":"2026-02-13T02:04:46.81623Z","steps":["trace[915582158] 'agreement among raft nodes before linearized reading' (duration: 212.558347ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:46.819364Z","caller":"traceutil/trace.go:171","msg":"trace[14739067] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"171.139242ms","start":"2026-02-13T02:04:46.648216Z","end":"2026-02-13T02:04:46.819355Z","steps":["trace[14739067] 'process raft request' (duration: 171.077991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:46.819523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.349549ms","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-02-13T02:04:46.819545Z","caller":"traceutil/trace.go:171","msg":"trace[463279884] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1035; }","duration":"148.41337ms","start":"2026-02-13T02:04:46.671126Z","end":"2026-02-13T02:04:46.819539Z","steps":["trace[463279884] 'agreement among raft nodes before linearized reading' (duration: 148.349339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:49.774025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.327368ms","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-02-13T02:04:49.774076Z","caller":"traceutil/trace.go:171","msg":"trace[1233305428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"191.41139ms","start":"2026-02-13T02:04:49.582653Z","end":"2026-02-13T02:04:49.774065Z","steps":["trace[1233305428] 'range keys from in-memory index tree' (duration: 191.226716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:49.774217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.340209ms","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-02-13T02:04:49.774236Z","caller":"traceutil/trace.go:171","msg":"trace[1985247705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1049; }","duration":"187.389971ms","start":"2026-02-13T02:04:49.58684Z","end":"2026-02-13T02:04:49.77423Z","steps":["trace[1985247705] 'range keys from in-memory index tree' (duration: 187.283868ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:58.841573Z","caller":"traceutil/trace.go:171","msg":"trace[1768019249] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"307.492546ms","start":"2026-02-13T02:04:58.534056Z","end":"2026-02-13T02:04:58.841548Z","steps":["trace[1768019249] 'process raft request' (duration: 307.357593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:04:58.841734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T02:04:58.53404Z","time spent":"307.602718ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T02:04:59.255162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.9243ms","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-02-13T02:04:59.255238Z","caller":"traceutil/trace.go:171","msg":"trace[1076854652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"191.037931ms","start":"2026-02-13T02:04:59.064182Z","end":"2026-02-13T02:04:59.25522Z","steps":["trace[1076854652] 'range keys from in-memory index tree' (duration: 190.773036ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:04:59.95547Z","caller":"traceutil/trace.go:171","msg":"trace[462877501] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"110.533983ms","start":"2026-02-13T02:04:59.844917Z","end":"2026-02-13T02:04:59.955451Z","steps":["trace[462877501] 'process raft request' (duration: 110.095474ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:05:04.841974Z","caller":"traceutil/trace.go:171","msg":"trace[324101352] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"137.296904ms","start":"2026-02-13T02:05:04.704657Z","end":"2026-02-13T02:05:04.841954Z","steps":["trace[324101352] 'process raft request' (duration: 137.191432ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:05:04.863148Z","caller":"traceutil/trace.go:171","msg":"trace[1981652001] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"148.362909ms","start":"2026-02-13T02:05:04.714773Z","end":"2026-02-13T02:05:04.863136Z","steps":["trace[1981652001] 'process raft request' (duration: 148.188735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T02:05:05.613619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.105524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T02:05:05.614718Z","caller":"traceutil/trace.go:171","msg":"trace[588825827] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1133; }","duration":"110.190155ms","start":"2026-02-13T02:05:05.503469Z","end":"2026-02-13T02:05:05.613659Z","steps":["trace[588825827] 'count revisions from in-memory index tree' (duration: 110.040013ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T02:07:54.873075Z","caller":"traceutil/trace.go:171","msg":"trace[1753681720] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"156.071966ms","start":"2026-02-13T02:07:54.716952Z","end":"2026-02-13T02:07:54.873024Z","steps":["trace[1753681720] 'process raft request' (duration: 155.949853ms)"],"step_count":1}