{"level":"warn","ts":"2026-02-18T02:47:40.775907Z","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-18T02:47:40.776045Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.232:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.232:2380","--initial-cluster=instance=https://199.19.213.232:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.232:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.232: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-18T02:47:40.776143Z","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-18T02:47:40.776182Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.232:2380"]} {"level":"info","ts":"2026-02-18T02:47:40.776229Z","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-18T02:47:40.776903Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"]} {"level":"info","ts":"2026-02-18T02:47:40.781951Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.232:2380"],"listen-peer-urls":["https://199.19.213.232:2380"],"advertise-client-urls":["https://199.19.213.232:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.232: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-18T02:47:40.786847Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.638701ms"} {"level":"info","ts":"2026-02-18T02:47:40.797915Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"20a2700e013b1fa5","cluster-id":"b7b35e617ebc7ac7"} {"level":"info","ts":"2026-02-18T02:47:40.798032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T02:47:40.79806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became follower at term 0"} {"level":"info","ts":"2026-02-18T02:47:40.798098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 20a2700e013b1fa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T02:47:40.798115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became follower at term 1"} {"level":"info","ts":"2026-02-18T02:47:40.798157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=(2351565160893325221)"} {"level":"warn","ts":"2026-02-18T02:47:40.811554Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T02:47:40.81842Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T02:47:40.83106Z","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-18T02:47:40.835907Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"20a2700e013b1fa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T02:47:40.840285Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"20a2700e013b1fa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T02:47:40.840357Z","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-18T02:47:40.840443Z","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-18T02:47:40.840451Z","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-18T02:47:40.84081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 switched to configuration voters=(2351565160893325221)"} {"level":"info","ts":"2026-02-18T02:47:40.840982Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7b35e617ebc7ac7","local-member-id":"20a2700e013b1fa5","added-peer-id":"20a2700e013b1fa5","added-peer-peer-urls":["https://199.19.213.232:2380"]} {"level":"info","ts":"2026-02-18T02:47:40.842521Z","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-18T02:47:40.842702Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.232:2380"} {"level":"info","ts":"2026-02-18T02:47:40.842719Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.232:2380"} {"level":"info","ts":"2026-02-18T02:47:40.843517Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"20a2700e013b1fa5","initial-advertise-peer-urls":["https://199.19.213.232:2380"],"listen-peer-urls":["https://199.19.213.232:2380"],"advertise-client-urls":["https://199.19.213.232:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.232:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T02:47:40.843646Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T02:47:41.399005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T02:47:41.39904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T02:47:41.399057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 received MsgPreVoteResp from 20a2700e013b1fa5 at term 1"} {"level":"info","ts":"2026-02-18T02:47:41.399067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became candidate at term 2"} {"level":"info","ts":"2026-02-18T02:47:41.399072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 received MsgVoteResp from 20a2700e013b1fa5 at term 2"} {"level":"info","ts":"2026-02-18T02:47:41.399086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"20a2700e013b1fa5 became leader at term 2"} {"level":"info","ts":"2026-02-18T02:47:41.399093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 20a2700e013b1fa5 elected leader 20a2700e013b1fa5 at term 2"} {"level":"info","ts":"2026-02-18T02:47:41.402258Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:47:41.407111Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"20a2700e013b1fa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.232:2379]}","request-path":"/0/members/20a2700e013b1fa5/attributes","cluster-id":"b7b35e617ebc7ac7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T02:47:41.40762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:47:41.407843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:47:41.413079Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T02:47:41.413094Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T02:47:41.413254Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7b35e617ebc7ac7","local-member-id":"20a2700e013b1fa5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:47:41.413338Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:47:41.413357Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:47:41.414533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T02:47:41.415417Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.232:2379"} {"level":"info","ts":"2026-02-18T02:47:46.997322Z","caller":"traceutil/trace.go:171","msg":"trace[1912997371] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"108.133752ms","start":"2026-02-18T02:47:46.889169Z","end":"2026-02-18T02:47:46.997303Z","steps":["trace[1912997371] 'process raft request' (duration: 24.077674ms)","trace[1912997371] 'compare' (duration: 83.963296ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:47:47.527303Z","caller":"traceutil/trace.go:171","msg":"trace[766136393] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"113.171181ms","start":"2026-02-18T02:47:47.414108Z","end":"2026-02-18T02:47:47.527279Z","steps":["trace[766136393] 'process raft request' (duration: 112.996688ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.527311Z","caller":"traceutil/trace.go:171","msg":"trace[1527015337] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:299; }","duration":"113.039499ms","start":"2026-02-18T02:47:47.414249Z","end":"2026-02-18T02:47:47.527289Z","steps":["trace[1527015337] 'read index received' (duration: 32.331243ms)","trace[1527015337] 'applied index is now lower than readState.Index' (duration: 80.707366ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:47:47.527434Z","caller":"traceutil/trace.go:171","msg":"trace[1153971028] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"113.273594ms","start":"2026-02-18T02:47:47.414149Z","end":"2026-02-18T02:47:47.527422Z","steps":["trace[1153971028] 'process raft request' (duration: 113.014398ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.527516Z","caller":"traceutil/trace.go:171","msg":"trace[887427339] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"115.894651ms","start":"2026-02-18T02:47:47.411609Z","end":"2026-02-18T02:47:47.527503Z","steps":["trace[887427339] 'process raft request' (duration: 93.75231ms)","trace[887427339] 'compare' (duration: 21.444196ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:47:47.527616Z","caller":"traceutil/trace.go:171","msg":"trace[1601567825] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"113.331236ms","start":"2026-02-18T02:47:47.414276Z","end":"2026-02-18T02:47:47.527607Z","steps":["trace[1601567825] 'process raft request' (duration: 112.925837ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.527741Z","caller":"traceutil/trace.go:171","msg":"trace[401837900] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"113.082299ms","start":"2026-02-18T02:47:47.414648Z","end":"2026-02-18T02:47:47.52773Z","steps":["trace[401837900] 'process raft request' (duration: 112.583598ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.527851Z","caller":"traceutil/trace.go:171","msg":"trace[1201450934] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"110.34307ms","start":"2026-02-18T02:47:47.4175Z","end":"2026-02-18T02:47:47.527843Z","steps":["trace[1201450934] 'process raft request' (duration: 109.759027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:47.528013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.734174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-18T02:47:47.528048Z","caller":"traceutil/trace.go:171","msg":"trace[731593110] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:298; }","duration":"113.802775ms","start":"2026-02-18T02:47:47.414238Z","end":"2026-02-18T02:47:47.528041Z","steps":["trace[731593110] 'agreement among raft nodes before linearized reading' (duration: 113.723413ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.754416Z","caller":"traceutil/trace.go:171","msg":"trace[1042547371] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"124.890976ms","start":"2026-02-18T02:47:47.629498Z","end":"2026-02-18T02:47:47.754389Z","steps":["trace[1042547371] 'process raft request' (duration: 123.987966ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.755802Z","caller":"traceutil/trace.go:171","msg":"trace[1066188678] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"122.539444ms","start":"2026-02-18T02:47:47.633252Z","end":"2026-02-18T02:47:47.755791Z","steps":["trace[1066188678] 'process raft request' (duration: 120.408939ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.757182Z","caller":"traceutil/trace.go:171","msg":"trace[1342300229] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"123.786292ms","start":"2026-02-18T02:47:47.633386Z","end":"2026-02-18T02:47:47.757172Z","steps":["trace[1342300229] 'process raft request' (duration: 122.36291ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.757482Z","caller":"traceutil/trace.go:171","msg":"trace[468560201] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"123.509195ms","start":"2026-02-18T02:47:47.633966Z","end":"2026-02-18T02:47:47.757475Z","steps":["trace[468560201] 'process raft request' (duration: 123.415283ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.757754Z","caller":"traceutil/trace.go:171","msg":"trace[1411335575] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"123.762941ms","start":"2026-02-18T02:47:47.633982Z","end":"2026-02-18T02:47:47.757745Z","steps":["trace[1411335575] 'process raft request' (duration: 123.446264ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:47.757955Z","caller":"traceutil/trace.go:171","msg":"trace[897356463] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"119.278154ms","start":"2026-02-18T02:47:47.638669Z","end":"2026-02-18T02:47:47.757947Z","steps":["trace[897356463] 'process raft request' (duration: 119.031158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:47.929319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.686248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:47:47.929396Z","caller":"traceutil/trace.go:171","msg":"trace[711812108] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:315; }","duration":"139.799161ms","start":"2026-02-18T02:47:47.789576Z","end":"2026-02-18T02:47:47.929375Z","steps":["trace[711812108] 'range keys from in-memory index tree' (duration: 139.615306ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:48.096027Z","caller":"traceutil/trace.go:171","msg":"trace[440700288] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"107.55068ms","start":"2026-02-18T02:47:47.988461Z","end":"2026-02-18T02:47:48.096012Z","steps":["trace[440700288] 'process raft request' (duration: 107.445028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:48.524556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.996223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:47:48.524626Z","caller":"traceutil/trace.go:171","msg":"trace[484559301] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:324; }","duration":"224.823709ms","start":"2026-02-18T02:47:48.29979Z","end":"2026-02-18T02:47:48.524614Z","steps":["trace[484559301] 'read index received' (duration: 40.371µs)","trace[484559301] 'applied index is now lower than readState.Index' (duration: 224.782578ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:47:48.524744Z","caller":"traceutil/trace.go:171","msg":"trace[2090909838] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"386.163738ms","start":"2026-02-18T02:47:48.138573Z","end":"2026-02-18T02:47:48.524736Z","steps":["trace[2090909838] 'process raft request' (duration: 160.671714ms)","trace[2090909838] 'compare' (duration: 224.899261ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:48.524779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:47:48.138553Z","time spent":"386.211169ms","remote":"127.0.0.1:38512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:47:48.524929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.135536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T02:47:48.524947Z","caller":"traceutil/trace.go:171","msg":"trace[25460340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:317; }","duration":"225.187187ms","start":"2026-02-18T02:47:48.299754Z","end":"2026-02-18T02:47:48.524941Z","steps":["trace[25460340] 'agreement among raft nodes before linearized reading' (duration: 225.128526ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:48.546067Z","caller":"traceutil/trace.go:171","msg":"trace[1741887235] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"163.641929ms","start":"2026-02-18T02:47:48.382406Z","end":"2026-02-18T02:47:48.546048Z","steps":["trace[1741887235] 'process raft request' (duration: 163.551187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:48.546416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.029837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-02-18T02:47:48.546456Z","caller":"traceutil/trace.go:171","msg":"trace[1137502062] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:317; }","duration":"164.103489ms","start":"2026-02-18T02:47:48.382343Z","end":"2026-02-18T02:47:48.546446Z","steps":["trace[1137502062] 'agreement among raft nodes before linearized reading' (duration: 163.993067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:48.963232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.037777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:47:48.963296Z","caller":"traceutil/trace.go:171","msg":"trace[95098603] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"217.722764ms","start":"2026-02-18T02:47:48.745563Z","end":"2026-02-18T02:47:48.963286Z","steps":["trace[95098603] 'read index received' (duration: 42.546555ms)","trace[95098603] 'applied index is now lower than readState.Index' (duration: 175.175619ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:48.963352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.784575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-18T02:47:48.963366Z","caller":"traceutil/trace.go:171","msg":"trace[1316021081] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:324; }","duration":"217.799855ms","start":"2026-02-18T02:47:48.74556Z","end":"2026-02-18T02:47:48.96336Z","steps":["trace[1316021081] 'agreement among raft nodes before linearized reading' (duration: 217.750394ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:48.963443Z","caller":"traceutil/trace.go:171","msg":"trace[1069169536] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"253.026833ms","start":"2026-02-18T02:47:48.710392Z","end":"2026-02-18T02:47:48.963419Z","steps":["trace[1069169536] 'process raft request' (duration: 77.763111ms)","trace[1069169536] 'compare' (duration: 174.945925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:58.112037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280400785368678806,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:47:58.400742Z","caller":"traceutil/trace.go:171","msg":"trace[1558170223] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"789.434828ms","start":"2026-02-18T02:47:57.611286Z","end":"2026-02-18T02:47:58.40072Z","steps":["trace[1558170223] 'read index received' (duration: 789.208533ms)","trace[1558170223] 'applied index is now lower than readState.Index' (duration: 225.385µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:47:58.400872Z","caller":"traceutil/trace.go:171","msg":"trace[741368608] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"801.150413ms","start":"2026-02-18T02:47:57.599711Z","end":"2026-02-18T02:47:58.400862Z","steps":["trace[741368608] 'process raft request' (duration: 800.860227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:58.401308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:47:57.599696Z","time spent":"801.202194ms","remote":"127.0.0.1:38488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:47:58.677297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.287583ms","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-18T02:47:58.677376Z","caller":"traceutil/trace.go:171","msg":"trace[962663222] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:415; }","duration":"267.410185ms","start":"2026-02-18T02:47:58.40995Z","end":"2026-02-18T02:47:58.67736Z","steps":["trace[962663222] 'range keys from in-memory index tree' (duration: 267.083988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:58.677677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066385351s","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-18T02:47:58.677711Z","caller":"traceutil/trace.go:171","msg":"trace[344299925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"1.066436573s","start":"2026-02-18T02:47:57.611263Z","end":"2026-02-18T02:47:58.677699Z","steps":["trace[344299925] 'agreement among raft nodes before linearized reading' (duration: 790.164974ms)","trace[344299925] 'range keys from in-memory index tree' (duration: 276.213648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:58.677671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.328168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T02:47:58.677745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:47:57.611251Z","time spent":"1.066487014s","remote":"127.0.0.1:38594","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":"info","ts":"2026-02-18T02:47:58.677767Z","caller":"traceutil/trace.go:171","msg":"trace[1104329980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"195.454341ms","start":"2026-02-18T02:47:58.482294Z","end":"2026-02-18T02:47:58.677749Z","steps":["trace[1104329980] 'range keys from in-memory index tree' (duration: 195.280118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:58.677927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061742681s","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-18T02:47:58.677969Z","caller":"traceutil/trace.go:171","msg":"trace[1203584511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"1.061792101s","start":"2026-02-18T02:47:57.616163Z","end":"2026-02-18T02:47:58.677955Z","steps":["trace[1203584511] 'agreement among raft nodes before linearized reading' (duration: 785.297658ms)","trace[1203584511] 'range keys from in-memory index tree' (duration: 276.434292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:58.678007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:47:57.616157Z","time spent":"1.061842412s","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T02:47:58.678148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.761831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-18T02:47:58.678182Z","caller":"traceutil/trace.go:171","msg":"trace[1279692763] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:415; }","duration":"661.817543ms","start":"2026-02-18T02:47:58.016353Z","end":"2026-02-18T02:47:58.678171Z","steps":["trace[1279692763] 'agreement among raft nodes before linearized reading' (duration: 385.119985ms)","trace[1279692763] 'range keys from in-memory index tree' (duration: 276.649377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:58.678209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:47:58.016323Z","time spent":"661.879034ms","remote":"127.0.0.1:38478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T02:47:58.678442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061540757s","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-18T02:47:58.678476Z","caller":"traceutil/trace.go:171","msg":"trace[1774441676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"1.061587957s","start":"2026-02-18T02:47:57.616875Z","end":"2026-02-18T02:47:58.678463Z","steps":["trace[1774441676] 'agreement among raft nodes before linearized reading' (duration: 784.604633ms)","trace[1774441676] 'range keys from in-memory index tree' (duration: 276.929373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:58.678505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:47:57.61687Z","time spent":"1.061627568s","remote":"127.0.0.1:38594","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-18T02:48:13.249896Z","caller":"traceutil/trace.go:171","msg":"trace[2004892699] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"349.191484ms","start":"2026-02-18T02:48:12.900691Z","end":"2026-02-18T02:48:13.249882Z","steps":["trace[2004892699] 'process raft request' (duration: 347.288623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:13.25001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:12.900678Z","time spent":"349.269376ms","remote":"127.0.0.1:38478","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-18T02:48:14.951812Z","caller":"traceutil/trace.go:171","msg":"trace[46072467] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"112.856094ms","start":"2026-02-18T02:48:14.838932Z","end":"2026-02-18T02:48:14.951788Z","steps":["trace[46072467] 'read index received' (duration: 112.65694ms)","trace[46072467] 'applied index is now lower than readState.Index' (duration: 198.294µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:48:14.951942Z","caller":"traceutil/trace.go:171","msg":"trace[1652895146] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"118.66576ms","start":"2026-02-18T02:48:14.833264Z","end":"2026-02-18T02:48:14.95193Z","steps":["trace[1652895146] 'process raft request' (duration: 118.381184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:14.952166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.214753ms","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-18T02:48:14.952201Z","caller":"traceutil/trace.go:171","msg":"trace[1071114298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"113.271084ms","start":"2026-02-18T02:48:14.838916Z","end":"2026-02-18T02:48:14.952187Z","steps":["trace[1071114298] 'agreement among raft nodes before linearized reading' (duration: 113.148781ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:48:15.097795Z","caller":"traceutil/trace.go:171","msg":"trace[569419051] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"121.395281ms","start":"2026-02-18T02:48:14.976384Z","end":"2026-02-18T02:48:15.097779Z","steps":["trace[569419051] 'process raft request' (duration: 121.36793ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:48:15.098054Z","caller":"traceutil/trace.go:171","msg":"trace[167816003] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"122.807461ms","start":"2026-02-18T02:48:14.975241Z","end":"2026-02-18T02:48:15.098049Z","steps":["trace[167816003] 'process raft request' (duration: 122.432433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:21.074341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.061996ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:48:21.074439Z","caller":"traceutil/trace.go:171","msg":"trace[1240321373] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"601.476192ms","start":"2026-02-18T02:48:20.47295Z","end":"2026-02-18T02:48:21.074426Z","steps":["trace[1240321373] 'read index received' (duration: 33.411µs)","trace[1240321373] 'applied index is now lower than readState.Index' (duration: 601.441521ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:48:21.074945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.975942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-th26s\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T02:48:21.079121Z","caller":"traceutil/trace.go:171","msg":"trace[233279985] range","detail":"{range_begin:/registry/pods/kube-system/cilium-th26s; range_end:; response_count:1; response_revision:534; }","duration":"602.068714ms","start":"2026-02-18T02:48:20.472914Z","end":"2026-02-18T02:48:21.074982Z","steps":["trace[233279985] 'agreement among raft nodes before linearized reading' (duration: 601.90841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:21.079313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:20.472894Z","time spent":"606.405429ms","remote":"127.0.0.1:38488","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-th26s\" "} {"level":"info","ts":"2026-02-18T02:48:21.401844Z","caller":"traceutil/trace.go:171","msg":"trace[86796177] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:550; }","duration":"277.045386ms","start":"2026-02-18T02:48:21.124777Z","end":"2026-02-18T02:48:21.401823Z","steps":["trace[86796177] 'read index received' (duration: 12.218486ms)","trace[86796177] 'applied index is now lower than readState.Index' (duration: 264.82606ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:48:21.402763Z","caller":"traceutil/trace.go:171","msg":"trace[1877878832] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"304.921142ms","start":"2026-02-18T02:48:21.097832Z","end":"2026-02-18T02:48:21.402754Z","steps":["trace[1877878832] 'process raft request' (duration: 303.501221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:21.402855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:21.097815Z","time spent":"304.983883ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:48:21.403141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.339223ms","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-18T02:48:21.403207Z","caller":"traceutil/trace.go:171","msg":"trace[2077890544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"278.437035ms","start":"2026-02-18T02:48:21.124752Z","end":"2026-02-18T02:48:21.403189Z","steps":["trace[2077890544] 'agreement among raft nodes before linearized reading' (duration: 278.153879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:21.403339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.562126ms","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-18T02:48:21.403442Z","caller":"traceutil/trace.go:171","msg":"trace[640893428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"277.619348ms","start":"2026-02-18T02:48:21.125741Z","end":"2026-02-18T02:48:21.40336Z","steps":["trace[640893428] 'agreement among raft nodes before linearized reading' (duration: 277.484865ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:48:21.403494Z","caller":"traceutil/trace.go:171","msg":"trace[930398363] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"287.90064ms","start":"2026-02-18T02:48:21.115582Z","end":"2026-02-18T02:48:21.403483Z","steps":["trace[930398363] 'process raft request' (duration: 286.087241ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:48:29.99225Z","caller":"traceutil/trace.go:171","msg":"trace[1075434995] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"151.416752ms","start":"2026-02-18T02:48:29.84081Z","end":"2026-02-18T02:48:29.992227Z","steps":["trace[1075434995] 'process raft request' (duration: 151.265189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:37.748401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280400785368679384,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:48:38.24883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280400785368679384,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:48:38.284469Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063168691s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:48:38.284841Z","caller":"traceutil/trace.go:171","msg":"trace[653209252] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"1.063576681s","start":"2026-02-18T02:48:37.221244Z","end":"2026-02-18T02:48:38.284821Z","steps":["trace[653209252] 'process raft request' (duration: 1.063445828s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:38.284958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:37.221236Z","time spent":"1.063650402s","remote":"127.0.0.1:38594","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-18T02:48:39.106104Z","caller":"traceutil/trace.go:171","msg":"trace[1081492488] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"871.2331ms","start":"2026-02-18T02:48:38.234849Z","end":"2026-02-18T02:48:39.106082Z","steps":["trace[1081492488] 'process raft request' (duration: 871.072807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:39.106221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.858575195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T02:48:39.106252Z","caller":"traceutil/trace.go:171","msg":"trace[846528372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"1.858613606s","start":"2026-02-18T02:48:37.247631Z","end":"2026-02-18T02:48:39.106244Z","steps":["trace[846528372] 'agreement among raft nodes before linearized reading' (duration: 1.858487963s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:39.106254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:38.234824Z","time spent":"871.357504ms","remote":"127.0.0.1:38380","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-18T02:48:39.106274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:37.247615Z","time spent":"1.858653966s","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T02:48:39.106103Z","caller":"traceutil/trace.go:171","msg":"trace[2097390665] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"1.858431312s","start":"2026-02-18T02:48:37.247633Z","end":"2026-02-18T02:48:39.106064Z","steps":["trace[2097390665] 'read index received' (duration: 1.037467178s)","trace[2097390665] 'applied index is now lower than readState.Index' (duration: 820.963394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:48:39.607566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280400785368679388,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:48:40.10784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2280400785368679388,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:48:40.251334Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.145394197s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T02:48:40.368988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.127104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:48:40.369982Z","caller":"traceutil/trace.go:171","msg":"trace[328076015] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"2.016675888s","start":"2026-02-18T02:48:38.353286Z","end":"2026-02-18T02:48:40.369962Z","steps":["trace[328076015] 'process raft request' (duration: 1.898244466s)","trace[328076015] 'compare' (duration: 116.760866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:48:40.370216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:38.353271Z","time spent":"2.016850042s","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:48:40.370399Z","caller":"traceutil/trace.go:171","msg":"trace[1408216669] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:734; }","duration":"1.26389329s","start":"2026-02-18T02:48:39.106487Z","end":"2026-02-18T02:48:40.37038Z","steps":["trace[1408216669] 'read index received' (duration: 1.14505292s)","trace[1408216669] 'applied index is now lower than readState.Index' (duration: 118.83946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:48:40.37069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.83285179s","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-18T02:48:40.370778Z","caller":"traceutil/trace.go:171","msg":"trace[1920790909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:716; }","duration":"2.832956613s","start":"2026-02-18T02:48:37.537812Z","end":"2026-02-18T02:48:40.370769Z","steps":["trace[1920790909] 'agreement among raft nodes before linearized reading' (duration: 2.832768049s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:40.370858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:37.537799Z","time spent":"2.833050885s","remote":"127.0.0.1:38594","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-18T02:48:40.371053Z","caller":"traceutil/trace.go:171","msg":"trace[48752828] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.256877611s","start":"2026-02-18T02:48:39.114167Z","end":"2026-02-18T02:48:40.371045Z","steps":["trace[48752828] 'process raft request' (duration: 1.255794638s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:40.371202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:39.114152Z","time spent":"1.256999313s","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:48:40.371436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89369288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:48:40.37153Z","caller":"traceutil/trace.go:171","msg":"trace[1889697287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"1.893800582s","start":"2026-02-18T02:48:38.477718Z","end":"2026-02-18T02:48:40.371519Z","steps":["trace[1889697287] 'agreement among raft nodes before linearized reading' (duration: 1.893676709s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:40.371653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:38.477706Z","time spent":"1.893939435s","remote":"127.0.0.1:38306","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-18T02:48:40.371905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.828850256s","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-18T02:48:40.372016Z","caller":"traceutil/trace.go:171","msg":"trace[558291604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:716; }","duration":"2.828978309s","start":"2026-02-18T02:48:37.543029Z","end":"2026-02-18T02:48:40.372007Z","steps":["trace[558291604] 'agreement among raft nodes before linearized reading' (duration: 2.828808755s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:40.372103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:37.543018Z","time spent":"2.829076551s","remote":"127.0.0.1:38594","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-18T02:48:40.37081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.839715735s","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-18T02:48:40.372435Z","caller":"traceutil/trace.go:171","msg":"trace[985950721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:716; }","duration":"2.84129703s","start":"2026-02-18T02:48:37.531044Z","end":"2026-02-18T02:48:40.372341Z","steps":["trace[985950721] 'agreement among raft nodes before linearized reading' (duration: 2.839638234s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:48:40.372505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:48:37.531027Z","time spent":"2.841453124s","remote":"127.0.0.1:38594","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-18T02:49:00.94678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.971867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:49:00.947013Z","caller":"traceutil/trace.go:171","msg":"trace[1828259334] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"367.054485ms","start":"2026-02-18T02:49:00.579937Z","end":"2026-02-18T02:49:00.946992Z","steps":["trace[1828259334] 'process raft request' (duration: 227.786442ms)","trace[1828259334] 'compare' (duration: 138.814834ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:00.947912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:00.579927Z","time spent":"367.912375ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:49:00.953624Z","caller":"traceutil/trace.go:171","msg":"trace[2096207307] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"115.665174ms","start":"2026-02-18T02:49:00.837939Z","end":"2026-02-18T02:49:00.953604Z","steps":["trace[2096207307] 'process raft request' (duration: 115.211114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:12.773047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.455583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T02:49:12.773093Z","caller":"traceutil/trace.go:171","msg":"trace[1717406187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"143.531874ms","start":"2026-02-18T02:49:12.629551Z","end":"2026-02-18T02:49:12.773083Z","steps":["trace[1717406187] 'range keys from in-memory index tree' (duration: 143.383732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:12.773184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.731206ms","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-18T02:49:12.7732Z","caller":"traceutil/trace.go:171","msg":"trace[1547896750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"108.770887ms","start":"2026-02-18T02:49:12.664423Z","end":"2026-02-18T02:49:12.773194Z","steps":["trace[1547896750] 'range keys from in-memory index tree' (duration: 108.661325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:13.472368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.331138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-02-18T02:49:13.472949Z","caller":"traceutil/trace.go:171","msg":"trace[1021252506] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:815; }","duration":"207.940911ms","start":"2026-02-18T02:49:13.264978Z","end":"2026-02-18T02:49:13.472919Z","steps":["trace[1021252506] 'range keys from in-memory index tree' (duration: 206.834268ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:18.754867Z","caller":"traceutil/trace.go:171","msg":"trace[913420291] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"126.077674ms","start":"2026-02-18T02:49:18.628774Z","end":"2026-02-18T02:49:18.754852Z","steps":["trace[913420291] 'read index received' (duration: 125.956872ms)","trace[913420291] 'applied index is now lower than readState.Index' (duration: 120.332µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:18.754978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.162356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:49:18.755004Z","caller":"traceutil/trace.go:171","msg":"trace[1932624238] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:879; }","duration":"126.236277ms","start":"2026-02-18T02:49:18.628761Z","end":"2026-02-18T02:49:18.754997Z","steps":["trace[1932624238] 'agreement among raft nodes before linearized reading' (duration: 126.148825ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:18.755222Z","caller":"traceutil/trace.go:171","msg":"trace[577488364] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"136.942885ms","start":"2026-02-18T02:49:18.618271Z","end":"2026-02-18T02:49:18.755214Z","steps":["trace[577488364] 'process raft request' (duration: 136.497855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:19.179412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.462634ms","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-18T02:49:19.179478Z","caller":"traceutil/trace.go:171","msg":"trace[613277006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:879; }","duration":"317.541806ms","start":"2026-02-18T02:49:18.861909Z","end":"2026-02-18T02:49:19.179451Z","steps":["trace[613277006] 'range keys from in-memory index tree' (duration: 317.365503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:19.179501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:18.861897Z","time spent":"317.599407ms","remote":"127.0.0.1:38594","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-18T02:49:19.179658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.801229ms","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-18T02:49:19.179672Z","caller":"traceutil/trace.go:171","msg":"trace[803129330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:879; }","duration":"290.835919ms","start":"2026-02-18T02:49:18.888831Z","end":"2026-02-18T02:49:19.179667Z","steps":["trace[803129330] 'range keys from in-memory index tree' (duration: 290.744857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:19.179755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.255045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T02:49:19.179768Z","caller":"traceutil/trace.go:171","msg":"trace[1782499293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"288.279875ms","start":"2026-02-18T02:49:18.891484Z","end":"2026-02-18T02:49:19.179764Z","steps":["trace[1782499293] 'range keys from in-memory index tree' (duration: 288.213043ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:27.670471Z","caller":"traceutil/trace.go:171","msg":"trace[1508989606] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"189.217074ms","start":"2026-02-18T02:49:27.481238Z","end":"2026-02-18T02:49:27.670455Z","steps":["trace[1508989606] 'read index received' (duration: 187.15789ms)","trace[1508989606] 'applied index is now lower than readState.Index' (duration: 2.058634ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:49:27.670659Z","caller":"traceutil/trace.go:171","msg":"trace[808266296] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"416.25886ms","start":"2026-02-18T02:49:27.254389Z","end":"2026-02-18T02:49:27.670648Z","steps":["trace[808266296] 'process raft request' (duration: 414.054863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:27.670742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:27.254377Z","time spent":"416.309211ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:49:27.670873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.633723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:49:27.670896Z","caller":"traceutil/trace.go:171","msg":"trace[343761528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"189.685474ms","start":"2026-02-18T02:49:27.481203Z","end":"2026-02-18T02:49:27.670888Z","steps":["trace[343761528] 'agreement among raft nodes before linearized reading' (duration: 189.650404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:27.671033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.326289ms","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-18T02:49:27.671052Z","caller":"traceutil/trace.go:171","msg":"trace[614924649] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:906; }","duration":"142.34883ms","start":"2026-02-18T02:49:27.528698Z","end":"2026-02-18T02:49:27.671047Z","steps":["trace[614924649] 'agreement among raft nodes before linearized reading' (duration: 142.314799ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:28.977261Z","caller":"traceutil/trace.go:171","msg":"trace[551263371] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"130.435357ms","start":"2026-02-18T02:49:28.846811Z","end":"2026-02-18T02:49:28.977246Z","steps":["trace[551263371] 'process raft request' (duration: 130.339855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:31.125972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.363364ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:49:31.126051Z","caller":"traceutil/trace.go:171","msg":"trace[1262118596] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"145.589323ms","start":"2026-02-18T02:49:30.98045Z","end":"2026-02-18T02:49:31.126039Z","steps":["trace[1262118596] 'read index received' (duration: 23.881µs)","trace[1262118596] 'applied index is now lower than readState.Index' (duration: 145.564342ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:31.126151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.692935ms","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-18T02:49:31.126168Z","caller":"traceutil/trace.go:171","msg":"trace[2109160690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"145.740016ms","start":"2026-02-18T02:49:30.980424Z","end":"2026-02-18T02:49:31.126164Z","steps":["trace[2109160690] 'agreement among raft nodes before linearized reading' (duration: 145.647284ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:33.85827Z","caller":"traceutil/trace.go:171","msg":"trace[1898587990] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"135.48356ms","start":"2026-02-18T02:49:33.722772Z","end":"2026-02-18T02:49:33.858256Z","steps":["trace[1898587990] 'process raft request' (duration: 135.373687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:38.845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.854116ms","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-18T02:49:38.848158Z","caller":"traceutil/trace.go:171","msg":"trace[761007472] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:955; }","duration":"128.001959ms","start":"2026-02-18T02:49:38.71706Z","end":"2026-02-18T02:49:38.845062Z","steps":["trace[761007472] 'count revisions from in-memory index tree' (duration: 127.797815ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:41.455294Z","caller":"traceutil/trace.go:171","msg":"trace[1717573334] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"155.310514ms","start":"2026-02-18T02:49:41.299958Z","end":"2026-02-18T02:49:41.455268Z","steps":["trace[1717573334] 'process raft request' (duration: 155.162791ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:41.455905Z","caller":"traceutil/trace.go:171","msg":"trace[858457331] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1003; }","duration":"135.426977ms","start":"2026-02-18T02:49:41.320466Z","end":"2026-02-18T02:49:41.455893Z","steps":["trace[858457331] 'read index received' (duration: 135.423307ms)","trace[858457331] 'applied index is now lower than readState.Index' (duration: 2.93µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:41.456078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.579601ms","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-18T02:49:41.456109Z","caller":"traceutil/trace.go:171","msg":"trace[1110291550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"135.658102ms","start":"2026-02-18T02:49:41.320441Z","end":"2026-02-18T02:49:41.456099Z","steps":["trace[1110291550] 'agreement among raft nodes before linearized reading' (duration: 135.51936ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:41.479456Z","caller":"traceutil/trace.go:171","msg":"trace[1173338016] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"237.881126ms","start":"2026-02-18T02:49:41.241558Z","end":"2026-02-18T02:49:41.479439Z","steps":["trace[1173338016] 'process raft request' (duration: 237.536709ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:42.507675Z","caller":"traceutil/trace.go:171","msg":"trace[2066940729] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"121.400907ms","start":"2026-02-18T02:49:42.38625Z","end":"2026-02-18T02:49:42.507651Z","steps":["trace[2066940729] 'process raft request' (duration: 48.676203ms)","trace[2066940729] 'compare' (duration: 72.340927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:51.53515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.177421ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:49:51.535214Z","caller":"traceutil/trace.go:171","msg":"trace[566402856] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"571.738571ms","start":"2026-02-18T02:49:50.963465Z","end":"2026-02-18T02:49:51.535203Z","steps":["trace[566402856] 'read index received' (duration: 177.444327ms)","trace[566402856] 'applied index is now lower than readState.Index' (duration: 394.293254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:51.535529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.057407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T02:49:51.53555Z","caller":"traceutil/trace.go:171","msg":"trace[2040556422] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1033; }","duration":"572.083017ms","start":"2026-02-18T02:49:50.96346Z","end":"2026-02-18T02:49:51.535543Z","steps":["trace[2040556422] 'agreement among raft nodes before linearized reading' (duration: 571.998966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:51.535572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:50.9634Z","time spent":"572.163859ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T02:49:51.535707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.72249ms","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":"info","ts":"2026-02-18T02:49:51.535727Z","caller":"traceutil/trace.go:171","msg":"trace[720125946] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1033; }","duration":"511.743011ms","start":"2026-02-18T02:49:51.023976Z","end":"2026-02-18T02:49:51.53572Z","steps":["trace[720125946] 'agreement among raft nodes before linearized reading' (duration: 511.71265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:51.535745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:51.023906Z","time spent":"511.833923ms","remote":"127.0.0.1:38538","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-18T02:49:51.53618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.345173ms","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-18T02:49:51.5362Z","caller":"traceutil/trace.go:171","msg":"trace[532498293] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1033; }","duration":"440.368644ms","start":"2026-02-18T02:49:51.095826Z","end":"2026-02-18T02:49:51.536195Z","steps":["trace[532498293] 'agreement among raft nodes before linearized reading' (duration: 440.331703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:51.536217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:51.095767Z","time spent":"440.445065ms","remote":"127.0.0.1:38670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-18T02:49:51.662232Z","caller":"traceutil/trace.go:171","msg":"trace[1246283392] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"119.126582ms","start":"2026-02-18T02:49:51.543087Z","end":"2026-02-18T02:49:51.662213Z","steps":["trace[1246283392] 'process raft request' (duration: 119.00048ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:51.664486Z","caller":"traceutil/trace.go:171","msg":"trace[733329042] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"120.296115ms","start":"2026-02-18T02:49:51.544167Z","end":"2026-02-18T02:49:51.664464Z","steps":["trace[733329042] 'process raft request' (duration: 119.608642ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:51.665698Z","caller":"traceutil/trace.go:171","msg":"trace[784594592] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"120.497609ms","start":"2026-02-18T02:49:51.545185Z","end":"2026-02-18T02:49:51.665683Z","steps":["trace[784594592] 'process raft request' (duration: 118.724315ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:51.667504Z","caller":"traceutil/trace.go:171","msg":"trace[720121305] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"121.542051ms","start":"2026-02-18T02:49:51.545948Z","end":"2026-02-18T02:49:51.66749Z","steps":["trace[720121305] 'process raft request' (duration: 119.53064ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:50:34.699783Z","caller":"traceutil/trace.go:171","msg":"trace[1441489667] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"104.885956ms","start":"2026-02-18T02:50:34.594871Z","end":"2026-02-18T02:50:34.699757Z","steps":["trace[1441489667] 'process raft request' (duration: 104.762374ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:03.354946Z","caller":"traceutil/trace.go:171","msg":"trace[2018050153] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"342.719649ms","start":"2026-02-18T02:53:03.012211Z","end":"2026-02-18T02:53:03.354931Z","steps":["trace[2018050153] 'read index received' (duration: 338.291542ms)","trace[2018050153] 'applied index is now lower than readState.Index' (duration: 4.427517ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:53:03.35514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.907134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-18T02:53:03.355164Z","caller":"traceutil/trace.go:171","msg":"trace[174361858] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1849; }","duration":"342.967335ms","start":"2026-02-18T02:53:03.012189Z","end":"2026-02-18T02:53:03.355156Z","steps":["trace[174361858] 'agreement among raft nodes before linearized reading' (duration: 342.821362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:03.355441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:03.012175Z","time spent":"343.002655ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-18T02:53:03.35559Z","caller":"traceutil/trace.go:171","msg":"trace[879122649] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"378.911054ms","start":"2026-02-18T02:53:02.976673Z","end":"2026-02-18T02:53:03.355584Z","steps":["trace[879122649] 'process raft request' (duration: 373.869483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:03.355643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:02.976657Z","time spent":"378.945124ms","remote":"127.0.0.1:38594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:53:29.186123Z","caller":"traceutil/trace.go:171","msg":"trace[211016462] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"108.458227ms","start":"2026-02-18T02:53:29.077648Z","end":"2026-02-18T02:53:29.186107Z","steps":["trace[211016462] 'process raft request' (duration: 108.326395ms)"],"step_count":1}