{"level":"warn","ts":"2026-02-08T16:31:28.758611Z","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-08T16:31:28.758699Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-08T16:31:28.758776Z","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-08T16:31:28.758787Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-08T16:31:28.758807Z","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-08T16:31:28.760407Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-08T16:31:28.760502Z","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://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-08T16:31:28.764995Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.093156ms"} {"level":"info","ts":"2026-02-08T16:31:28.794147Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-08T16:31:28.794206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T16:31:28.794303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-08T16:31:28.794316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T16:31:28.794348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-08T16:31:28.794419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-08T16:31:28.957264Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T16:31:28.96398Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T16:31:28.967282Z","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-08T16:31:28.977231Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T16:31:28.989681Z","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-08T16:31:28.99049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-08T16:31:28.990505Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-08T16:31:28.990654Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T16:31:28.990675Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T16:31:28.992283Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T16:31:28.994695Z","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-08T16:31:28.994721Z","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-08T16:31:28.994728Z","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-08T16:31:28.99621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-08T16:31:28.996286Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-08T16:31:29.30231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T16:31:29.302702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T16:31:29.302728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-08T16:31:29.302739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-08T16:31:29.302749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-08T16:31:29.302763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-08T16:31:29.302921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-08T16:31:29.306211Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:31:29.30832Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T16:31:29.308417Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T16:31:29.308555Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T16:31:29.308799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T16:31:29.308815Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T16:31:29.311505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T16:31:29.315795Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-08T16:31:29.31686Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:31:29.318644Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:31:29.318672Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:31:31.23275Z","caller":"traceutil/trace.go:171","msg":"trace[1402835458] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"124.119976ms","start":"2026-02-08T16:31:31.108607Z","end":"2026-02-08T16:31:31.232727Z","steps":["trace[1402835458] 'process raft request' (duration: 54.571588ms)","trace[1402835458] 'compare' (duration: 69.158214ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:31.233803Z","caller":"traceutil/trace.go:171","msg":"trace[1992178547] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"125.051496ms","start":"2026-02-08T16:31:31.10874Z","end":"2026-02-08T16:31:31.233791Z","steps":["trace[1992178547] 'process raft request' (duration: 123.725682ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.235408Z","caller":"traceutil/trace.go:171","msg":"trace[1220783035] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"118.284637ms","start":"2026-02-08T16:31:31.117114Z","end":"2026-02-08T16:31:31.235398Z","steps":["trace[1220783035] 'process raft request' (duration: 116.630002ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.235294Z","caller":"traceutil/trace.go:171","msg":"trace[1496184779] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:14; }","duration":"102.85126ms","start":"2026-02-08T16:31:31.13243Z","end":"2026-02-08T16:31:31.235281Z","steps":["trace[1496184779] 'read index received' (duration: 30.900149ms)","trace[1496184779] 'applied index is now lower than readState.Index' (duration: 71.95022ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:31:31.236861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.406297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-ckh2z\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-02-08T16:31:31.237905Z","caller":"traceutil/trace.go:171","msg":"trace[1288704680] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-ckh2z; range_end:; response_count:1; response_revision:14; }","duration":"105.482256ms","start":"2026-02-08T16:31:31.132405Z","end":"2026-02-08T16:31:31.237887Z","steps":["trace[1288704680] 'agreement among raft nodes before linearized reading' (duration: 104.323532ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.243122Z","caller":"traceutil/trace.go:171","msg":"trace[2089524668] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"101.450661ms","start":"2026-02-08T16:31:31.141665Z","end":"2026-02-08T16:31:31.243116Z","steps":["trace[2089524668] 'process raft request' (duration: 101.43675ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.243304Z","caller":"traceutil/trace.go:171","msg":"trace[1540857712] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"101.939942ms","start":"2026-02-08T16:31:31.141358Z","end":"2026-02-08T16:31:31.243298Z","steps":["trace[1540857712] 'process raft request' (duration: 101.729778ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.243315Z","caller":"traceutil/trace.go:171","msg":"trace[292548099] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"102.623416ms","start":"2026-02-08T16:31:31.140671Z","end":"2026-02-08T16:31:31.243294Z","steps":["trace[292548099] 'process raft request' (duration: 102.406222ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.243541Z","caller":"traceutil/trace.go:171","msg":"trace[779970355] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"104.356835ms","start":"2026-02-08T16:31:31.139143Z","end":"2026-02-08T16:31:31.243499Z","steps":["trace[779970355] 'process raft request' (duration: 103.892805ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.24366Z","caller":"traceutil/trace.go:171","msg":"trace[1250402565] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"103.399064ms","start":"2026-02-08T16:31:31.140252Z","end":"2026-02-08T16:31:31.243651Z","steps":["trace[1250402565] 'process raft request' (duration: 102.811006ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.275118Z","caller":"traceutil/trace.go:171","msg":"trace[793928542] transaction","detail":"{read_only:false; number_of_response:0; response_revision:19; }","duration":"104.854315ms","start":"2026-02-08T16:31:31.170247Z","end":"2026-02-08T16:31:31.275101Z","steps":["trace[793928542] 'process raft request' (duration: 104.667003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:31.275316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.226931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-08T16:31:31.276565Z","caller":"traceutil/trace.go:171","msg":"trace[2115248952] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"142.553474ms","start":"2026-02-08T16:31:31.133993Z","end":"2026-02-08T16:31:31.276546Z","steps":["trace[2115248952] 'agreement among raft nodes before linearized reading' (duration: 141.133795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:31.275605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.49796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-08T16:31:31.275672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.448135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-08T16:31:31.275709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.659168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-08T16:31:31.27999Z","caller":"traceutil/trace.go:171","msg":"trace[901148477] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"113.941673ms","start":"2026-02-08T16:31:31.166035Z","end":"2026-02-08T16:31:31.279977Z","steps":["trace[901148477] 'agreement among raft nodes before linearized reading' (duration: 109.533183ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.280091Z","caller":"traceutil/trace.go:171","msg":"trace[42936916] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:20; }","duration":"121.889516ms","start":"2026-02-08T16:31:31.158193Z","end":"2026-02-08T16:31:31.280083Z","steps":["trace[42936916] 'agreement among raft nodes before linearized reading' (duration: 117.431463ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.280207Z","caller":"traceutil/trace.go:171","msg":"trace[900195576] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"146.154374ms","start":"2026-02-08T16:31:31.134045Z","end":"2026-02-08T16:31:31.2802Z","steps":["trace[900195576] 'agreement among raft nodes before linearized reading' (duration: 141.645787ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.439658Z","caller":"traceutil/trace.go:171","msg":"trace[2005180631] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:30; }","duration":"115.499662ms","start":"2026-02-08T16:31:31.324141Z","end":"2026-02-08T16:31:31.439641Z","steps":["trace[2005180631] 'read index received' (duration: 36.534246ms)","trace[2005180631] 'applied index is now lower than readState.Index' (duration: 78.964805ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:31.439814Z","caller":"traceutil/trace.go:171","msg":"trace[1490001338] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"155.130793ms","start":"2026-02-08T16:31:31.284674Z","end":"2026-02-08T16:31:31.439804Z","steps":["trace[1490001338] 'process raft request' (duration: 154.884367ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.440113Z","caller":"traceutil/trace.go:171","msg":"trace[1848085042] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"155.385739ms","start":"2026-02-08T16:31:31.28472Z","end":"2026-02-08T16:31:31.440105Z","steps":["trace[1848085042] 'process raft request' (duration: 154.857756ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.440518Z","caller":"traceutil/trace.go:171","msg":"trace[300662922] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"155.649056ms","start":"2026-02-08T16:31:31.28485Z","end":"2026-02-08T16:31:31.440499Z","steps":["trace[300662922] 'process raft request' (duration: 154.759429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:31.441178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.539972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-08T16:31:31.441375Z","caller":"traceutil/trace.go:171","msg":"trace[1955800707] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:32; }","duration":"156.682751ms","start":"2026-02-08T16:31:31.284604Z","end":"2026-02-08T16:31:31.441287Z","steps":["trace[1955800707] 'agreement among raft nodes before linearized reading' (duration: 156.50355ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.439726Z","caller":"traceutil/trace.go:171","msg":"trace[1199154651] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"159.955038ms","start":"2026-02-08T16:31:31.279746Z","end":"2026-02-08T16:31:31.439701Z","steps":["trace[1199154651] 'process raft request' (duration: 80.918588ms)","trace[1199154651] 'compare' (duration: 78.71975ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:31.440996Z","caller":"traceutil/trace.go:171","msg":"trace[1310701205] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"156.566203ms","start":"2026-02-08T16:31:31.28442Z","end":"2026-02-08T16:31:31.440987Z","steps":["trace[1310701205] 'process raft request' (duration: 155.09218ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.441021Z","caller":"traceutil/trace.go:171","msg":"trace[389186386] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"156.535191ms","start":"2026-02-08T16:31:31.28448Z","end":"2026-02-08T16:31:31.441015Z","steps":["trace[389186386] 'process raft request' (duration: 155.060848ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.445994Z","caller":"traceutil/trace.go:171","msg":"trace[1146043961] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"111.474886ms","start":"2026-02-08T16:31:31.334506Z","end":"2026-02-08T16:31:31.445981Z","steps":["trace[1146043961] 'process raft request' (duration: 111.304736ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.446271Z","caller":"traceutil/trace.go:171","msg":"trace[809720259] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"112.537763ms","start":"2026-02-08T16:31:31.333725Z","end":"2026-02-08T16:31:31.446262Z","steps":["trace[809720259] 'process raft request' (duration: 111.952336ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.446464Z","caller":"traceutil/trace.go:171","msg":"trace[1251286188] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"112.6523ms","start":"2026-02-08T16:31:31.333804Z","end":"2026-02-08T16:31:31.446457Z","steps":["trace[1251286188] 'process raft request' (duration: 111.926084ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.446681Z","caller":"traceutil/trace.go:171","msg":"trace[67013928] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"112.80253ms","start":"2026-02-08T16:31:31.333865Z","end":"2026-02-08T16:31:31.446668Z","steps":["trace[67013928] 'process raft request' (duration: 111.888842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:31.448363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.923944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-08T16:31:31.448517Z","caller":"traceutil/trace.go:171","msg":"trace[1562486990] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:38; }","duration":"115.098816ms","start":"2026-02-08T16:31:31.333401Z","end":"2026-02-08T16:31:31.4485Z","steps":["trace[1562486990] 'agreement among raft nodes before linearized reading' (duration: 112.582256ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:31.449231Z","caller":"traceutil/trace.go:171","msg":"trace[1053115748] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"115.288947ms","start":"2026-02-08T16:31:31.333912Z","end":"2026-02-08T16:31:31.449201Z","steps":["trace[1053115748] 'process raft request' (duration: 111.876701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:31.452512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.66077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-08T16:31:31.452567Z","caller":"traceutil/trace.go:171","msg":"trace[966904028] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:38; }","duration":"116.12589ms","start":"2026-02-08T16:31:31.336433Z","end":"2026-02-08T16:31:31.452559Z","steps":["trace[966904028] 'agreement among raft nodes before linearized reading' (duration: 112.65514ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:33.12202Z","caller":"traceutil/trace.go:171","msg":"trace[672211617] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"102.550627ms","start":"2026-02-08T16:31:33.019439Z","end":"2026-02-08T16:31:33.12199Z","steps":["trace[672211617] 'process raft request' (duration: 72.901728ms)","trace[672211617] 'compare' (duration: 29.553883ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:38.257054Z","caller":"traceutil/trace.go:171","msg":"trace[458348326] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"100.828905ms","start":"2026-02-08T16:31:38.156205Z","end":"2026-02-08T16:31:38.257034Z","steps":["trace[458348326] 'process raft request' (duration: 100.702227ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:40.055935Z","caller":"traceutil/trace.go:171","msg":"trace[720056721] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:360; }","duration":"142.543332ms","start":"2026-02-08T16:31:39.913373Z","end":"2026-02-08T16:31:40.055916Z","steps":["trace[720056721] 'read index received' (duration: 76.177592ms)","trace[720056721] 'applied index is now lower than readState.Index' (duration: 66.36497ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:40.056062Z","caller":"traceutil/trace.go:171","msg":"trace[72915021] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"143.460102ms","start":"2026-02-08T16:31:39.912594Z","end":"2026-02-08T16:31:40.056054Z","steps":["trace[72915021] 'process raft request' (duration: 76.946552ms)","trace[72915021] 'compare' (duration: 66.289465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:31:40.05626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.872894ms","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-08T16:31:40.056287Z","caller":"traceutil/trace.go:171","msg":"trace[1385409886] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:350; }","duration":"142.931578ms","start":"2026-02-08T16:31:39.913347Z","end":"2026-02-08T16:31:40.056279Z","steps":["trace[1385409886] 'agreement among raft nodes before linearized reading' (duration: 142.82304ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:40.056477Z","caller":"traceutil/trace.go:171","msg":"trace[1314789309] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"142.996852ms","start":"2026-02-08T16:31:39.913473Z","end":"2026-02-08T16:31:40.05647Z","steps":["trace[1314789309] 'process raft request' (duration: 142.774247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:40.343528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.899622ms","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-08T16:31:40.344517Z","caller":"traceutil/trace.go:171","msg":"trace[131245371] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:357; }","duration":"185.919199ms","start":"2026-02-08T16:31:40.158584Z","end":"2026-02-08T16:31:40.344503Z","steps":["trace[131245371] 'range keys from in-memory index tree' (duration: 184.811366ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:40.344134Z","caller":"traceutil/trace.go:171","msg":"trace[1151705425] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"185.397184ms","start":"2026-02-08T16:31:40.158714Z","end":"2026-02-08T16:31:40.344111Z","steps":["trace[1151705425] 'process raft request' (duration: 132.416595ms)","trace[1151705425] 'compare' (duration: 52.84406ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:40.413061Z","caller":"traceutil/trace.go:171","msg":"trace[1885240767] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:368; }","duration":"206.546971ms","start":"2026-02-08T16:31:40.206496Z","end":"2026-02-08T16:31:40.413043Z","steps":["trace[1885240767] 'read index received' (duration: 84.642469ms)","trace[1885240767] 'applied index is now lower than readState.Index' (duration: 121.903652ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:40.413552Z","caller":"traceutil/trace.go:171","msg":"trace[305403672] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"250.628983ms","start":"2026-02-08T16:31:40.16291Z","end":"2026-02-08T16:31:40.413539Z","steps":["trace[305403672] 'process raft request' (duration: 250.095548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:40.413887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.374195ms","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-08T16:31:40.414104Z","caller":"traceutil/trace.go:171","msg":"trace[457096311] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:361; }","duration":"207.568058ms","start":"2026-02-08T16:31:40.206467Z","end":"2026-02-08T16:31:40.414035Z","steps":["trace[457096311] 'agreement among raft nodes before linearized reading' (duration: 207.366135ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:40.414676Z","caller":"traceutil/trace.go:171","msg":"trace[64331193] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"254.856772ms","start":"2026-02-08T16:31:40.159807Z","end":"2026-02-08T16:31:40.414664Z","steps":["trace[64331193] 'process raft request' (duration: 253.052753ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:40.415124Z","caller":"traceutil/trace.go:171","msg":"trace[707486276] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"255.276139ms","start":"2026-02-08T16:31:40.159837Z","end":"2026-02-08T16:31:40.415113Z","steps":["trace[707486276] 'process raft request' (duration: 253.127008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:40.510272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.865775ms","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-08T16:31:40.510321Z","caller":"traceutil/trace.go:171","msg":"trace[1085151340] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:362; }","duration":"253.947901ms","start":"2026-02-08T16:31:40.25636Z","end":"2026-02-08T16:31:40.510308Z","steps":["trace[1085151340] 'agreement among raft nodes before linearized reading' (duration: 253.852605ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:40.510444Z","caller":"traceutil/trace.go:171","msg":"trace[1100500906] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"157.491142ms","start":"2026-02-08T16:31:40.352948Z","end":"2026-02-08T16:31:40.510439Z","steps":["trace[1100500906] 'process raft request' (duration: 156.355996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:40.510533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.548337ms","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-08T16:31:40.510575Z","caller":"traceutil/trace.go:171","msg":"trace[1608299115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:362; }","duration":"202.598552ms","start":"2026-02-08T16:31:40.307948Z","end":"2026-02-08T16:31:40.510546Z","steps":["trace[1608299115] 'agreement among raft nodes before linearized reading' (duration: 202.56702ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:40.976744Z","caller":"traceutil/trace.go:171","msg":"trace[618836462] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"155.344299ms","start":"2026-02-08T16:31:40.821374Z","end":"2026-02-08T16:31:40.976718Z","steps":["trace[618836462] 'process raft request' (duration: 95.043486ms)","trace[618836462] 'compare' (duration: 60.060657ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:41.016895Z","caller":"traceutil/trace.go:171","msg":"trace[170925614] transaction","detail":"{read_only:false; number_of_response:1; response_revision:386; }","duration":"141.135375ms","start":"2026-02-08T16:31:40.875752Z","end":"2026-02-08T16:31:41.016887Z","steps":["trace[170925614] 'process raft request' (duration: 140.991985ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:41.016863Z","caller":"traceutil/trace.go:171","msg":"trace[341052581] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:396; }","duration":"117.066225ms","start":"2026-02-08T16:31:40.899782Z","end":"2026-02-08T16:31:41.016848Z","steps":["trace[341052581] 'read index received' (duration: 16.711143ms)","trace[341052581] 'applied index is now lower than readState.Index' (duration: 100.353962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:31:41.017427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.638253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-08T16:31:41.017457Z","caller":"traceutil/trace.go:171","msg":"trace[1091798458] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:386; }","duration":"117.680106ms","start":"2026-02-08T16:31:40.899769Z","end":"2026-02-08T16:31:41.017449Z","steps":["trace[1091798458] 'agreement among raft nodes before linearized reading' (duration: 117.58779ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:41.130451Z","caller":"traceutil/trace.go:171","msg":"trace[974155098] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"100.714697ms","start":"2026-02-08T16:31:41.02971Z","end":"2026-02-08T16:31:41.130425Z","steps":["trace[974155098] 'process raft request' (duration: 46.849556ms)","trace[974155098] 'compare' (duration: 52.081382ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:44.466503Z","caller":"traceutil/trace.go:171","msg":"trace[1332291325] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"201.972403ms","start":"2026-02-08T16:31:44.264513Z","end":"2026-02-08T16:31:44.466485Z","steps":["trace[1332291325] 'process raft request' (duration: 201.860735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:45.085342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.511372ms","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-08T16:31:45.08541Z","caller":"traceutil/trace.go:171","msg":"trace[598344918] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:405; }","duration":"334.584577ms","start":"2026-02-08T16:31:44.75081Z","end":"2026-02-08T16:31:45.085394Z","steps":["trace[598344918] 'range keys from in-memory index tree' (duration: 334.429177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:45.085456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:31:44.750757Z","time spent":"334.686934ms","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5293,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-02-08T16:31:45.087622Z","caller":"traceutil/trace.go:171","msg":"trace[1746312669] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"169.900442ms","start":"2026-02-08T16:31:44.917691Z","end":"2026-02-08T16:31:45.087591Z","steps":["trace[1746312669] 'process raft request' (duration: 165.70341ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:46.364Z","caller":"traceutil/trace.go:171","msg":"trace[1561798626] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"218.198614ms","start":"2026-02-08T16:31:46.145781Z","end":"2026-02-08T16:31:46.363979Z","steps":["trace[1561798626] 'process raft request' (duration: 218.053545ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:47.584703Z","caller":"traceutil/trace.go:171","msg":"trace[643292584] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"344.945985ms","start":"2026-02-08T16:31:47.239741Z","end":"2026-02-08T16:31:47.584687Z","steps":["trace[643292584] 'read index received' (duration: 344.812906ms)","trace[643292584] 'applied index is now lower than readState.Index' (duration: 132.489µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:31:47.584828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.074224ms","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-08T16:31:47.584847Z","caller":"traceutil/trace.go:171","msg":"trace[1265400473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"345.131368ms","start":"2026-02-08T16:31:47.239709Z","end":"2026-02-08T16:31:47.58484Z","steps":["trace[1265400473] 'agreement among raft nodes before linearized reading' (duration: 345.036532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:47.584867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:31:47.239695Z","time spent":"345.16676ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T16:31:47.585069Z","caller":"traceutil/trace.go:171","msg":"trace[763113239] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"384.700895ms","start":"2026-02-08T16:31:47.200336Z","end":"2026-02-08T16:31:47.585037Z","steps":["trace[763113239] 'process raft request' (duration: 384.259806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:47.585661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:31:47.200319Z","time spent":"384.830275ms","remote":"127.0.0.1:58642","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-08T16:31:48.129639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810222683858325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T16:31:48.630116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810222683858325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T16:31:48.806695Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.219180618s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-08T16:31:48.929714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.432668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:31:48.929926Z","caller":"traceutil/trace.go:171","msg":"trace[504763132] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.342462803s","start":"2026-02-08T16:31:47.587449Z","end":"2026-02-08T16:31:48.929912Z","steps":["trace[504763132] 'process raft request' (duration: 1.219624628s)","trace[504763132] 'compare' (duration: 122.340222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:31:48.929984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:31:47.587436Z","time spent":"1.342523378s","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:31:48.9342Z","caller":"traceutil/trace.go:171","msg":"trace[1387480356] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"1.304943966s","start":"2026-02-08T16:31:47.629246Z","end":"2026-02-08T16:31:48.93419Z","steps":["trace[1387480356] 'read index received' (duration: 1.177835812s)","trace[1387480356] 'applied index is now lower than readState.Index' (duration: 127.106874ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:31:48.934354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305106467s","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-08T16:31:48.934376Z","caller":"traceutil/trace.go:171","msg":"trace[1904263727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"1.30515719s","start":"2026-02-08T16:31:47.629212Z","end":"2026-02-08T16:31:48.934369Z","steps":["trace[1904263727] 'agreement among raft nodes before linearized reading' (duration: 1.305118178s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:48.934394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:31:47.629193Z","time spent":"1.305196843s","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T16:31:48.934488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229201262s","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-08T16:31:48.934507Z","caller":"traceutil/trace.go:171","msg":"trace[1166875311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"1.229251475s","start":"2026-02-08T16:31:47.70525Z","end":"2026-02-08T16:31:48.934501Z","steps":["trace[1166875311] 'agreement among raft nodes before linearized reading' (duration: 1.229220713s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:48.934524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:31:47.70523Z","time spent":"1.229290379s","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T16:31:48.934712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.147976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-08T16:31:48.934748Z","caller":"traceutil/trace.go:171","msg":"trace[907707618] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:414; }","duration":"128.243722ms","start":"2026-02-08T16:31:48.806494Z","end":"2026-02-08T16:31:48.934737Z","steps":["trace[907707618] 'agreement among raft nodes before linearized reading' (duration: 128.180388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:48.934902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.047294ms","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-08T16:31:48.934929Z","caller":"traceutil/trace.go:171","msg":"trace[382206174] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:414; }","duration":"982.102167ms","start":"2026-02-08T16:31:47.952819Z","end":"2026-02-08T16:31:48.934921Z","steps":["trace[382206174] 'agreement among raft nodes before linearized reading' (duration: 982.051904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:48.934958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:31:47.952805Z","time spent":"982.14554ms","remote":"127.0.0.1:58536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6921,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-08T16:31:48.936305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147956217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:31:48.93635Z","caller":"traceutil/trace.go:171","msg":"trace[1869898156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"1.148033362s","start":"2026-02-08T16:31:47.788306Z","end":"2026-02-08T16:31:48.936339Z","steps":["trace[1869898156] 'agreement among raft nodes before linearized reading' (duration: 1.147934885s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:48.936381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:31:47.78824Z","time spent":"1.148133479s","remote":"127.0.0.1:58300","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-08T16:31:49.156737Z","caller":"traceutil/trace.go:171","msg":"trace[20286302] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"132.050815ms","start":"2026-02-08T16:31:49.02466Z","end":"2026-02-08T16:31:49.156711Z","steps":["trace[20286302] 'process raft request' (duration: 75.127601ms)","trace[20286302] 'compare' (duration: 56.837058ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:49.194547Z","caller":"traceutil/trace.go:171","msg":"trace[410878587] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"150.006616ms","start":"2026-02-08T16:31:49.04452Z","end":"2026-02-08T16:31:49.194526Z","steps":["trace[410878587] 'process raft request' (duration: 149.966484ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:49.194983Z","caller":"traceutil/trace.go:171","msg":"trace[2095242352] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"157.780845ms","start":"2026-02-08T16:31:49.037191Z","end":"2026-02-08T16:31:49.194972Z","steps":["trace[2095242352] 'process raft request' (duration: 157.094969ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:49.19513Z","caller":"traceutil/trace.go:171","msg":"trace[2132873058] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"157.803018ms","start":"2026-02-08T16:31:49.03729Z","end":"2026-02-08T16:31:49.195093Z","steps":["trace[2132873058] 'process raft request' (duration: 157.160413ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:53.066213Z","caller":"traceutil/trace.go:171","msg":"trace[883375488] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"111.923278ms","start":"2026-02-08T16:31:52.954277Z","end":"2026-02-08T16:31:53.066201Z","steps":["trace[883375488] 'process raft request' (duration: 111.744775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:53.23143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.606508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:31:53.231532Z","caller":"traceutil/trace.go:171","msg":"trace[1231997457] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"162.110178ms","start":"2026-02-08T16:31:53.069411Z","end":"2026-02-08T16:31:53.231521Z","steps":["trace[1231997457] 'process raft request' (duration: 162.080326ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:31:53.231759Z","caller":"traceutil/trace.go:171","msg":"trace[1425673372] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"162.71958ms","start":"2026-02-08T16:31:53.069033Z","end":"2026-02-08T16:31:53.231753Z","steps":["trace[1425673372] 'process raft request' (duration: 61.738736ms)","trace[1425673372] 'compare' (duration: 100.538444ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:55.281578Z","caller":"traceutil/trace.go:171","msg":"trace[1560392768] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"208.204385ms","start":"2026-02-08T16:31:55.073359Z","end":"2026-02-08T16:31:55.281564Z","steps":["trace[1560392768] 'process raft request' (duration: 208.13603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:31:55.532491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.227233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:31:55.532999Z","caller":"traceutil/trace.go:171","msg":"trace[325406673] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"246.20045ms","start":"2026-02-08T16:31:55.28678Z","end":"2026-02-08T16:31:55.53298Z","steps":["trace[325406673] 'process raft request' (duration: 132.412858ms)","trace[325406673] 'compare' (duration: 113.043291ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:31:55.533123Z","caller":"traceutil/trace.go:171","msg":"trace[1592190298] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"188.745261ms","start":"2026-02-08T16:31:55.344369Z","end":"2026-02-08T16:31:55.533115Z","steps":["trace[1592190298] 'process raft request' (duration: 188.227145ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:02.656978Z","caller":"traceutil/trace.go:171","msg":"trace[710091790] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"122.645326ms","start":"2026-02-08T16:32:02.534049Z","end":"2026-02-08T16:32:02.656694Z","steps":["trace[710091790] 'read index received' (duration: 122.397799ms)","trace[710091790] 'applied index is now lower than readState.Index' (duration: 246.817µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:02.657174Z","caller":"traceutil/trace.go:171","msg":"trace[888558551] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"570.098067ms","start":"2026-02-08T16:32:02.086843Z","end":"2026-02-08T16:32:02.656941Z","steps":["trace[888558551] 'process raft request' (duration: 569.675088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:02.657376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.313862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T16:32:02.657532Z","caller":"traceutil/trace.go:171","msg":"trace[315363729] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:512; }","duration":"123.502946ms","start":"2026-02-08T16:32:02.534023Z","end":"2026-02-08T16:32:02.657526Z","steps":["trace[315363729] 'agreement among raft nodes before linearized reading' (duration: 123.174763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:02.657501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:02.086828Z","time spent":"570.474603ms","remote":"127.0.0.1:58642","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-08T16:32:05.429695Z","caller":"traceutil/trace.go:171","msg":"trace[1223383433] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"129.16135ms","start":"2026-02-08T16:32:05.300513Z","end":"2026-02-08T16:32:05.429675Z","steps":["trace[1223383433] 'process raft request' (duration: 78.760039ms)","trace[1223383433] 'compare' (duration: 50.239729ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:05.623916Z","caller":"traceutil/trace.go:171","msg":"trace[741464055] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"185.395891ms","start":"2026-02-08T16:32:05.438496Z","end":"2026-02-08T16:32:05.623892Z","steps":["trace[741464055] 'read index received' (duration: 147.420776ms)","trace[741464055] 'applied index is now lower than readState.Index' (duration: 37.974225ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:05.624018Z","caller":"traceutil/trace.go:171","msg":"trace[2107163632] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"186.881416ms","start":"2026-02-08T16:32:05.437126Z","end":"2026-02-08T16:32:05.624007Z","steps":["trace[2107163632] 'process raft request' (duration: 148.781732ms)","trace[2107163632] 'compare' (duration: 37.853107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:32:05.624281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.762527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:05.624326Z","caller":"traceutil/trace.go:171","msg":"trace[439157855] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:530; }","duration":"185.836922ms","start":"2026-02-08T16:32:05.438478Z","end":"2026-02-08T16:32:05.624314Z","steps":["trace[439157855] 'agreement among raft nodes before linearized reading' (duration: 185.756436ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:05.706459Z","caller":"traceutil/trace.go:171","msg":"trace[1443063103] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"267.893973ms","start":"2026-02-08T16:32:05.438545Z","end":"2026-02-08T16:32:05.706439Z","steps":["trace[1443063103] 'process raft request' (duration: 267.624335ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:05.706706Z","caller":"traceutil/trace.go:171","msg":"trace[1525536809] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"260.476721ms","start":"2026-02-08T16:32:05.446023Z","end":"2026-02-08T16:32:05.7065Z","steps":["trace[1525536809] 'process raft request' (duration: 260.298008ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:05.784252Z","caller":"traceutil/trace.go:171","msg":"trace[340370960] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:549; }","duration":"160.262101ms","start":"2026-02-08T16:32:05.623975Z","end":"2026-02-08T16:32:05.784237Z","steps":["trace[340370960] 'read index received' (duration: 82.33455ms)","trace[340370960] 'applied index is now lower than readState.Index' (duration: 77.926791ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:05.78435Z","caller":"traceutil/trace.go:171","msg":"trace[1729559756] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"186.814992ms","start":"2026-02-08T16:32:05.597521Z","end":"2026-02-08T16:32:05.784336Z","steps":["trace[1729559756] 'process raft request' (duration: 143.761509ms)","trace[1729559756] 'compare' (duration: 42.712729ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:05.785133Z","caller":"traceutil/trace.go:171","msg":"trace[1459834937] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"157.675029ms","start":"2026-02-08T16:32:05.627443Z","end":"2026-02-08T16:32:05.785118Z","steps":["trace[1459834937] 'process raft request' (duration: 156.749334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:05.785775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.266625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:05.785823Z","caller":"traceutil/trace.go:171","msg":"trace[1548135647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"255.324989ms","start":"2026-02-08T16:32:05.530486Z","end":"2026-02-08T16:32:05.785811Z","steps":["trace[1548135647] 'agreement among raft nodes before linearized reading' (duration: 255.219552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:05.786366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.657736ms","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-08T16:32:05.78641Z","caller":"traceutil/trace.go:171","msg":"trace[26375163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"288.71545ms","start":"2026-02-08T16:32:05.497684Z","end":"2026-02-08T16:32:05.7864Z","steps":["trace[26375163] 'agreement among raft nodes before linearized reading' (duration: 288.577211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:05.786892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.326388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-08T16:32:05.786968Z","caller":"traceutil/trace.go:171","msg":"trace[1269333293] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:534; }","duration":"298.426715ms","start":"2026-02-08T16:32:05.488526Z","end":"2026-02-08T16:32:05.786953Z","steps":["trace[1269333293] 'agreement among raft nodes before linearized reading' (duration: 298.249873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:05.787593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.723371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3343"} {"level":"info","ts":"2026-02-08T16:32:05.787635Z","caller":"traceutil/trace.go:171","msg":"trace[816420468] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:534; }","duration":"156.84476ms","start":"2026-02-08T16:32:05.630779Z","end":"2026-02-08T16:32:05.787624Z","steps":["trace[816420468] 'agreement among raft nodes before linearized reading' (duration: 156.685659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:05.788848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.639756ms","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-08T16:32:05.788926Z","caller":"traceutil/trace.go:171","msg":"trace[67916450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"135.183185ms","start":"2026-02-08T16:32:05.653702Z","end":"2026-02-08T16:32:05.788885Z","steps":["trace[67916450] 'agreement among raft nodes before linearized reading' (duration: 134.56151ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:05.96317Z","caller":"traceutil/trace.go:171","msg":"trace[790801918] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"166.451888ms","start":"2026-02-08T16:32:05.7967Z","end":"2026-02-08T16:32:05.963152Z","steps":["trace[790801918] 'process raft request' (duration: 148.708888ms)","trace[790801918] 'compare' (duration: 17.649063ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:06.038166Z","caller":"traceutil/trace.go:171","msg":"trace[429771074] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"229.150417ms","start":"2026-02-08T16:32:05.808989Z","end":"2026-02-08T16:32:06.03814Z","steps":["trace[429771074] 'process raft request' (duration: 228.928022ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:06.038312Z","caller":"traceutil/trace.go:171","msg":"trace[1242741122] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:552; }","duration":"158.469058ms","start":"2026-02-08T16:32:05.879821Z","end":"2026-02-08T16:32:06.03829Z","steps":["trace[1242741122] 'read index received' (duration: 65.540177ms)","trace[1242741122] 'applied index is now lower than readState.Index' (duration: 92.928001ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:06.038441Z","caller":"traceutil/trace.go:171","msg":"trace[845035491] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"229.383294ms","start":"2026-02-08T16:32:05.809052Z","end":"2026-02-08T16:32:06.038435Z","steps":["trace[845035491] 'process raft request' (duration: 229.058531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:06.038518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.682953ms","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-08T16:32:06.038556Z","caller":"traceutil/trace.go:171","msg":"trace[1035989110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"158.797802ms","start":"2026-02-08T16:32:05.879745Z","end":"2026-02-08T16:32:06.038543Z","steps":["trace[1035989110] 'agreement among raft nodes before linearized reading' (duration: 158.62581ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:06.038585Z","caller":"traceutil/trace.go:171","msg":"trace[803192541] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"229.576437ms","start":"2026-02-08T16:32:05.809003Z","end":"2026-02-08T16:32:06.038579Z","steps":["trace[803192541] 'process raft request' (duration: 229.05028ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:06.038757Z","caller":"traceutil/trace.go:171","msg":"trace[2121264622] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"229.61656ms","start":"2026-02-08T16:32:05.809134Z","end":"2026-02-08T16:32:06.03875Z","steps":["trace[2121264622] 'process raft request' (duration: 229.119775ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:06.038822Z","caller":"traceutil/trace.go:171","msg":"trace[219910614] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"229.726348ms","start":"2026-02-08T16:32:05.809087Z","end":"2026-02-08T16:32:06.038814Z","steps":["trace[219910614] 'process raft request' (duration: 229.069472ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:06.038945Z","caller":"traceutil/trace.go:171","msg":"trace[345314099] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"229.845196ms","start":"2026-02-08T16:32:05.809089Z","end":"2026-02-08T16:32:06.038934Z","steps":["trace[345314099] 'process raft request' (duration: 229.107364ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:06.184627Z","caller":"traceutil/trace.go:171","msg":"trace[694484759] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"137.914102ms","start":"2026-02-08T16:32:06.046694Z","end":"2026-02-08T16:32:06.184609Z","steps":["trace[694484759] 'process raft request' (duration: 137.645423ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:06.370235Z","caller":"traceutil/trace.go:171","msg":"trace[334896053] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"182.730994ms","start":"2026-02-08T16:32:06.187485Z","end":"2026-02-08T16:32:06.370216Z","steps":["trace[334896053] 'read index received' (duration: 182.573623ms)","trace[334896053] 'applied index is now lower than readState.Index' (duration: 156.441µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:06.370617Z","caller":"traceutil/trace.go:171","msg":"trace[1999664524] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"232.00346ms","start":"2026-02-08T16:32:06.138562Z","end":"2026-02-08T16:32:06.370566Z","steps":["trace[1999664524] 'process raft request' (duration: 231.548548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:06.371278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.907267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/local-path-storage/local-path-provisioner-6f56467975\" ","response":"range_response_count:1 size:3225"} {"level":"info","ts":"2026-02-08T16:32:06.371322Z","caller":"traceutil/trace.go:171","msg":"trace[1116192486] range","detail":"{range_begin:/registry/replicasets/local-path-storage/local-path-provisioner-6f56467975; range_end:; response_count:1; response_revision:544; }","duration":"183.856594ms","start":"2026-02-08T16:32:06.18745Z","end":"2026-02-08T16:32:06.371307Z","steps":["trace[1116192486] 'agreement among raft nodes before linearized reading' (duration: 182.864434ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:08.31962Z","caller":"traceutil/trace.go:171","msg":"trace[1929841946] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"128.318863ms","start":"2026-02-08T16:32:08.191281Z","end":"2026-02-08T16:32:08.3196Z","steps":["trace[1929841946] 'process raft request' (duration: 93.978222ms)","trace[1929841946] 'compare' (duration: 34.271216ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:10.517594Z","caller":"traceutil/trace.go:171","msg":"trace[1713500045] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"258.123394ms","start":"2026-02-08T16:32:10.259454Z","end":"2026-02-08T16:32:10.517578Z","steps":["trace[1713500045] 'process raft request' (duration: 258.027247ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:10.542995Z","caller":"traceutil/trace.go:171","msg":"trace[972144427] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"220.703527ms","start":"2026-02-08T16:32:10.322275Z","end":"2026-02-08T16:32:10.542979Z","steps":["trace[972144427] 'read index received' (duration: 195.614306ms)","trace[972144427] 'applied index is now lower than readState.Index' (duration: 25.088531ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:10.543207Z","caller":"traceutil/trace.go:171","msg":"trace[1125191854] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"279.455508ms","start":"2026-02-08T16:32:10.263745Z","end":"2026-02-08T16:32:10.543201Z","steps":["trace[1125191854] 'process raft request' (duration: 279.157847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:10.543356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.069703ms","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-08T16:32:10.543371Z","caller":"traceutil/trace.go:171","msg":"trace[1517741420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"221.116006ms","start":"2026-02-08T16:32:10.322251Z","end":"2026-02-08T16:32:10.543367Z","steps":["trace[1517741420] 'agreement among raft nodes before linearized reading' (duration: 221.043511ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:11.165013Z","caller":"traceutil/trace.go:171","msg":"trace[647587687] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"617.431955ms","start":"2026-02-08T16:32:10.54753Z","end":"2026-02-08T16:32:11.164962Z","steps":["trace[647587687] 'process raft request' (duration: 617.21108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:11.165201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:10.54752Z","time spent":"617.614909ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:32:13.583951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056719998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:13.584004Z","caller":"traceutil/trace.go:171","msg":"trace[1618574079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"2.056806964s","start":"2026-02-08T16:32:11.527185Z","end":"2026-02-08T16:32:13.583992Z","steps":["trace[1618574079] 'range keys from in-memory index tree' (duration: 2.056675345s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:13.584042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:11.527171Z","time spent":"2.056864898s","remote":"127.0.0.1:58300","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-08T16:32:13.584277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.788667674s","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-08T16:32:13.584297Z","caller":"traceutil/trace.go:171","msg":"trace[362854196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"1.788709306s","start":"2026-02-08T16:32:11.795582Z","end":"2026-02-08T16:32:13.584291Z","steps":["trace[362854196] 'range keys from in-memory index tree' (duration: 1.788567627s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:13.584314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:11.795568Z","time spent":"1.788742479s","remote":"127.0.0.1:58642","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-08T16:32:13.584724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064412364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T16:32:13.584744Z","caller":"traceutil/trace.go:171","msg":"trace[890596042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"1.064459047s","start":"2026-02-08T16:32:12.520279Z","end":"2026-02-08T16:32:13.584738Z","steps":["trace[890596042] 'range keys from in-memory index tree' (duration: 1.064337918s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:13.584769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:12.520266Z","time spent":"1.064491419s","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T16:32:13.584969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051016871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T16:32:13.584992Z","caller":"traceutil/trace.go:171","msg":"trace[302489824] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:552; }","duration":"1.051047713s","start":"2026-02-08T16:32:12.533935Z","end":"2026-02-08T16:32:13.584983Z","steps":["trace[302489824] 'range keys from in-memory index tree' (duration: 1.050968297s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:13.585011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:12.533929Z","time spent":"1.051077386s","remote":"127.0.0.1:58364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-02-08T16:32:13.585114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030796211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-08T16:32:13.585131Z","caller":"traceutil/trace.go:171","msg":"trace[648221323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"1.030839004s","start":"2026-02-08T16:32:12.554287Z","end":"2026-02-08T16:32:13.585126Z","steps":["trace[648221323] 'range keys from in-memory index tree' (duration: 1.030729437s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:13.585146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:12.554272Z","time spent":"1.030870257s","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T16:32:13.709991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810222683858599,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T16:32:14.08336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.220132229s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T16:32:14.083719Z","caller":"traceutil/trace.go:171","msg":"trace[705239047] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.220537139s","start":"2026-02-08T16:32:12.863163Z","end":"2026-02-08T16:32:14.083701Z","steps":["trace[705239047] 'process raft request' (duration: 1.22042447s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:14.083824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:12.863147Z","time spent":"1.220612025s","remote":"127.0.0.1:58642","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-08T16:32:15.40031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.316784874s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T16:32:15.47795Z","caller":"traceutil/trace.go:171","msg":"trace[886391041] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:572; }","duration":"2.308280567s","start":"2026-02-08T16:32:13.169603Z","end":"2026-02-08T16:32:15.477884Z","steps":["trace[886391041] 'read index received' (duration: 919.493988ms)","trace[886391041] 'applied index is now lower than readState.Index' (duration: 1.388785529s)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:32:15.478075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:13.705292Z","time spent":"1.772779932s","remote":"127.0.0.1:58364","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T16:32:15.478445Z","caller":"traceutil/trace.go:171","msg":"trace[1740390140] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.770862866s","start":"2026-02-08T16:32:13.707566Z","end":"2026-02-08T16:32:15.478429Z","steps":["trace[1740390140] 'process raft request' (duration: 1.770127613s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:15.47856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:13.707557Z","time spent":"1.770927971s","remote":"127.0.0.1:58642","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-08T16:32:15.478704Z","caller":"traceutil/trace.go:171","msg":"trace[623094690] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.769727325s","start":"2026-02-08T16:32:13.708967Z","end":"2026-02-08T16:32:15.478695Z","steps":["trace[623094690] 'process raft request' (duration: 1.768843772s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:15.478756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:13.708962Z","time spent":"1.769766877s","remote":"127.0.0.1:58642","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-08T16:32:15.478875Z","caller":"traceutil/trace.go:171","msg":"trace[1295631207] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.76769829s","start":"2026-02-08T16:32:13.711165Z","end":"2026-02-08T16:32:15.478863Z","steps":["trace[1295631207] 'process raft request' (duration: 1.766683407s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:15.478927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:13.711158Z","time spent":"1.767742483s","remote":"127.0.0.1:58642","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-08T16:32:15.479105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.309497514s","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-08T16:32:15.479139Z","caller":"traceutil/trace.go:171","msg":"trace[492757590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"2.309558538s","start":"2026-02-08T16:32:13.169571Z","end":"2026-02-08T16:32:15.47913Z","steps":["trace[492757590] 'agreement among raft nodes before linearized reading' (duration: 2.309452261s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:15.479168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:13.169555Z","time spent":"2.309606732s","remote":"127.0.0.1:58642","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-08T16:32:15.479292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951566885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:15.479317Z","caller":"traceutil/trace.go:171","msg":"trace[525458374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"1.951626119s","start":"2026-02-08T16:32:13.527683Z","end":"2026-02-08T16:32:15.479309Z","steps":["trace[525458374] 'agreement among raft nodes before linearized reading' (duration: 1.951588727s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:15.47934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:13.527665Z","time spent":"1.951669572s","remote":"127.0.0.1:58314","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-08T16:32:15.781738Z","caller":"traceutil/trace.go:171","msg":"trace[714837906] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"289.73705ms","start":"2026-02-08T16:32:15.491973Z","end":"2026-02-08T16:32:15.78171Z","steps":["trace[714837906] 'process raft request' (duration: 265.069448ms)","trace[714837906] 'compare' (duration: 24.540543ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:15.781758Z","caller":"traceutil/trace.go:171","msg":"trace[1240029437] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"288.664482ms","start":"2026-02-08T16:32:15.493076Z","end":"2026-02-08T16:32:15.78174Z","steps":["trace[1240029437] 'read index received' (duration: 21.107827ms)","trace[1240029437] 'applied index is now lower than readState.Index' (duration: 267.555565ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:15.781996Z","caller":"traceutil/trace.go:171","msg":"trace[815226896] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"287.771539ms","start":"2026-02-08T16:32:15.494215Z","end":"2026-02-08T16:32:15.781987Z","steps":["trace[815226896] 'process raft request' (duration: 287.473898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:15.782119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.007666ms","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-08T16:32:15.782146Z","caller":"traceutil/trace.go:171","msg":"trace[2027856007] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:559; }","duration":"267.068921ms","start":"2026-02-08T16:32:15.51507Z","end":"2026-02-08T16:32:15.782138Z","steps":["trace[2027856007] 'agreement among raft nodes before linearized reading' (duration: 266.993615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:15.782204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.115464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:15.782228Z","caller":"traceutil/trace.go:171","msg":"trace[1648425108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"289.178228ms","start":"2026-02-08T16:32:15.493043Z","end":"2026-02-08T16:32:15.782221Z","steps":["trace[1648425108] 'agreement among raft nodes before linearized reading' (duration: 289.129385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:16.205994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.820113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:32:16.206088Z","caller":"traceutil/trace.go:171","msg":"trace[539097370] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"316.511279ms","start":"2026-02-08T16:32:15.889559Z","end":"2026-02-08T16:32:16.20607Z","steps":["trace[539097370] 'process raft request' (duration: 119.498994ms)","trace[539097370] 'compare' (duration: 196.625882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:32:16.206144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:15.889538Z","time spent":"316.578803ms","remote":"127.0.0.1:58642","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-08T16:32:17.651741Z","caller":"traceutil/trace.go:171","msg":"trace[678976374] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"120.957871ms","start":"2026-02-08T16:32:17.530761Z","end":"2026-02-08T16:32:17.651719Z","steps":["trace[678976374] 'process raft request' (duration: 120.839027ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:17.681296Z","caller":"traceutil/trace.go:171","msg":"trace[1901629135] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"149.998753ms","start":"2026-02-08T16:32:17.531277Z","end":"2026-02-08T16:32:17.681276Z","steps":["trace[1901629135] 'read index received' (duration: 120.423815ms)","trace[1901629135] 'applied index is now lower than readState.Index' (duration: 29.574318ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:17.681389Z","caller":"traceutil/trace.go:171","msg":"trace[415822544] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"150.488651ms","start":"2026-02-08T16:32:17.530894Z","end":"2026-02-08T16:32:17.681383Z","steps":["trace[415822544] 'process raft request' (duration: 150.230113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:17.681499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.206344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:17.681542Z","caller":"traceutil/trace.go:171","msg":"trace[799390312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"150.265661ms","start":"2026-02-08T16:32:17.531265Z","end":"2026-02-08T16:32:17.681531Z","steps":["trace[799390312] 'agreement among raft nodes before linearized reading' (duration: 150.197065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:17.681602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.24647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-08T16:32:17.68164Z","caller":"traceutil/trace.go:171","msg":"trace[2065070624] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:566; }","duration":"148.300447ms","start":"2026-02-08T16:32:17.533328Z","end":"2026-02-08T16:32:17.681628Z","steps":["trace[2065070624] 'agreement among raft nodes before linearized reading' (duration: 148.23313ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:17.681664Z","caller":"traceutil/trace.go:171","msg":"trace[1684115901] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"150.693032ms","start":"2026-02-08T16:32:17.530963Z","end":"2026-02-08T16:32:17.681656Z","steps":["trace[1684115901] 'process raft request' (duration: 150.254031ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:17.681733Z","caller":"traceutil/trace.go:171","msg":"trace[2009541959] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"127.667007ms","start":"2026-02-08T16:32:17.554062Z","end":"2026-02-08T16:32:17.681729Z","steps":["trace[2009541959] 'process raft request' (duration: 127.181688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:18.242085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.312239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hpw22\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-08T16:32:18.24214Z","caller":"traceutil/trace.go:171","msg":"trace[2011670138] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hpw22; range_end:; response_count:1; response_revision:566; }","duration":"404.406155ms","start":"2026-02-08T16:32:17.837723Z","end":"2026-02-08T16:32:18.242129Z","steps":["trace[2011670138] 'range keys from in-memory index tree' (duration: 404.196994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:18.242166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:17.83771Z","time spent":"404.450354ms","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-hpw22\" "} {"level":"warn","ts":"2026-02-08T16:32:18.24245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.074864ms","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-08T16:32:18.242511Z","caller":"traceutil/trace.go:171","msg":"trace[1278357544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"455.16641ms","start":"2026-02-08T16:32:17.787329Z","end":"2026-02-08T16:32:18.242495Z","steps":["trace[1278357544] 'range keys from in-memory index tree' (duration: 454.9428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:18.24255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:17.787315Z","time spent":"455.226917ms","remote":"127.0.0.1:58642","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-08T16:32:19.176496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.78761ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T16:32:19.17759Z","caller":"traceutil/trace.go:171","msg":"trace[1270587919] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"926.031388ms","start":"2026-02-08T16:32:18.251545Z","end":"2026-02-08T16:32:19.177577Z","steps":["trace[1270587919] 'process raft request' (duration: 925.9779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:19.177667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:18.251536Z","time spent":"926.078226ms","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":"info","ts":"2026-02-08T16:32:19.177853Z","caller":"traceutil/trace.go:171","msg":"trace[631308051] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"930.117225ms","start":"2026-02-08T16:32:18.247731Z","end":"2026-02-08T16:32:19.177848Z","steps":["trace[631308051] 'process raft request' (duration: 929.670973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:19.177881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:18.247714Z","time spent":"930.151393ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:32:22.933147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.403337493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:22.933194Z","caller":"traceutil/trace.go:171","msg":"trace[1702672616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"3.40342721s","start":"2026-02-08T16:32:19.529756Z","end":"2026-02-08T16:32:22.933184Z","steps":["trace[1702672616] 'range keys from in-memory index tree' (duration: 3.403278846s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:22.933214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:19.529738Z","time spent":"3.403471528s","remote":"127.0.0.1:58300","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-08T16:32:22.933307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.27669465s","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-08T16:32:22.933319Z","caller":"traceutil/trace.go:171","msg":"trace[53421644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"3.276744399s","start":"2026-02-08T16:32:19.656571Z","end":"2026-02-08T16:32:22.933315Z","steps":["trace[53421644] 'range keys from in-memory index tree' (duration: 3.276580775s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:22.93333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:19.656551Z","time spent":"3.276775857s","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T16:32:22.933462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.2470288s","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-08T16:32:22.933475Z","caller":"traceutil/trace.go:171","msg":"trace[470218173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"3.24706055s","start":"2026-02-08T16:32:19.68641Z","end":"2026-02-08T16:32:22.93347Z","steps":["trace[470218173] 'range keys from in-memory index tree' (duration: 3.246966973s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:22.933487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:19.6864Z","time spent":"3.247084199s","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T16:32:22.933554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.246415316s","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-08T16:32:22.933567Z","caller":"traceutil/trace.go:171","msg":"trace[44922771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"3.246442536s","start":"2026-02-08T16:32:19.687119Z","end":"2026-02-08T16:32:22.933562Z","steps":["trace[44922771] 'range keys from in-memory index tree' (duration: 3.246358279s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:22.93358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:19.687112Z","time spent":"3.246464844s","remote":"127.0.0.1:58642","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-08T16:32:22.933958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778100518s","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-08T16:32:22.933991Z","caller":"traceutil/trace.go:171","msg":"trace[1987528689] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:568; }","duration":"1.778134987s","start":"2026-02-08T16:32:21.155852Z","end":"2026-02-08T16:32:22.933987Z","steps":["trace[1987528689] 'range keys from in-memory index tree' (duration: 1.778095439s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:22.93408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.74990294s","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-08T16:32:22.934092Z","caller":"traceutil/trace.go:171","msg":"trace[1491321067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"1.749950098s","start":"2026-02-08T16:32:21.184138Z","end":"2026-02-08T16:32:22.934089Z","steps":["trace[1491321067] 'range keys from in-memory index tree' (duration: 1.749835003s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:22.934103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:21.184121Z","time spent":"1.749978997s","remote":"127.0.0.1:58642","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-08T16:32:22.934257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403409881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:22.934269Z","caller":"traceutil/trace.go:171","msg":"trace[22686884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"1.40344972s","start":"2026-02-08T16:32:21.530815Z","end":"2026-02-08T16:32:22.934265Z","steps":["trace[22686884] 'range keys from in-memory index tree' (duration: 1.403344004s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:22.934281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:21.530798Z","time spent":"1.403478769s","remote":"127.0.0.1:58314","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-08T16:32:22.93459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.973982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T16:32:22.934605Z","caller":"traceutil/trace.go:171","msg":"trace[386766281] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:568; }","duration":"400.04131ms","start":"2026-02-08T16:32:22.53456Z","end":"2026-02-08T16:32:22.934601Z","steps":["trace[386766281] 'range keys from in-memory index tree' (duration: 399.892005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:22.934616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:22.53454Z","time spent":"400.072948ms","remote":"127.0.0.1:58364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-02-08T16:32:28.661892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810222683858757,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T16:32:28.832334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.396154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:32:28.832522Z","caller":"traceutil/trace.go:171","msg":"trace[1204621436] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"670.888385ms","start":"2026-02-08T16:32:28.161617Z","end":"2026-02-08T16:32:28.832506Z","steps":["trace[1204621436] 'read index received' (duration: 521.239756ms)","trace[1204621436] 'applied index is now lower than readState.Index' (duration: 149.647779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:32:28.833152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.512158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-08T16:32:28.833629Z","caller":"traceutil/trace.go:171","msg":"trace[413733103] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:645; }","duration":"672.217069ms","start":"2026-02-08T16:32:28.16139Z","end":"2026-02-08T16:32:28.833607Z","steps":["trace[413733103] 'agreement among raft nodes before linearized reading' (duration: 671.622225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:28.8339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:28.161384Z","time spent":"672.394514ms","remote":"127.0.0.1:58384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-08T16:32:28.833736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.742541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-08T16:32:28.835748Z","caller":"traceutil/trace.go:171","msg":"trace[875502035] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:645; }","duration":"281.776366ms","start":"2026-02-08T16:32:28.553957Z","end":"2026-02-08T16:32:28.835733Z","steps":["trace[875502035] 'agreement among raft nodes before linearized reading' (duration: 279.715672ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:28.834242Z","caller":"traceutil/trace.go:171","msg":"trace[272982518] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"666.251912ms","start":"2026-02-08T16:32:28.167978Z","end":"2026-02-08T16:32:28.83423Z","steps":["trace[272982518] 'process raft request' (duration: 664.451212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:28.836124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:28.167971Z","time spent":"668.110391ms","remote":"127.0.0.1:58410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T16:32:28.83428Z","caller":"traceutil/trace.go:171","msg":"trace[2131945926] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"673.806094ms","start":"2026-02-08T16:32:28.160465Z","end":"2026-02-08T16:32:28.834271Z","steps":["trace[2131945926] 'process raft request' (duration: 522.383165ms)","trace[2131945926] 'compare' (duration: 149.162401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:32:28.836386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:28.160452Z","time spent":"675.868379ms","remote":"127.0.0.1:58384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:32:28.833227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.868025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-08T16:32:28.836616Z","caller":"traceutil/trace.go:171","msg":"trace[263925561] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:645; }","duration":"482.283383ms","start":"2026-02-08T16:32:28.354323Z","end":"2026-02-08T16:32:28.836607Z","steps":["trace[263925561] 'agreement among raft nodes before linearized reading' (duration: 478.798688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:28.836801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:28.354297Z","time spent":"482.470927ms","remote":"127.0.0.1:58384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-02-08T16:32:31.51826Z","caller":"traceutil/trace.go:171","msg":"trace[1879598103] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"434.854841ms","start":"2026-02-08T16:32:31.083385Z","end":"2026-02-08T16:32:31.51824Z","steps":["trace[1879598103] 'process raft request' (duration: 434.207686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:31.51844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:31.083375Z","time spent":"434.980698ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:32:31.671336Z","caller":"traceutil/trace.go:171","msg":"trace[1559682607] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"584.584757ms","start":"2026-02-08T16:32:31.086739Z","end":"2026-02-08T16:32:31.671323Z","steps":["trace[1559682607] 'process raft request' (duration: 559.788914ms)","trace[1559682607] 'compare' (duration: 24.600668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:32:31.67158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:31.086727Z","time spent":"584.659616ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:32:31.672126Z","caller":"traceutil/trace.go:171","msg":"trace[1884916769] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"585.314021ms","start":"2026-02-08T16:32:31.086801Z","end":"2026-02-08T16:32:31.672115Z","steps":["trace[1884916769] 'process raft request' (duration: 584.403602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:31.673775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:32:31.086796Z","time spent":"585.36691ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:32:31.671259Z","caller":"traceutil/trace.go:171","msg":"trace[129785531] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:701; }","duration":"141.690043ms","start":"2026-02-08T16:32:31.529551Z","end":"2026-02-08T16:32:31.671241Z","steps":["trace[129785531] 'read index received' (duration: 116.929089ms)","trace[129785531] 'applied index is now lower than readState.Index' (duration: 24.760224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:32:31.675768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.206089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:32:31.67589Z","caller":"traceutil/trace.go:171","msg":"trace[1509755844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"146.301126ms","start":"2026-02-08T16:32:31.529527Z","end":"2026-02-08T16:32:31.675828Z","steps":["trace[1509755844] 'agreement among raft nodes before linearized reading' (duration: 144.41726ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:32.066744Z","caller":"traceutil/trace.go:171","msg":"trace[486461995] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"175.445448ms","start":"2026-02-08T16:32:31.891284Z","end":"2026-02-08T16:32:32.06673Z","steps":["trace[486461995] 'process raft request' (duration: 175.35688ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:34.144696Z","caller":"traceutil/trace.go:171","msg":"trace[932687158] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"114.063339ms","start":"2026-02-08T16:32:34.030616Z","end":"2026-02-08T16:32:34.14468Z","steps":["trace[932687158] 'process raft request' (duration: 113.979631ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:34.345459Z","caller":"traceutil/trace.go:171","msg":"trace[1779644856] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"161.343753ms","start":"2026-02-08T16:32:34.184101Z","end":"2026-02-08T16:32:34.345445Z","steps":["trace[1779644856] 'process raft request' (duration: 161.224546ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:34.488762Z","caller":"traceutil/trace.go:171","msg":"trace[1322700037] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:723; }","duration":"139.359799ms","start":"2026-02-08T16:32:34.349388Z","end":"2026-02-08T16:32:34.488748Z","steps":["trace[1322700037] 'read index received' (duration: 127.458879ms)","trace[1322700037] 'applied index is now lower than readState.Index' (duration: 11.90042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:32:34.48891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.506206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hpw22\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-08T16:32:34.488928Z","caller":"traceutil/trace.go:171","msg":"trace[1545366349] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hpw22; range_end:; response_count:1; response_revision:698; }","duration":"139.557165ms","start":"2026-02-08T16:32:34.349366Z","end":"2026-02-08T16:32:34.488923Z","steps":["trace[1545366349] 'agreement among raft nodes before linearized reading' (duration: 139.449858ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:34.488985Z","caller":"traceutil/trace.go:171","msg":"trace[74224467] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"258.753936ms","start":"2026-02-08T16:32:34.230209Z","end":"2026-02-08T16:32:34.488963Z","steps":["trace[74224467] 'process raft request' (duration: 246.68376ms)","trace[74224467] 'compare' (duration: 11.783131ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:34.677306Z","caller":"traceutil/trace.go:171","msg":"trace[1710099831] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"178.079048ms","start":"2026-02-08T16:32:34.499211Z","end":"2026-02-08T16:32:34.67729Z","steps":["trace[1710099831] 'process raft request' (duration: 162.958032ms)","trace[1710099831] 'compare' (duration: 14.90274ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:34.680502Z","caller":"traceutil/trace.go:171","msg":"trace[1988480954] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"123.514596ms","start":"2026-02-08T16:32:34.556978Z","end":"2026-02-08T16:32:34.680493Z","steps":["trace[1988480954] 'process raft request' (duration: 123.263291ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:34.803681Z","caller":"traceutil/trace.go:171","msg":"trace[639209202] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"113.621947ms","start":"2026-02-08T16:32:34.69004Z","end":"2026-02-08T16:32:34.803662Z","steps":["trace[639209202] 'process raft request' (duration: 92.062294ms)","trace[639209202] 'compare' (duration: 21.485454ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:34.806289Z","caller":"traceutil/trace.go:171","msg":"trace[397623933] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"112.585998ms","start":"2026-02-08T16:32:34.693695Z","end":"2026-02-08T16:32:34.806281Z","steps":["trace[397623933] 'process raft request' (duration: 112.548849ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:36.834591Z","caller":"traceutil/trace.go:171","msg":"trace[861832730] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"128.374672ms","start":"2026-02-08T16:32:36.7062Z","end":"2026-02-08T16:32:36.834575Z","steps":["trace[861832730] 'process raft request' (duration: 128.266474ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:37.831262Z","caller":"traceutil/trace.go:171","msg":"trace[1987297760] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"178.721228ms","start":"2026-02-08T16:32:37.65251Z","end":"2026-02-08T16:32:37.831231Z","steps":["trace[1987297760] 'process raft request' (duration: 178.581211ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:38.066476Z","caller":"traceutil/trace.go:171","msg":"trace[98524929] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"104.458157ms","start":"2026-02-08T16:32:37.961995Z","end":"2026-02-08T16:32:38.066453Z","steps":["trace[98524929] 'process raft request' (duration: 61.109043ms)","trace[98524929] 'compare' (duration: 42.434937ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:38.222728Z","caller":"traceutil/trace.go:171","msg":"trace[2100488260] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"104.668405ms","start":"2026-02-08T16:32:38.118052Z","end":"2026-02-08T16:32:38.222721Z","steps":["trace[2100488260] 'process raft request' (duration: 104.516518ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:38.222693Z","caller":"traceutil/trace.go:171","msg":"trace[1341036555] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"100.412026ms","start":"2026-02-08T16:32:38.122263Z","end":"2026-02-08T16:32:38.222675Z","steps":["trace[1341036555] 'process raft request' (duration: 100.386256ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:39.194603Z","caller":"traceutil/trace.go:171","msg":"trace[855817366] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"122.243825ms","start":"2026-02-08T16:32:39.072335Z","end":"2026-02-08T16:32:39.194579Z","steps":["trace[855817366] 'process raft request' (duration: 61.996416ms)","trace[855817366] 'compare' (duration: 59.836195ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:32:39.195358Z","caller":"traceutil/trace.go:171","msg":"trace[1660661752] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"121.295017ms","start":"2026-02-08T16:32:39.07399Z","end":"2026-02-08T16:32:39.195285Z","steps":["trace[1660661752] 'process raft request' (duration: 120.526887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:45.28411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.862137ms","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-08T16:32:45.284155Z","caller":"traceutil/trace.go:171","msg":"trace[1031195066] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:785; }","duration":"254.940268ms","start":"2026-02-08T16:32:45.029204Z","end":"2026-02-08T16:32:45.284144Z","steps":["trace[1031195066] 'count revisions from in-memory index tree' (duration: 254.819977ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:45.57449Z","caller":"traceutil/trace.go:171","msg":"trace[2122233083] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"288.017718ms","start":"2026-02-08T16:32:45.286458Z","end":"2026-02-08T16:32:45.574476Z","steps":["trace[2122233083] 'process raft request' (duration: 286.055171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:46.034974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.688989ms","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-08T16:32:46.035046Z","caller":"traceutil/trace.go:171","msg":"trace[1082537870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"165.767349ms","start":"2026-02-08T16:32:45.869262Z","end":"2026-02-08T16:32:46.035029Z","steps":["trace[1082537870] 'range keys from in-memory index tree' (duration: 165.55476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:32:46.035287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.765804ms","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-08T16:32:46.035377Z","caller":"traceutil/trace.go:171","msg":"trace[1566353025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"161.855224ms","start":"2026-02-08T16:32:45.873512Z","end":"2026-02-08T16:32:46.035368Z","steps":["trace[1566353025] 'range keys from in-memory index tree' (duration: 161.694066ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:32:48.350027Z","caller":"traceutil/trace.go:171","msg":"trace[1523251345] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"229.441061ms","start":"2026-02-08T16:32:48.120568Z","end":"2026-02-08T16:32:48.350009Z","steps":["trace[1523251345] 'process raft request' (duration: 229.342081ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:04.469296Z","caller":"traceutil/trace.go:171","msg":"trace[1769110295] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"227.609924ms","start":"2026-02-08T16:33:04.24167Z","end":"2026-02-08T16:33:04.46928Z","steps":["trace[1769110295] 'read index received' (duration: 219.435276ms)","trace[1769110295] 'applied index is now lower than readState.Index' (duration: 8.174158ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:33:04.469491Z","caller":"traceutil/trace.go:171","msg":"trace[1373131410] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"248.756565ms","start":"2026-02-08T16:33:04.220725Z","end":"2026-02-08T16:33:04.469482Z","steps":["trace[1373131410] 'process raft request' (duration: 240.419074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:04.46968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.001018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T16:33:04.469707Z","caller":"traceutil/trace.go:171","msg":"trace[1832289305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"228.067999ms","start":"2026-02-08T16:33:04.241633Z","end":"2026-02-08T16:33:04.469701Z","steps":["trace[1832289305] 'agreement among raft nodes before linearized reading' (duration: 227.969458ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:07.227584Z","caller":"traceutil/trace.go:171","msg":"trace[1220744209] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"161.753913ms","start":"2026-02-08T16:33:07.065809Z","end":"2026-02-08T16:33:07.227563Z","steps":["trace[1220744209] 'process raft request' (duration: 161.286247ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:08.373333Z","caller":"traceutil/trace.go:171","msg":"trace[2011271458] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"132.055299ms","start":"2026-02-08T16:33:08.24126Z","end":"2026-02-08T16:33:08.373315Z","steps":["trace[2011271458] 'process raft request' (duration: 131.946028ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:08.684936Z","caller":"traceutil/trace.go:171","msg":"trace[1760054125] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"100.163996ms","start":"2026-02-08T16:33:08.584754Z","end":"2026-02-08T16:33:08.684918Z","steps":["trace[1760054125] 'process raft request' (duration: 57.936778ms)","trace[1760054125] 'compare' (duration: 39.827425ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:33:25.061137Z","caller":"traceutil/trace.go:171","msg":"trace[276241186] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"254.172087ms","start":"2026-02-08T16:33:24.806945Z","end":"2026-02-08T16:33:25.061117Z","steps":["trace[276241186] 'read index received' (duration: 253.994512ms)","trace[276241186] 'applied index is now lower than readState.Index' (duration: 176.525µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:33:25.061323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.377201ms","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-08T16:33:25.061362Z","caller":"traceutil/trace.go:171","msg":"trace[1113011118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"254.443893ms","start":"2026-02-08T16:33:24.806908Z","end":"2026-02-08T16:33:25.061352Z","steps":["trace[1113011118] 'agreement among raft nodes before linearized reading' (duration: 254.290319ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:25.061584Z","caller":"traceutil/trace.go:171","msg":"trace[247127409] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"255.9752ms","start":"2026-02-08T16:33:24.805599Z","end":"2026-02-08T16:33:25.061574Z","steps":["trace[247127409] 'process raft request' (duration: 255.404536ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:25.240759Z","caller":"traceutil/trace.go:171","msg":"trace[1061630018] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"169.116434ms","start":"2026-02-08T16:33:25.071611Z","end":"2026-02-08T16:33:25.240728Z","steps":["trace[1061630018] 'process raft request' (duration: 112.161852ms)","trace[1061630018] 'compare' (duration: 56.831069ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:33:36.008098Z","caller":"traceutil/trace.go:171","msg":"trace[1609510345] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"130.227617ms","start":"2026-02-08T16:33:35.877848Z","end":"2026-02-08T16:33:36.008075Z","steps":["trace[1609510345] 'process raft request' (duration: 129.765533ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:36.428368Z","caller":"traceutil/trace.go:171","msg":"trace[186505500] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"298.34536ms","start":"2026-02-08T16:33:36.130005Z","end":"2026-02-08T16:33:36.428351Z","steps":["trace[186505500] 'read index received' (duration: 298.170064ms)","trace[186505500] 'applied index is now lower than readState.Index' (duration: 174.696µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:33:36.428659Z","caller":"traceutil/trace.go:171","msg":"trace[2068867845] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"416.131177ms","start":"2026-02-08T16:33:36.01247Z","end":"2026-02-08T16:33:36.428601Z","steps":["trace[2068867845] 'process raft request' (duration: 415.753354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:36.428855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:33:36.012444Z","time spent":"416.369674ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:33:36.428725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.712382ms","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-08T16:33:36.42917Z","caller":"traceutil/trace.go:171","msg":"trace[56192816] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:963; }","duration":"299.212577ms","start":"2026-02-08T16:33:36.129948Z","end":"2026-02-08T16:33:36.42916Z","steps":["trace[56192816] 'agreement among raft nodes before linearized reading' (duration: 298.734992ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:40.268183Z","caller":"traceutil/trace.go:171","msg":"trace[1812622373] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"108.663697ms","start":"2026-02-08T16:33:40.159501Z","end":"2026-02-08T16:33:40.268165Z","steps":["trace[1812622373] 'read index received' (duration: 108.44261ms)","trace[1812622373] 'applied index is now lower than readState.Index' (duration: 220.397µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:33:40.268352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.837443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-08T16:33:40.268875Z","caller":"traceutil/trace.go:171","msg":"trace[240625391] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:978; }","duration":"109.522745ms","start":"2026-02-08T16:33:40.159347Z","end":"2026-02-08T16:33:40.26887Z","steps":["trace[240625391] 'agreement among raft nodes before linearized reading' (duration: 108.890094ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:40.268824Z","caller":"traceutil/trace.go:171","msg":"trace[561633288] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"134.56387ms","start":"2026-02-08T16:33:40.13425Z","end":"2026-02-08T16:33:40.268814Z","steps":["trace[561633288] 'process raft request' (duration: 133.759144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:43.169634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.343463ms","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-08T16:33:43.169695Z","caller":"traceutil/trace.go:171","msg":"trace[774342670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:988; }","duration":"306.450686ms","start":"2026-02-08T16:33:42.863233Z","end":"2026-02-08T16:33:43.169683Z","steps":["trace[774342670] 'range keys from in-memory index tree' (duration: 306.160946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:43.170224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:33:42.86321Z","time spent":"306.507688ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T16:33:43.985182Z","caller":"traceutil/trace.go:171","msg":"trace[1194009382] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"538.147152ms","start":"2026-02-08T16:33:43.447011Z","end":"2026-02-08T16:33:43.985158Z","steps":["trace[1194009382] 'process raft request' (duration: 537.948905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:43.986086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:33:43.446995Z","time spent":"538.996331ms","remote":"127.0.0.1:58642","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-08T16:33:45.343876Z","caller":"traceutil/trace.go:171","msg":"trace[1306387481] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"157.139663ms","start":"2026-02-08T16:33:45.186717Z","end":"2026-02-08T16:33:45.343856Z","steps":["trace[1306387481] 'read index received' (duration: 156.971797ms)","trace[1306387481] 'applied index is now lower than readState.Index' (duration: 167.036µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:33:45.34426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.514726ms","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-08T16:33:45.344349Z","caller":"traceutil/trace.go:171","msg":"trace[542246791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"157.6422ms","start":"2026-02-08T16:33:45.186683Z","end":"2026-02-08T16:33:45.344326Z","steps":["trace[542246791] 'agreement among raft nodes before linearized reading' (duration: 157.363241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:45.344628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.908765ms","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-08T16:33:45.347203Z","caller":"traceutil/trace.go:171","msg":"trace[2029051654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"123.538017ms","start":"2026-02-08T16:33:45.223647Z","end":"2026-02-08T16:33:45.347185Z","steps":["trace[2029051654] 'agreement among raft nodes before linearized reading' (duration: 120.618146ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:45.344699Z","caller":"traceutil/trace.go:171","msg":"trace[1059336129] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"181.472198ms","start":"2026-02-08T16:33:45.163214Z","end":"2026-02-08T16:33:45.344686Z","steps":["trace[1059336129] 'process raft request' (duration: 180.531155ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:45.493601Z","caller":"traceutil/trace.go:171","msg":"trace[1677615] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"141.002963ms","start":"2026-02-08T16:33:45.352573Z","end":"2026-02-08T16:33:45.493576Z","steps":["trace[1677615] 'process raft request' (duration: 140.982702ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:45.49379Z","caller":"traceutil/trace.go:171","msg":"trace[121135593] transaction","detail":"{read_only:false; number_of_response:1; response_revision:997; }","duration":"143.434098ms","start":"2026-02-08T16:33:45.35035Z","end":"2026-02-08T16:33:45.493784Z","steps":["trace[121135593] 'process raft request' (duration: 134.264059ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:45.493892Z","caller":"traceutil/trace.go:171","msg":"trace[40992329] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"141.497181ms","start":"2026-02-08T16:33:45.352391Z","end":"2026-02-08T16:33:45.493888Z","steps":["trace[40992329] 'process raft request' (duration: 141.131227ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:50.908672Z","caller":"traceutil/trace.go:171","msg":"trace[1953755584] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"107.692111ms","start":"2026-02-08T16:33:50.800933Z","end":"2026-02-08T16:33:50.908625Z","steps":["trace[1953755584] 'process raft request' (duration: 107.159092ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:54.756354Z","caller":"traceutil/trace.go:171","msg":"trace[1305547909] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"115.586472ms","start":"2026-02-08T16:33:54.640746Z","end":"2026-02-08T16:33:54.756332Z","steps":["trace[1305547909] 'read index received' (duration: 115.402795ms)","trace[1305547909] 'applied index is now lower than readState.Index' (duration: 182.837µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:33:54.756636Z","caller":"traceutil/trace.go:171","msg":"trace[1475605701] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"146.94755ms","start":"2026-02-08T16:33:54.609675Z","end":"2026-02-08T16:33:54.756623Z","steps":["trace[1475605701] 'process raft request' (duration: 146.537754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:54.756882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.125183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-02-08T16:33:54.756918Z","caller":"traceutil/trace.go:171","msg":"trace[1184221787] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1024; }","duration":"116.194345ms","start":"2026-02-08T16:33:54.640712Z","end":"2026-02-08T16:33:54.756906Z","steps":["trace[1184221787] 'agreement among raft nodes before linearized reading' (duration: 116.118472ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:58.966145Z","caller":"traceutil/trace.go:171","msg":"trace[842084000] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"170.226515ms","start":"2026-02-08T16:33:58.795899Z","end":"2026-02-08T16:33:58.966125Z","steps":["trace[842084000] 'read index received' (duration: 170.030167ms)","trace[842084000] 'applied index is now lower than readState.Index' (duration: 195.498µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:33:58.96644Z","caller":"traceutil/trace.go:171","msg":"trace[1365113816] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"192.08101ms","start":"2026-02-08T16:33:58.774345Z","end":"2026-02-08T16:33:58.966426Z","steps":["trace[1365113816] 'process raft request' (duration: 191.638313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:58.966682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.765796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" ","response":"range_response_count:1 size:1488"} {"level":"info","ts":"2026-02-08T16:33:58.966723Z","caller":"traceutil/trace.go:171","msg":"trace[518764670] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:1; response_revision:1035; }","duration":"170.838639ms","start":"2026-02-08T16:33:58.795874Z","end":"2026-02-08T16:33:58.966713Z","steps":["trace[518764670] 'agreement among raft nodes before linearized reading' (duration: 170.756095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:33:58.967036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.059908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-02-08T16:33:58.967082Z","caller":"traceutil/trace.go:171","msg":"trace[1054781283] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:1; response_revision:1035; }","duration":"171.1107ms","start":"2026-02-08T16:33:58.795959Z","end":"2026-02-08T16:33:58.96707Z","steps":["trace[1054781283] 'agreement among raft nodes before linearized reading' (duration: 171.040287ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:33:59.742499Z","caller":"traceutil/trace.go:171","msg":"trace[602110695] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"105.699608ms","start":"2026-02-08T16:33:59.636737Z","end":"2026-02-08T16:33:59.742436Z","steps":["trace[602110695] 'process raft request' (duration: 105.520421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:34:00.37656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.385873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"info","ts":"2026-02-08T16:34:00.376637Z","caller":"traceutil/trace.go:171","msg":"trace[165612934] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:1040; }","duration":"124.494448ms","start":"2026-02-08T16:34:00.252126Z","end":"2026-02-08T16:34:00.37662Z","steps":["trace[165612934] 'range keys from in-memory index tree' (duration: 124.305449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:34:00.37785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.819098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-02-08T16:34:00.379643Z","caller":"traceutil/trace.go:171","msg":"trace[55699813] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1040; }","duration":"116.637231ms","start":"2026-02-08T16:34:00.262985Z","end":"2026-02-08T16:34:00.379622Z","steps":["trace[55699813] 'range keys from in-memory index tree' (duration: 114.735275ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:34:01.996994Z","caller":"traceutil/trace.go:171","msg":"trace[1754080977] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"166.317075ms","start":"2026-02-08T16:34:01.830661Z","end":"2026-02-08T16:34:01.996978Z","steps":["trace[1754080977] 'process raft request' (duration: 166.217501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:34:05.255737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.619696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-02-08T16:34:05.2558Z","caller":"traceutil/trace.go:171","msg":"trace[1231690260] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:1053; }","duration":"150.71475ms","start":"2026-02-08T16:34:05.10507Z","end":"2026-02-08T16:34:05.255785Z","steps":["trace[1231690260] 'range keys from in-memory index tree' (duration: 150.564184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:34:05.255991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.460533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/\" range_end:\"/registry/configmaps/kube-system0\" limit:500 ","response":"range_response_count:8 size:16536"} {"level":"info","ts":"2026-02-08T16:34:05.256023Z","caller":"traceutil/trace.go:171","msg":"trace[990407212] range","detail":"{range_begin:/registry/configmaps/kube-system/; range_end:/registry/configmaps/kube-system0; response_count:8; response_revision:1053; }","duration":"141.505075ms","start":"2026-02-08T16:34:05.114506Z","end":"2026-02-08T16:34:05.256011Z","steps":["trace[990407212] 'range keys from in-memory index tree' (duration: 141.397531ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:34:16.41563Z","caller":"traceutil/trace.go:171","msg":"trace[220304212] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"124.390288ms","start":"2026-02-08T16:34:16.291225Z","end":"2026-02-08T16:34:16.415616Z","steps":["trace[220304212] 'process raft request' (duration: 124.239562ms)"],"step_count":1}