{"level":"warn","ts":"2026-08-22T14:35:27.674495Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:35:27.674582Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-08-22T14:35:27.674655Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:35:27.674668Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-08-22T14:35:27.674689Z","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-08-22T14:35:27.675309Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-08-22T14:35:27.675381Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-08-22T14:35:27.733881Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"53.033675ms"} {"level":"info","ts":"2026-08-22T14:35:27.79462Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-08-22T14:35:27.794724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:35:27.79478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-08-22T14:35:27.79479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:35:27.794817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-08-22T14:35:27.794952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-08-22T14:35:27.832469Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:35:27.844106Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:35:27.851765Z","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-08-22T14:35:27.872475Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:35:27.873002Z","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-08-22T14:35:27.873143Z","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-08-22T14:35:27.873137Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:35:27.873183Z","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-08-22T14:35:27.879394Z","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-08-22T14:35:27.879622Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-08-22T14:35:27.879662Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-08-22T14:35:27.879872Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:35:27.87998Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:35:27.882066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-08-22T14:35:27.882195Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-08-22T14:35:28.096289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:35:28.096337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:35:28.096364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-08-22T14:35:28.09645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:35:28.096459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-08-22T14:35:28.096469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-08-22T14:35:28.096481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-08-22T14:35:28.104929Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:35:28.111685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:35:28.111682Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:35:28.111737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:35:28.112818Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:35:28.1132Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:35:28.113503Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:35:28.116262Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-08-22T14:35:28.118944Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:35:28.124098Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:35:28.124141Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:35:29.925294Z","caller":"traceutil/trace.go:171","msg":"trace[1761585439] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"201.13636ms","start":"2026-08-22T14:35:29.724134Z","end":"2026-08-22T14:35:29.925271Z","steps":["trace[1761585439] 'process raft request' (duration: 201.06647ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:29.925394Z","caller":"traceutil/trace.go:171","msg":"trace[887624812] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"206.886482ms","start":"2026-08-22T14:35:29.718473Z","end":"2026-08-22T14:35:29.92536Z","steps":["trace[887624812] 'process raft request' (duration: 184.029128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:29.925419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.047904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-6ht8z\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-08-22T14:35:29.925472Z","caller":"traceutil/trace.go:171","msg":"trace[732945785] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"204.880804ms","start":"2026-08-22T14:35:29.720585Z","end":"2026-08-22T14:35:29.925466Z","steps":["trace[732945785] 'process raft request' (duration: 204.526131ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:29.925483Z","caller":"traceutil/trace.go:171","msg":"trace[791156996] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-6ht8z; range_end:; response_count:1; response_revision:19; }","duration":"206.159185ms","start":"2026-08-22T14:35:29.719316Z","end":"2026-08-22T14:35:29.925475Z","steps":["trace[791156996] 'agreement among raft nodes before linearized reading' (duration: 206.040194ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:29.925639Z","caller":"traceutil/trace.go:171","msg":"trace[1179717033] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"190.937698ms","start":"2026-08-22T14:35:29.734694Z","end":"2026-08-22T14:35:29.925632Z","steps":["trace[1179717033] 'process raft request' (duration: 190.577515ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:29.925669Z","caller":"traceutil/trace.go:171","msg":"trace[1923667670] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"199.95284ms","start":"2026-08-22T14:35:29.725709Z","end":"2026-08-22T14:35:29.925662Z","steps":["trace[1923667670] 'process raft request' (duration: 199.525646ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:29.925678Z","caller":"traceutil/trace.go:171","msg":"trace[481367284] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"201.036059ms","start":"2026-08-22T14:35:29.724633Z","end":"2026-08-22T14:35:29.925669Z","steps":["trace[481367284] 'process raft request' (duration: 200.584915ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:29.925443Z","caller":"traceutil/trace.go:171","msg":"trace[1415700530] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"201.504814ms","start":"2026-08-22T14:35:29.723904Z","end":"2026-08-22T14:35:29.925409Z","steps":["trace[1415700530] 'process raft request' (duration: 201.269842ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:29.925707Z","caller":"traceutil/trace.go:171","msg":"trace[171717617] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"199.86711ms","start":"2026-08-22T14:35:29.725802Z","end":"2026-08-22T14:35:29.92567Z","steps":["trace[171717617] 'process raft request' (duration: 199.448406ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:29.92532Z","caller":"traceutil/trace.go:171","msg":"trace[1365963062] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"205.954324ms","start":"2026-08-22T14:35:29.719348Z","end":"2026-08-22T14:35:29.925302Z","steps":["trace[1365963062] 'read index received' (duration: 22.748414ms)","trace[1365963062] 'applied index is now lower than readState.Index' (duration: 183.20516ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:35:29.925803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.147938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-08-22T14:35:29.92583Z","caller":"traceutil/trace.go:171","msg":"trace[1881977138] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"194.208328ms","start":"2026-08-22T14:35:29.731616Z","end":"2026-08-22T14:35:29.925825Z","steps":["trace[1881977138] 'agreement among raft nodes before linearized reading' (duration: 194.163468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:29.925838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.019611ms","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-08-22T14:35:29.925865Z","caller":"traceutil/trace.go:171","msg":"trace[950855720] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:19; }","duration":"180.082701ms","start":"2026-08-22T14:35:29.745774Z","end":"2026-08-22T14:35:29.925857Z","steps":["trace[950855720] 'agreement among raft nodes before linearized reading' (duration: 180.015161ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:30.149498Z","caller":"traceutil/trace.go:171","msg":"trace[97645895] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"221.32447ms","start":"2026-08-22T14:35:29.928139Z","end":"2026-08-22T14:35:30.149463Z","steps":["trace[97645895] 'process raft request' (duration: 157.822293ms)","trace[97645895] 'compare' (duration: 63.321205ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:35:30.149565Z","caller":"traceutil/trace.go:171","msg":"trace[1320871247] linearizableReadLoop","detail":"{readStateIndex:27; appliedIndex:24; }","duration":"217.610687ms","start":"2026-08-22T14:35:29.931936Z","end":"2026-08-22T14:35:30.149547Z","steps":["trace[1320871247] 'read index received' (duration: 75.994011ms)","trace[1320871247] 'applied index is now lower than readState.Index' (duration: 141.615956ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:35:30.14965Z","caller":"traceutil/trace.go:171","msg":"trace[1002280460] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"217.705527ms","start":"2026-08-22T14:35:29.931938Z","end":"2026-08-22T14:35:30.149644Z","steps":["trace[1002280460] 'process raft request' (duration: 217.514416ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:30.149694Z","caller":"traceutil/trace.go:171","msg":"trace[1017226643] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"217.781448ms","start":"2026-08-22T14:35:29.9319Z","end":"2026-08-22T14:35:30.149682Z","steps":["trace[1017226643] 'process raft request' (duration: 217.527926ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:30.14986Z","caller":"traceutil/trace.go:171","msg":"trace[1448310709] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"217.860938ms","start":"2026-08-22T14:35:29.931991Z","end":"2026-08-22T14:35:30.149852Z","steps":["trace[1448310709] 'process raft request' (duration: 217.479535ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:30.149918Z","caller":"traceutil/trace.go:171","msg":"trace[1868126313] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"217.504706ms","start":"2026-08-22T14:35:29.932398Z","end":"2026-08-22T14:35:30.149903Z","steps":["trace[1868126313] 'process raft request' (duration: 217.124152ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:30.149984Z","caller":"traceutil/trace.go:171","msg":"trace[1297023471] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"217.734837ms","start":"2026-08-22T14:35:29.932242Z","end":"2026-08-22T14:35:30.149977Z","steps":["trace[1297023471] 'process raft request' (duration: 217.246983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:30.150024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.425499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:35:30.150003Z","caller":"traceutil/trace.go:171","msg":"trace[1904995683] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"220.980927ms","start":"2026-08-22T14:35:29.928767Z","end":"2026-08-22T14:35:30.149748Z","steps":["trace[1904995683] 'process raft request' (duration: 220.627784ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:30.150074Z","caller":"traceutil/trace.go:171","msg":"trace[1889281598] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"217.682027ms","start":"2026-08-22T14:35:29.932381Z","end":"2026-08-22T14:35:30.150063Z","steps":["trace[1889281598] 'process raft request' (duration: 217.125262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:30.1501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.164771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:35:30.150124Z","caller":"traceutil/trace.go:171","msg":"trace[2059174245] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:28; }","duration":"218.218271ms","start":"2026-08-22T14:35:29.931899Z","end":"2026-08-22T14:35:30.150117Z","steps":["trace[2059174245] 'agreement among raft nodes before linearized reading' (duration: 218.188061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:30.150167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.250675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:35:30.150223Z","caller":"traceutil/trace.go:171","msg":"trace[212989671] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:28; }","duration":"138.331436ms","start":"2026-08-22T14:35:30.011883Z","end":"2026-08-22T14:35:30.150214Z","steps":["trace[212989671] 'agreement among raft nodes before linearized reading' (duration: 138.263096ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:30.150075Z","caller":"traceutil/trace.go:171","msg":"trace[2052324209] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:28; }","duration":"203.51818ms","start":"2026-08-22T14:35:29.946548Z","end":"2026-08-22T14:35:30.150066Z","steps":["trace[2052324209] 'agreement among raft nodes before linearized reading' (duration: 203.409439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:32.158158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.001497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:35:32.158312Z","caller":"traceutil/trace.go:171","msg":"trace[1065790392] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"207.291719ms","start":"2026-08-22T14:35:31.950996Z","end":"2026-08-22T14:35:32.158288Z","steps":["trace[1065790392] 'process raft request' (duration: 46.352774ms)","trace[1065790392] 'compare' (duration: 159.819555ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:35:33.891321Z","caller":"traceutil/trace.go:171","msg":"trace[1594746857] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"209.492706ms","start":"2026-08-22T14:35:33.681799Z","end":"2026-08-22T14:35:33.891292Z","steps":["trace[1594746857] 'process raft request' (duration: 209.348335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:44.566119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120581734643358,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:35:44.663966Z","caller":"traceutil/trace.go:171","msg":"trace[773354870] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"598.11221ms","start":"2026-08-22T14:35:44.065827Z","end":"2026-08-22T14:35:44.663939Z","steps":["trace[773354870] 'read index received' (duration: 597.842247ms)","trace[773354870] 'applied index is now lower than readState.Index' (duration: 268.913µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:35:44.664181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.329372ms","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-08-22T14:35:44.664236Z","caller":"traceutil/trace.go:171","msg":"trace[722290787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"598.433993ms","start":"2026-08-22T14:35:44.065785Z","end":"2026-08-22T14:35:44.664219Z","steps":["trace[722290787] 'agreement among raft nodes before linearized reading' (duration: 598.316672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:44.664286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:35:44.065768Z","time spent":"598.506583ms","remote":"127.0.0.1:37038","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":"info","ts":"2026-08-22T14:35:44.664343Z","caller":"traceutil/trace.go:171","msg":"trace[2107335933] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"614.778766ms","start":"2026-08-22T14:35:44.049271Z","end":"2026-08-22T14:35:44.66405Z","steps":["trace[2107335933] 'process raft request' (duration: 614.493303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:44.664469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.650906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-22T14:35:44.664621Z","caller":"traceutil/trace.go:171","msg":"trace[1472612328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"462.848118ms","start":"2026-08-22T14:35:44.201749Z","end":"2026-08-22T14:35:44.664597Z","steps":["trace[1472612328] 'agreement among raft nodes before linearized reading' (duration: 462.645946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:44.664682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:35:44.201733Z","time spent":"462.939239ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:35:44.664766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.287451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T14:35:44.664845Z","caller":"traceutil/trace.go:171","msg":"trace[1065961904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"144.386282ms","start":"2026-08-22T14:35:44.520423Z","end":"2026-08-22T14:35:44.664809Z","steps":["trace[1065961904] 'agreement among raft nodes before linearized reading' (duration: 144.297082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:44.664885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.446753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T14:35:44.664922Z","caller":"traceutil/trace.go:171","msg":"trace[1476814918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"144.528463ms","start":"2026-08-22T14:35:44.520383Z","end":"2026-08-22T14:35:44.664912Z","steps":["trace[1476814918] 'agreement among raft nodes before linearized reading' (duration: 144.469453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:44.665283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:35:44.049245Z","time spent":"615.337921ms","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:35:52.921821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.716186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:35:52.921896Z","caller":"traceutil/trace.go:171","msg":"trace[1700344755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"105.841606ms","start":"2026-08-22T14:35:52.816038Z","end":"2026-08-22T14:35:52.92188Z","steps":["trace[1700344755] 'range keys from in-memory index tree' (duration: 105.618864ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:53.112751Z","caller":"traceutil/trace.go:171","msg":"trace[187413200] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"115.628551ms","start":"2026-08-22T14:35:52.997104Z","end":"2026-08-22T14:35:53.112733Z","steps":["trace[187413200] 'process raft request' (duration: 50.36368ms)","trace[187413200] 'compare' (duration: 65.189811ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:35:53.113513Z","caller":"traceutil/trace.go:171","msg":"trace[1026249168] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"112.365083ms","start":"2026-08-22T14:35:53.00114Z","end":"2026-08-22T14:35:53.113505Z","steps":["trace[1026249168] 'process raft request' (duration: 112.335612ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:53.113655Z","caller":"traceutil/trace.go:171","msg":"trace[1780695088] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"113.858726ms","start":"2026-08-22T14:35:52.999791Z","end":"2026-08-22T14:35:53.113649Z","steps":["trace[1780695088] 'process raft request' (duration: 113.653053ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:55.058801Z","caller":"traceutil/trace.go:171","msg":"trace[33940667] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"335.824143ms","start":"2026-08-22T14:35:54.722952Z","end":"2026-08-22T14:35:55.058776Z","steps":["trace[33940667] 'process raft request' (duration: 335.654342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:55.05897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:35:54.722944Z","time spent":"335.941964ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:35:55.059409Z","caller":"traceutil/trace.go:171","msg":"trace[1161836888] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:483; }","duration":"334.863684ms","start":"2026-08-22T14:35:54.724527Z","end":"2026-08-22T14:35:55.059391Z","steps":["trace[1161836888] 'read index received' (duration: 334.054287ms)","trace[1161836888] 'applied index is now lower than readState.Index' (duration: 808.637µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:35:55.059504Z","caller":"traceutil/trace.go:171","msg":"trace[399724029] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"335.54735ms","start":"2026-08-22T14:35:54.723935Z","end":"2026-08-22T14:35:55.059482Z","steps":["trace[399724029] 'process raft request' (duration: 335.384488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:55.05956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.359727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:35:55.059588Z","caller":"traceutil/trace.go:171","msg":"trace[933743089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"241.419078ms","start":"2026-08-22T14:35:54.818161Z","end":"2026-08-22T14:35:55.05958Z","steps":["trace[933743089] 'agreement among raft nodes before linearized reading' (duration: 241.387348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:55.059615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:35:54.723923Z","time spent":"335.64137ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:35:55.059531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.969619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-22T14:35:55.059526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.980195ms","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-08-22T14:35:55.059787Z","caller":"traceutil/trace.go:171","msg":"trace[281645592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"332.222032ms","start":"2026-08-22T14:35:54.727545Z","end":"2026-08-22T14:35:55.059767Z","steps":["trace[281645592] 'agreement among raft nodes before linearized reading' (duration: 331.955979ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:55.059863Z","caller":"traceutil/trace.go:171","msg":"trace[194665708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"335.342809ms","start":"2026-08-22T14:35:54.724513Z","end":"2026-08-22T14:35:55.059855Z","steps":["trace[194665708] 'agreement among raft nodes before linearized reading' (duration: 334.968925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:55.059892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:35:54.724505Z","time spent":"335.380259ms","remote":"127.0.0.1:37038","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-08-22T14:35:55.059879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:35:54.727538Z","time spent":"332.325333ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T14:36:06.643526Z","caller":"traceutil/trace.go:171","msg":"trace[1864986464] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"809.465029ms","start":"2026-08-22T14:36:05.834029Z","end":"2026-08-22T14:36:06.643494Z","steps":["trace[1864986464] 'process raft request' (duration: 809.317268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:06.643699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.834011Z","time spent":"809.60983ms","remote":"127.0.0.1:37038","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-08-22T14:36:07.524461Z","caller":"traceutil/trace.go:171","msg":"trace[531183555] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.604936883s","start":"2026-08-22T14:36:05.919488Z","end":"2026-08-22T14:36:07.524424Z","steps":["trace[531183555] 'process raft request' (duration: 1.604700521s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:07.524592Z","caller":"traceutil/trace.go:171","msg":"trace[1598647408] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.598014553s","start":"2026-08-22T14:36:05.926549Z","end":"2026-08-22T14:36:07.524563Z","steps":["trace[1598647408] 'process raft request' (duration: 1.597838412s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.524618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.91947Z","time spent":"1.605078794s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:07.524708Z","caller":"traceutil/trace.go:171","msg":"trace[452740598] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.588113257s","start":"2026-08-22T14:36:05.936519Z","end":"2026-08-22T14:36:07.524632Z","steps":["trace[452740598] 'process raft request' (duration: 1.587922735s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:07.524873Z","caller":"traceutil/trace.go:171","msg":"trace[1832243967] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.574071635s","start":"2026-08-22T14:36:05.95079Z","end":"2026-08-22T14:36:07.524862Z","steps":["trace[1832243967] 'process raft request' (duration: 1.573931394s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:07.524865Z","caller":"traceutil/trace.go:171","msg":"trace[2067667058] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.574375988s","start":"2026-08-22T14:36:05.95046Z","end":"2026-08-22T14:36:07.524835Z","steps":["trace[2067667058] 'process raft request' (duration: 1.574169697s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.524927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.950766Z","time spent":"1.574138556s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:07.525117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.950441Z","time spent":"1.5745743s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:07.525213Z","caller":"traceutil/trace.go:171","msg":"trace[392055949] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.572496002s","start":"2026-08-22T14:36:05.952694Z","end":"2026-08-22T14:36:07.52519Z","steps":["trace[392055949] 'process raft request' (duration: 1.572102918s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:07.52519Z","caller":"traceutil/trace.go:171","msg":"trace[1014694405] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.598026153s","start":"2026-08-22T14:36:05.926492Z","end":"2026-08-22T14:36:07.524518Z","steps":["trace[1014694405] 'process raft request' (duration: 1.597821561s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.525328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.952685Z","time spent":"1.572597963s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:07.5254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.926474Z","time spent":"1.59883842s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:07.525629Z","caller":"traceutil/trace.go:171","msg":"trace[124425473] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.568912741s","start":"2026-08-22T14:36:05.956693Z","end":"2026-08-22T14:36:07.525606Z","steps":["trace[124425473] 'process raft request' (duration: 1.568176354s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.525717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.956684Z","time spent":"1.569003862s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:07.524917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.9365Z","time spent":"1.588318248s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:07.52475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.926544Z","time spent":"1.598128864s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:07.525932Z","caller":"traceutil/trace.go:171","msg":"trace[771471966] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.561806479s","start":"2026-08-22T14:36:05.96411Z","end":"2026-08-22T14:36:07.525917Z","steps":["trace[771471966] 'process raft request' (duration: 1.561359755s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.526016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.964092Z","time spent":"1.561890319s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:07.526494Z","caller":"traceutil/trace.go:171","msg":"trace[1126132158] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.562281313s","start":"2026-08-22T14:36:05.964199Z","end":"2026-08-22T14:36:07.52648Z","steps":["trace[1126132158] 'process raft request' (duration: 1.56204621s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.526574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.964194Z","time spent":"1.562351764s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:07.526832Z","caller":"traceutil/trace.go:171","msg":"trace[1808477890] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:548; }","duration":"401.294273ms","start":"2026-08-22T14:36:07.125512Z","end":"2026-08-22T14:36:07.526806Z","steps":["trace[1808477890] 'read index received' (duration: 398.572799ms)","trace[1808477890] 'applied index is now lower than readState.Index' (duration: 2.720444ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:07.526951Z","caller":"traceutil/trace.go:171","msg":"trace[936096314] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.525414185s","start":"2026-08-22T14:36:06.001525Z","end":"2026-08-22T14:36:07.526939Z","steps":["trace[936096314] 'process raft request' (duration: 1.524913171s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.527014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:06.001503Z","time spent":"1.525476705s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:07.52715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.390747ms","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-08-22T14:36:07.53494Z","caller":"traceutil/trace.go:171","msg":"trace[1956788143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"398.500138ms","start":"2026-08-22T14:36:07.128683Z","end":"2026-08-22T14:36:07.527183Z","steps":["trace[1956788143] 'agreement among raft nodes before linearized reading' (duration: 398.329676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.535024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:07.128674Z","time spent":"406.325826ms","remote":"127.0.0.1:37038","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-08-22T14:36:07.535346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.830426ms","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-08-22T14:36:07.535393Z","caller":"traceutil/trace.go:171","msg":"trace[1202808529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"409.923037ms","start":"2026-08-22T14:36:07.125451Z","end":"2026-08-22T14:36:07.535375Z","steps":["trace[1202808529] 'agreement among raft nodes before linearized reading' (duration: 401.549394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.535421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:07.125431Z","time spent":"409.982817ms","remote":"127.0.0.1:37038","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-08-22T14:36:07.535426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.821423ms","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-08-22T14:36:07.535492Z","caller":"traceutil/trace.go:171","msg":"trace[1253001074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"344.917754ms","start":"2026-08-22T14:36:07.190561Z","end":"2026-08-22T14:36:07.535478Z","steps":["trace[1253001074] 'agreement among raft nodes before linearized reading' (duration: 344.731612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.535523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:07.190509Z","time spent":"345.007315ms","remote":"127.0.0.1:37038","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-08-22T14:36:07.535537Z","caller":"traceutil/trace.go:171","msg":"trace[756823790] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.433044094s","start":"2026-08-22T14:36:06.094135Z","end":"2026-08-22T14:36:07.527179Z","steps":["trace[756823790] 'process raft request' (duration: 1.432485099s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.535594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:06.094109Z","time spent":"1.441450526s","remote":"127.0.0.1:36852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:07.53573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.543948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-22T14:36:07.535762Z","caller":"traceutil/trace.go:171","msg":"trace[289751458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"405.61361ms","start":"2026-08-22T14:36:07.130136Z","end":"2026-08-22T14:36:07.53575Z","steps":["trace[289751458] 'agreement among raft nodes before linearized reading' (duration: 405.534089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.535794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:07.130112Z","time spent":"405.67619ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T14:36:07.861464Z","caller":"traceutil/trace.go:171","msg":"trace[517928208] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"168.172415ms","start":"2026-08-22T14:36:07.693267Z","end":"2026-08-22T14:36:07.861439Z","steps":["trace[517928208] 'process raft request' (duration: 145.016104ms)","trace[517928208] 'compare' (duration: 22.952779ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:07.861532Z","caller":"traceutil/trace.go:171","msg":"trace[1697901260] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"167.956704ms","start":"2026-08-22T14:36:07.693559Z","end":"2026-08-22T14:36:07.861516Z","steps":["trace[1697901260] 'process raft request' (duration: 167.880963ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:07.861592Z","caller":"traceutil/trace.go:171","msg":"trace[1299316174] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"168.035164ms","start":"2026-08-22T14:36:07.693515Z","end":"2026-08-22T14:36:07.861551Z","steps":["trace[1299316174] 'process raft request' (duration: 167.861792ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:07.861827Z","caller":"traceutil/trace.go:171","msg":"trace[1663294564] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"166.880634ms","start":"2026-08-22T14:36:07.694904Z","end":"2026-08-22T14:36:07.861785Z","steps":["trace[1663294564] 'process raft request' (duration: 166.576661ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:07.862004Z","caller":"traceutil/trace.go:171","msg":"trace[939428073] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"109.000873ms","start":"2026-08-22T14:36:07.752981Z","end":"2026-08-22T14:36:07.861982Z","steps":["trace[939428073] 'process raft request' (duration: 108.854132ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:07.862085Z","caller":"traceutil/trace.go:171","msg":"trace[312766967] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:587; }","duration":"148.731407ms","start":"2026-08-22T14:36:07.713335Z","end":"2026-08-22T14:36:07.862067Z","steps":["trace[312766967] 'read index received' (duration: 125.008611ms)","trace[312766967] 'applied index is now lower than readState.Index' (duration: 23.720976ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:07.863969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.007935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-08-22T14:36:07.868352Z","caller":"traceutil/trace.go:171","msg":"trace[1556304615] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"186.400522ms","start":"2026-08-22T14:36:07.681905Z","end":"2026-08-22T14:36:07.868306Z","steps":["trace[1556304615] 'agreement among raft nodes before linearized reading' (duration: 180.208078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.864473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.482419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-08-22T14:36:07.868536Z","caller":"traceutil/trace.go:171","msg":"trace[1881804787] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"186.608944ms","start":"2026-08-22T14:36:07.681918Z","end":"2026-08-22T14:36:07.868527Z","steps":["trace[1881804787] 'agreement among raft nodes before linearized reading' (duration: 180.190488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:07.864642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.513358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-08-22T14:36:07.868652Z","caller":"traceutil/trace.go:171","msg":"trace[1221181932] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"186.631674ms","start":"2026-08-22T14:36:07.682003Z","end":"2026-08-22T14:36:07.868635Z","steps":["trace[1221181932] 'agreement among raft nodes before linearized reading' (duration: 180.076497ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:09.021715Z","caller":"traceutil/trace.go:171","msg":"trace[1894798506] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"130.548898ms","start":"2026-08-22T14:36:08.890299Z","end":"2026-08-22T14:36:09.020848Z","steps":["trace[1894798506] 'process raft request' (duration: 106.711182ms)","trace[1894798506] 'compare' (duration: 23.699095ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:09.020852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.063897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-08-22T14:36:09.025993Z","caller":"traceutil/trace.go:171","msg":"trace[412098452] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"102.376716ms","start":"2026-08-22T14:36:08.923589Z","end":"2026-08-22T14:36:09.025966Z","steps":["trace[412098452] 'process raft request' (duration: 100.827991ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:09.026103Z","caller":"traceutil/trace.go:171","msg":"trace[1990878254] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:597; }","duration":"148.295022ms","start":"2026-08-22T14:36:08.877725Z","end":"2026-08-22T14:36:09.02602Z","steps":["trace[1990878254] 'range keys from in-memory index tree' (duration: 142.900036ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:15.156514Z","caller":"traceutil/trace.go:171","msg":"trace[537730329] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"339.364782ms","start":"2026-08-22T14:36:14.817122Z","end":"2026-08-22T14:36:15.156487Z","steps":["trace[537730329] 'read index received' (duration: 338.080841ms)","trace[537730329] 'applied index is now lower than readState.Index' (duration: 1.282721ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:15.156615Z","caller":"traceutil/trace.go:171","msg":"trace[1825570759] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"849.104863ms","start":"2026-08-22T14:36:14.30749Z","end":"2026-08-22T14:36:15.156595Z","steps":["trace[1825570759] 'process raft request' (duration: 847.980843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:15.156658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.517443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:15.156697Z","caller":"traceutil/trace.go:171","msg":"trace[802490018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"339.614194ms","start":"2026-08-22T14:36:14.817071Z","end":"2026-08-22T14:36:15.156685Z","steps":["trace[802490018] 'agreement among raft nodes before linearized reading' (duration: 339.513543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:15.156733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:14.81705Z","time spent":"339.675914ms","remote":"127.0.0.1:36808","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-08-22T14:36:15.156742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:14.307458Z","time spent":"849.217654ms","remote":"127.0.0.1:36882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:17.234192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.797411836s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:36:17.23449Z","caller":"traceutil/trace.go:171","msg":"trace[454587465] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"1.636431111s","start":"2026-08-22T14:36:15.597986Z","end":"2026-08-22T14:36:17.234417Z","steps":["trace[454587465] 'read index received' (duration: 32.631µs)","trace[454587465] 'applied index is now lower than readState.Index' (duration: 1.63639547s)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:17.234517Z","caller":"traceutil/trace.go:171","msg":"trace[1929539570] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"539.096537ms","start":"2026-08-22T14:36:16.695397Z","end":"2026-08-22T14:36:17.234493Z","steps":["trace[1929539570] 'process raft request' (duration: 538.941165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:17.234627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:16.695371Z","time spent":"539.198538ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:17.234772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636771034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T14:36:17.234872Z","caller":"traceutil/trace.go:171","msg":"trace[70594576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.636917725s","start":"2026-08-22T14:36:15.597937Z","end":"2026-08-22T14:36:17.234854Z","steps":["trace[70594576] 'agreement among raft nodes before linearized reading' (duration: 1.636672433s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:17.234922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:15.597918Z","time spent":"1.636993036s","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T14:36:17.235006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595452078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T14:36:17.235063Z","caller":"traceutil/trace.go:171","msg":"trace[948059693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.595524808s","start":"2026-08-22T14:36:15.639522Z","end":"2026-08-22T14:36:17.235046Z","steps":["trace[948059693] 'agreement among raft nodes before linearized reading' (duration: 1.595254095s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:17.235037Z","caller":"traceutil/trace.go:171","msg":"trace[727189978] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"2.021266412s","start":"2026-08-22T14:36:15.213078Z","end":"2026-08-22T14:36:17.234344Z","steps":["trace[727189978] 'process raft request' (duration: 223.613644ms)","trace[727189978] 'compare' (duration: 1.796497488s)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:17.235108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:15.639509Z","time spent":"1.595589449s","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:36:17.235181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598113601s","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-08-22T14:36:17.235226Z","caller":"traceutil/trace.go:171","msg":"trace[1628696980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"1.598189291s","start":"2026-08-22T14:36:15.637025Z","end":"2026-08-22T14:36:17.235215Z","steps":["trace[1628696980] 'agreement among raft nodes before linearized reading' (duration: 1.598098751s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:17.235225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:15.213052Z","time spent":"2.022087041s","remote":"127.0.0.1:37126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:17.235256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:15.637001Z","time spent":"1.598248332s","remote":"127.0.0.1:37038","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-08-22T14:36:17.235083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413514302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:36:17.235052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602103045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T14:36:17.23535Z","caller":"traceutil/trace.go:171","msg":"trace[871448564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"1.413794084s","start":"2026-08-22T14:36:15.821528Z","end":"2026-08-22T14:36:17.235322Z","steps":["trace[871448564] 'agreement among raft nodes before linearized reading' (duration: 1.413526662s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:17.235389Z","caller":"traceutil/trace.go:171","msg":"trace[427463182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"1.60253526s","start":"2026-08-22T14:36:15.632834Z","end":"2026-08-22T14:36:17.235369Z","steps":["trace[427463182] 'agreement among raft nodes before linearized reading' (duration: 1.601821383s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:17.235427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:15.821508Z","time spent":"1.413889575s","remote":"127.0.0.1:36814","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-08-22T14:36:17.235479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:15.632812Z","time spent":"1.60265089s","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T14:36:17.536894Z","caller":"traceutil/trace.go:171","msg":"trace[1541303967] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"290.441007ms","start":"2026-08-22T14:36:17.24643Z","end":"2026-08-22T14:36:17.536871Z","steps":["trace[1541303967] 'process raft request' (duration: 290.221855ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:17.53724Z","caller":"traceutil/trace.go:171","msg":"trace[819616877] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"290.7292ms","start":"2026-08-22T14:36:17.246499Z","end":"2026-08-22T14:36:17.537228Z","steps":["trace[819616877] 'process raft request' (duration: 290.316236ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:17.537256Z","caller":"traceutil/trace.go:171","msg":"trace[657533381] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"243.466293ms","start":"2026-08-22T14:36:17.293766Z","end":"2026-08-22T14:36:17.537232Z","steps":["trace[657533381] 'read index received' (duration: 242.890728ms)","trace[657533381] 'applied index is now lower than readState.Index' (duration: 574.615µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:17.537352Z","caller":"traceutil/trace.go:171","msg":"trace[1519333188] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"290.396747ms","start":"2026-08-22T14:36:17.246861Z","end":"2026-08-22T14:36:17.537257Z","steps":["trace[1519333188] 'process raft request' (duration: 290.270836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:17.537482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.003573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:17.537576Z","caller":"traceutil/trace.go:171","msg":"trace[1359138693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"291.206924ms","start":"2026-08-22T14:36:17.246353Z","end":"2026-08-22T14:36:17.53756Z","steps":["trace[1359138693] 'agreement among raft nodes before linearized reading' (duration: 290.985932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:19.317224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120581734643833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:36:19.81788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120581734643833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:36:20.17418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.808479ms","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-08-22T14:36:20.174263Z","caller":"traceutil/trace.go:171","msg":"trace[806768953] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:639; }","duration":"732.898792ms","start":"2026-08-22T14:36:19.44135Z","end":"2026-08-22T14:36:20.174248Z","steps":["trace[806768953] 'range keys from in-memory index tree' (duration: 732.797209ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:20.175781Z","caller":"traceutil/trace.go:171","msg":"trace[2019264850] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.495034577s","start":"2026-08-22T14:36:17.680736Z","end":"2026-08-22T14:36:20.175771Z","steps":["trace[2019264850] 'process raft request' (duration: 2.492865467s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:20.175884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:17.680713Z","time spent":"2.495101759s","remote":"127.0.0.1:36882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:20.392379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.714916ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T14:36:20.392616Z","caller":"traceutil/trace.go:171","msg":"trace[193836870] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"161.402501ms","start":"2026-08-22T14:36:20.231195Z","end":"2026-08-22T14:36:20.392598Z","steps":["trace[193836870] 'process raft request' (duration: 161.285177ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:20.392613Z","caller":"traceutil/trace.go:171","msg":"trace[1021951262] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"1.576357128s","start":"2026-08-22T14:36:18.816223Z","end":"2026-08-22T14:36:20.39258Z","steps":["trace[1021951262] 'read index received' (duration: 1.35712788s)","trace[1021951262] 'applied index is now lower than readState.Index' (duration: 219.223827ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:20.392839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576601825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:20.392949Z","caller":"traceutil/trace.go:171","msg":"trace[1436132021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.576747549s","start":"2026-08-22T14:36:18.816183Z","end":"2026-08-22T14:36:20.392931Z","steps":["trace[1436132021] 'agreement among raft nodes before linearized reading' (duration: 1.576570094s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:20.393191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:18.816162Z","time spent":"1.577021087s","remote":"127.0.0.1:36814","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-08-22T14:36:20.392972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094707897s","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-08-22T14:36:20.393753Z","caller":"traceutil/trace.go:171","msg":"trace[328938069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.095549114s","start":"2026-08-22T14:36:19.298191Z","end":"2026-08-22T14:36:20.39374Z","steps":["trace[328938069] 'agreement among raft nodes before linearized reading' (duration: 1.094599373s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:20.393878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:19.298168Z","time spent":"1.095694947s","remote":"127.0.0.1:37038","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-08-22T14:36:20.393079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.251747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T14:36:20.39402Z","caller":"traceutil/trace.go:171","msg":"trace[833296001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"852.293069ms","start":"2026-08-22T14:36:19.541695Z","end":"2026-08-22T14:36:20.393988Z","steps":["trace[833296001] 'agreement among raft nodes before linearized reading' (duration: 851.128752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:20.393099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.169429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-22T14:36:20.394146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:19.541674Z","time spent":"852.433674ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T14:36:20.394214Z","caller":"traceutil/trace.go:171","msg":"trace[284958504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"853.293464ms","start":"2026-08-22T14:36:19.540876Z","end":"2026-08-22T14:36:20.39417Z","steps":["trace[284958504] 'agreement among raft nodes before linearized reading' (duration: 852.141887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:20.394304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:19.540854Z","time spent":"853.434148ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:36:20.393126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.159079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T14:36:20.394386Z","caller":"traceutil/trace.go:171","msg":"trace[669866595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"853.45147ms","start":"2026-08-22T14:36:19.540922Z","end":"2026-08-22T14:36:20.394373Z","steps":["trace[669866595] 'agreement among raft nodes before linearized reading' (duration: 852.089197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:20.394435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:19.5409Z","time spent":"853.521832ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T14:36:20.94745Z","caller":"traceutil/trace.go:171","msg":"trace[1257832537] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"101.056246ms","start":"2026-08-22T14:36:20.846364Z","end":"2026-08-22T14:36:20.94742Z","steps":["trace[1257832537] 'process raft request' (duration: 100.414106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:21.772896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.603156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:36:21.773179Z","caller":"traceutil/trace.go:171","msg":"trace[1400499314] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"625.682579ms","start":"2026-08-22T14:36:21.147478Z","end":"2026-08-22T14:36:21.773161Z","steps":["trace[1400499314] 'process raft request' (duration: 625.603537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:21.773303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:21.147454Z","time spent":"625.803502ms","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:21.773288Z","caller":"traceutil/trace.go:171","msg":"trace[1431559745] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"640.861701ms","start":"2026-08-22T14:36:21.132387Z","end":"2026-08-22T14:36:21.773249Z","steps":["trace[1431559745] 'process raft request' (duration: 237.827982ms)","trace[1431559745] 'compare' (duration: 402.421399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:21.77353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:21.132367Z","time spent":"641.096739ms","remote":"127.0.0.1:36876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:21.853813Z","caller":"traceutil/trace.go:171","msg":"trace[1275305279] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"346.351768ms","start":"2026-08-22T14:36:21.507429Z","end":"2026-08-22T14:36:21.853781Z","steps":["trace[1275305279] 'process raft request' (duration: 345.78615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:21.853994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:21.5074Z","time spent":"346.498114ms","remote":"127.0.0.1:36882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:22.796551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.901683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T14:36:22.796653Z","caller":"traceutil/trace.go:171","msg":"trace[1482891583] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:660; }","duration":"110.055658ms","start":"2026-08-22T14:36:22.686577Z","end":"2026-08-22T14:36:22.796633Z","steps":["trace[1482891583] 'range keys from in-memory index tree' (duration: 109.697926ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:23.072515Z","caller":"traceutil/trace.go:171","msg":"trace[1463099803] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"141.457928ms","start":"2026-08-22T14:36:22.931009Z","end":"2026-08-22T14:36:23.072467Z","steps":["trace[1463099803] 'process raft request' (duration: 140.57719ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:23.450717Z","caller":"traceutil/trace.go:171","msg":"trace[199153392] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"204.88899ms","start":"2026-08-22T14:36:23.245806Z","end":"2026-08-22T14:36:23.450695Z","steps":["trace[199153392] 'process raft request' (duration: 140.346411ms)","trace[199153392] 'compare' (duration: 64.366574ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:25.187162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.249349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:25.187308Z","caller":"traceutil/trace.go:171","msg":"trace[1965838422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"370.432035ms","start":"2026-08-22T14:36:24.816858Z","end":"2026-08-22T14:36:25.187291Z","steps":["trace[1965838422] 'range keys from in-memory index tree' (duration: 370.177137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:25.187575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:24.816842Z","time spent":"370.689052ms","remote":"127.0.0.1:36814","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-08-22T14:36:27.099643Z","caller":"traceutil/trace.go:171","msg":"trace[767027512] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"158.682987ms","start":"2026-08-22T14:36:26.940923Z","end":"2026-08-22T14:36:27.099606Z","steps":["trace[767027512] 'process raft request' (duration: 158.44395ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:30.70412Z","caller":"traceutil/trace.go:171","msg":"trace[1870709326] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"264.844844ms","start":"2026-08-22T14:36:30.439252Z","end":"2026-08-22T14:36:30.704096Z","steps":["trace[1870709326] 'process raft request' (duration: 264.69889ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:30.74746Z","caller":"traceutil/trace.go:171","msg":"trace[1018678558] transaction","detail":"{read_only:false; number_of_response:0; response_revision:760; }","duration":"308.163248ms","start":"2026-08-22T14:36:30.439268Z","end":"2026-08-22T14:36:30.747431Z","steps":["trace[1018678558] 'process raft request' (duration: 308.089866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:30.747593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:30.439234Z","time spent":"308.296012ms","remote":"127.0.0.1:38650","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:36:30.74761Z","caller":"traceutil/trace.go:171","msg":"trace[176563107] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:789; }","duration":"203.434191ms","start":"2026-08-22T14:36:30.544155Z","end":"2026-08-22T14:36:30.747589Z","steps":["trace[176563107] 'read index received' (duration: 159.851479ms)","trace[176563107] 'applied index is now lower than readState.Index' (duration: 43.581252ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:30.747628Z","caller":"traceutil/trace.go:171","msg":"trace[326439896] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"106.387696ms","start":"2026-08-22T14:36:30.641222Z","end":"2026-08-22T14:36:30.74761Z","steps":["trace[326439896] 'process raft request' (duration: 106.245461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:30.747851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.366157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:36:30.747917Z","caller":"traceutil/trace.go:171","msg":"trace[1381386962] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:761; }","duration":"137.47794ms","start":"2026-08-22T14:36:30.610424Z","end":"2026-08-22T14:36:30.747902Z","steps":["trace[1381386962] 'agreement among raft nodes before linearized reading' (duration: 137.368527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:30.748003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.840554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T14:36:30.748083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.764039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T14:36:30.748135Z","caller":"traceutil/trace.go:171","msg":"trace[298476312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"196.828871ms","start":"2026-08-22T14:36:30.55129Z","end":"2026-08-22T14:36:30.748119Z","steps":["trace[298476312] 'agreement among raft nodes before linearized reading' (duration: 196.524712ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:30.748186Z","caller":"traceutil/trace.go:171","msg":"trace[1868161571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"203.992758ms","start":"2026-08-22T14:36:30.544108Z","end":"2026-08-22T14:36:30.748101Z","steps":["trace[1868161571] 'agreement among raft nodes before linearized reading' (duration: 203.573036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:30.748255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.093868ms","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-08-22T14:36:30.748303Z","caller":"traceutil/trace.go:171","msg":"trace[506462199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"196.171101ms","start":"2026-08-22T14:36:30.552117Z","end":"2026-08-22T14:36:30.748289Z","steps":["trace[506462199] 'agreement among raft nodes before linearized reading' (duration: 195.672766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:30.748492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.305306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T14:36:30.748566Z","caller":"traceutil/trace.go:171","msg":"trace[993179876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"200.381239ms","start":"2026-08-22T14:36:30.54817Z","end":"2026-08-22T14:36:30.748551Z","steps":["trace[993179876] 'agreement among raft nodes before linearized reading' (duration: 199.551414ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:30.939331Z","caller":"traceutil/trace.go:171","msg":"trace[1202567489] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"187.663973ms","start":"2026-08-22T14:36:30.751629Z","end":"2026-08-22T14:36:30.939293Z","steps":["trace[1202567489] 'process raft request' (duration: 157.601651ms)","trace[1202567489] 'compare' (duration: 29.616959ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:30.947628Z","caller":"traceutil/trace.go:171","msg":"trace[1073273655] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"195.051517ms","start":"2026-08-22T14:36:30.75256Z","end":"2026-08-22T14:36:30.947612Z","steps":["trace[1073273655] 'process raft request' (duration: 194.979645ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:30.947677Z","caller":"traceutil/trace.go:171","msg":"trace[583813871] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"195.238892ms","start":"2026-08-22T14:36:30.752402Z","end":"2026-08-22T14:36:30.947641Z","steps":["trace[583813871] 'process raft request' (duration: 195.056897ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:30.947882Z","caller":"traceutil/trace.go:171","msg":"trace[735122018] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:792; }","duration":"131.19962ms","start":"2026-08-22T14:36:30.816568Z","end":"2026-08-22T14:36:30.947768Z","steps":["trace[735122018] 'read index received' (duration: 92.676201ms)","trace[735122018] 'applied index is now lower than readState.Index' (duration: 38.522189ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:30.947976Z","caller":"traceutil/trace.go:171","msg":"trace[2049068188] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"195.180732ms","start":"2026-08-22T14:36:30.752698Z","end":"2026-08-22T14:36:30.947878Z","steps":["trace[2049068188] 'process raft request' (duration: 194.881372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:30.948144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.428147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:30.94879Z","caller":"traceutil/trace.go:171","msg":"trace[2059920660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"131.646223ms","start":"2026-08-22T14:36:30.816552Z","end":"2026-08-22T14:36:30.948199Z","steps":["trace[2059920660] 'agreement among raft nodes before linearized reading' (duration: 131.397026ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:31.276307Z","caller":"traceutil/trace.go:171","msg":"trace[731827698] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"166.587608ms","start":"2026-08-22T14:36:31.109695Z","end":"2026-08-22T14:36:31.276282Z","steps":["trace[731827698] 'process raft request' (duration: 166.419103ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:32.627516Z","caller":"traceutil/trace.go:171","msg":"trace[803716250] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"108.010743ms","start":"2026-08-22T14:36:32.519484Z","end":"2026-08-22T14:36:32.627495Z","steps":["trace[803716250] 'process raft request' (duration: 55.699173ms)","trace[803716250] 'compare' (duration: 52.217258ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:32.774298Z","caller":"traceutil/trace.go:171","msg":"trace[1313257714] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"118.068346ms","start":"2026-08-22T14:36:32.656206Z","end":"2026-08-22T14:36:32.774274Z","steps":["trace[1313257714] 'process raft request' (duration: 117.404215ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:32.913014Z","caller":"traceutil/trace.go:171","msg":"trace[1702949912] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"124.567221ms","start":"2026-08-22T14:36:32.78843Z","end":"2026-08-22T14:36:32.912997Z","steps":["trace[1702949912] 'process raft request' (duration: 122.988814ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:32.947062Z","caller":"traceutil/trace.go:171","msg":"trace[2093304483] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"141.259012ms","start":"2026-08-22T14:36:32.80578Z","end":"2026-08-22T14:36:32.947039Z","steps":["trace[2093304483] 'process raft request' (duration: 141.002625ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:32.947096Z","caller":"traceutil/trace.go:171","msg":"trace[1033638379] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"132.183649ms","start":"2026-08-22T14:36:32.814887Z","end":"2026-08-22T14:36:32.94707Z","steps":["trace[1033638379] 'read index received' (duration: 96.56404ms)","trace[1033638379] 'applied index is now lower than readState.Index' (duration: 35.618749ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:32.947466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.563391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:32.947517Z","caller":"traceutil/trace.go:171","msg":"trace[122862488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"132.653084ms","start":"2026-08-22T14:36:32.814852Z","end":"2026-08-22T14:36:32.947505Z","steps":["trace[122862488] 'agreement among raft nodes before linearized reading' (duration: 132.567531ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:32.947666Z","caller":"traceutil/trace.go:171","msg":"trace[345976913] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"127.232311ms","start":"2026-08-22T14:36:32.820422Z","end":"2026-08-22T14:36:32.947655Z","steps":["trace[345976913] 'process raft request' (duration: 126.505969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:33.084306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.459326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T14:36:33.084339Z","caller":"traceutil/trace.go:171","msg":"trace[1939740132] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"125.395524ms","start":"2026-08-22T14:36:32.958913Z","end":"2026-08-22T14:36:33.084309Z","steps":["trace[1939740132] 'process raft request' (duration: 125.183178ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:33.084386Z","caller":"traceutil/trace.go:171","msg":"trace[1564217340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"131.56018ms","start":"2026-08-22T14:36:32.952809Z","end":"2026-08-22T14:36:33.084369Z","steps":["trace[1564217340] 'agreement among raft nodes before linearized reading' (duration: 131.379445ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:33.084423Z","caller":"traceutil/trace.go:171","msg":"trace[2116728661] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"128.266331ms","start":"2026-08-22T14:36:32.956121Z","end":"2026-08-22T14:36:33.084387Z","steps":["trace[2116728661] 'process raft request' (duration: 127.22134ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:33.084678Z","caller":"traceutil/trace.go:171","msg":"trace[1014303396] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"121.148648ms","start":"2026-08-22T14:36:32.963518Z","end":"2026-08-22T14:36:33.084667Z","steps":["trace[1014303396] 'process raft request' (duration: 120.622991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:33.084965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.482587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T14:36:33.085071Z","caller":"traceutil/trace.go:171","msg":"trace[1981644136] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:781; }","duration":"130.606191ms","start":"2026-08-22T14:36:32.954441Z","end":"2026-08-22T14:36:33.085047Z","steps":["trace[1981644136] 'agreement among raft nodes before linearized reading' (duration: 130.397275ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:35.647895Z","caller":"traceutil/trace.go:171","msg":"trace[1132341662] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"306.294942ms","start":"2026-08-22T14:36:35.341575Z","end":"2026-08-22T14:36:35.64787Z","steps":["trace[1132341662] 'read index received' (duration: 306.089977ms)","trace[1132341662] 'applied index is now lower than readState.Index' (duration: 204.045µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:35.64815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.55009ms","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-08-22T14:36:35.64819Z","caller":"traceutil/trace.go:171","msg":"trace[1557554802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"306.640513ms","start":"2026-08-22T14:36:35.34154Z","end":"2026-08-22T14:36:35.648181Z","steps":["trace[1557554802] 'agreement among raft nodes before linearized reading' (duration: 306.427766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:35.648228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:35.341523Z","time spent":"306.694114ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T14:36:35.648544Z","caller":"traceutil/trace.go:171","msg":"trace[918436689] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"370.779831ms","start":"2026-08-22T14:36:35.277723Z","end":"2026-08-22T14:36:35.648502Z","steps":["trace[918436689] 'process raft request' (duration: 369.997177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:35.648726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:35.277701Z","time spent":"370.940646ms","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:35.76458Z","caller":"traceutil/trace.go:171","msg":"trace[1503967968] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"110.575142ms","start":"2026-08-22T14:36:35.653977Z","end":"2026-08-22T14:36:35.764552Z","steps":["trace[1503967968] 'process raft request' (duration: 99.200835ms)","trace[1503967968] 'compare' (duration: 11.258303ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:36.076773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.147248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:36:36.07707Z","caller":"traceutil/trace.go:171","msg":"trace[2127628081] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:832; }","duration":"285.112526ms","start":"2026-08-22T14:36:35.791939Z","end":"2026-08-22T14:36:36.077052Z","steps":["trace[2127628081] 'read index received' (duration: 126.669249ms)","trace[2127628081] 'applied index is now lower than readState.Index' (duration: 158.442457ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:36.07708Z","caller":"traceutil/trace.go:171","msg":"trace[34321800] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"422.212472ms","start":"2026-08-22T14:36:35.654848Z","end":"2026-08-22T14:36:36.077061Z","steps":["trace[34321800] 'process raft request' (duration: 422.023267ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:36.077102Z","caller":"traceutil/trace.go:171","msg":"trace[729787684] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"422.396448ms","start":"2026-08-22T14:36:35.654683Z","end":"2026-08-22T14:36:36.07708Z","steps":["trace[729787684] 'process raft request' (duration: 263.859008ms)","trace[729787684] 'compare' (duration: 158.008824ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:36.077142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.194368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:36:36.077152Z","caller":"traceutil/trace.go:171","msg":"trace[981275224] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"419.571824ms","start":"2026-08-22T14:36:35.657545Z","end":"2026-08-22T14:36:36.077116Z","steps":["trace[981275224] 'process raft request' (duration: 419.43072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:36.077186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:35.654824Z","time spent":"422.300955ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:36.077195Z","caller":"traceutil/trace.go:171","msg":"trace[1963492872] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:802; }","duration":"285.277321ms","start":"2026-08-22T14:36:35.791905Z","end":"2026-08-22T14:36:36.077183Z","steps":["trace[1963492872] 'agreement among raft nodes before linearized reading' (duration: 285.202309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:36.077206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:35.654666Z","time spent":"422.48266ms","remote":"127.0.0.1:36964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:36.077304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:35.657536Z","time spent":"419.675557ms","remote":"127.0.0.1:37278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:36.65947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120581734644143,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:36:36.737232Z","caller":"traceutil/trace.go:171","msg":"trace[1118660280] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"651.854162ms","start":"2026-08-22T14:36:36.085353Z","end":"2026-08-22T14:36:36.737207Z","steps":["trace[1118660280] 'process raft request' (duration: 651.690837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:36.737392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:36.085333Z","time spent":"651.987495ms","remote":"127.0.0.1:37246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:36.781539Z","caller":"traceutil/trace.go:171","msg":"trace[1145846724] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:835; }","duration":"622.835778ms","start":"2026-08-22T14:36:36.158674Z","end":"2026-08-22T14:36:36.78151Z","steps":["trace[1145846724] 'read index received' (duration: 578.42119ms)","trace[1145846724] 'applied index is now lower than readState.Index' (duration: 44.413328ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:36.781682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.984612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:36:36.78185Z","caller":"traceutil/trace.go:171","msg":"trace[1550283679] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"556.397381ms","start":"2026-08-22T14:36:36.225429Z","end":"2026-08-22T14:36:36.781826Z","steps":["trace[1550283679] 'process raft request' (duration: 555.896276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:36.781981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:36.225406Z","time spent":"556.506874ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:36.781722Z","caller":"traceutil/trace.go:171","msg":"trace[1461326857] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:804; }","duration":"623.088365ms","start":"2026-08-22T14:36:36.158623Z","end":"2026-08-22T14:36:36.781711Z","steps":["trace[1461326857] 'agreement among raft nodes before linearized reading' (duration: 622.992043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:36.782492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:36.158572Z","time spent":"623.890659ms","remote":"127.0.0.1:37278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:36:40.701178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120581734644229,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:36:40.84281Z","caller":"traceutil/trace.go:171","msg":"trace[261371164] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"696.820258ms","start":"2026-08-22T14:36:40.145954Z","end":"2026-08-22T14:36:40.842774Z","steps":["trace[261371164] 'process raft request' (duration: 696.606311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:40.843039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:40.145919Z","time spent":"697.008303ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:41.132374Z","caller":"traceutil/trace.go:171","msg":"trace[694370123] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:857; }","duration":"931.67521ms","start":"2026-08-22T14:36:40.200673Z","end":"2026-08-22T14:36:41.132348Z","steps":["trace[694370123] 'read index received' (duration: 641.86548ms)","trace[694370123] 'applied index is now lower than readState.Index' (duration: 289.8084ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:41.132432Z","caller":"traceutil/trace.go:171","msg":"trace[672857108] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"838.125789ms","start":"2026-08-22T14:36:40.294268Z","end":"2026-08-22T14:36:41.132394Z","steps":["trace[672857108] 'process raft request' (duration: 837.926363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:41.132589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.901037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:36:41.132725Z","caller":"traceutil/trace.go:171","msg":"trace[239274076] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:825; }","duration":"932.079021ms","start":"2026-08-22T14:36:40.200618Z","end":"2026-08-22T14:36:41.132697Z","steps":["trace[239274076] 'agreement among raft nodes before linearized reading' (duration: 931.840914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:41.132839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:40.200607Z","time spent":"932.211666ms","remote":"127.0.0.1:37032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:36:41.132751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:40.294241Z","time spent":"838.381776ms","remote":"127.0.0.1:37016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:41.132975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.921473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:41.133113Z","caller":"traceutil/trace.go:171","msg":"trace[1798023400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"316.331995ms","start":"2026-08-22T14:36:40.816733Z","end":"2026-08-22T14:36:41.133065Z","steps":["trace[1798023400] 'agreement among raft nodes before linearized reading' (duration: 315.926103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:41.133167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:40.816712Z","time spent":"316.441738ms","remote":"127.0.0.1:36814","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-08-22T14:36:41.133323Z","caller":"traceutil/trace.go:171","msg":"trace[1357109311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:825; }","duration":"100.036973ms","start":"2026-08-22T14:36:41.033266Z","end":"2026-08-22T14:36:41.133303Z","steps":["trace[1357109311] 'agreement among raft nodes before linearized reading' (duration: 99.722795ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:42.388585Z","caller":"traceutil/trace.go:171","msg":"trace[1839740720] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"251.054798ms","start":"2026-08-22T14:36:42.137506Z","end":"2026-08-22T14:36:42.38856Z","steps":["trace[1839740720] 'process raft request' (duration: 250.903733ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:43.281908Z","caller":"traceutil/trace.go:171","msg":"trace[1445310279] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"130.883085ms","start":"2026-08-22T14:36:43.151003Z","end":"2026-08-22T14:36:43.281886Z","steps":["trace[1445310279] 'process raft request' (duration: 130.662118ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:43.281893Z","caller":"traceutil/trace.go:171","msg":"trace[1265501920] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"127.108797ms","start":"2026-08-22T14:36:43.154762Z","end":"2026-08-22T14:36:43.281871Z","steps":["trace[1265501920] 'read index received' (duration: 126.92152ms)","trace[1265501920] 'applied index is now lower than readState.Index' (duration: 185.906µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:43.282135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.350033ms","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-08-22T14:36:43.282185Z","caller":"traceutil/trace.go:171","msg":"trace[1815374894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"127.437116ms","start":"2026-08-22T14:36:43.154737Z","end":"2026-08-22T14:36:43.282174Z","steps":["trace[1815374894] 'agreement among raft nodes before linearized reading' (duration: 127.21264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:43.282352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.68037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-22T14:36:43.28235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.749767ms","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-08-22T14:36:43.282398Z","caller":"traceutil/trace.go:171","msg":"trace[1129317365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:836; }","duration":"103.753772ms","start":"2026-08-22T14:36:43.178633Z","end":"2026-08-22T14:36:43.282387Z","steps":["trace[1129317365] 'agreement among raft nodes before linearized reading' (duration: 103.639889ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:43.282434Z","caller":"traceutil/trace.go:171","msg":"trace[608337390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:836; }","duration":"126.88395ms","start":"2026-08-22T14:36:43.155527Z","end":"2026-08-22T14:36:43.282411Z","steps":["trace[608337390] 'agreement among raft nodes before linearized reading' (duration: 126.625083ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:43.525375Z","caller":"traceutil/trace.go:171","msg":"trace[49585037] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"129.018161ms","start":"2026-08-22T14:36:43.396334Z","end":"2026-08-22T14:36:43.525352Z","steps":["trace[49585037] 'process raft request' (duration: 97.409992ms)","trace[49585037] 'compare' (duration: 31.520307ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:03.753242Z","caller":"traceutil/trace.go:171","msg":"trace[1294625047] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"249.478322ms","start":"2026-08-22T14:37:03.50374Z","end":"2026-08-22T14:37:03.753219Z","steps":["trace[1294625047] 'read index received' (duration: 249.223956ms)","trace[1294625047] 'applied index is now lower than readState.Index' (duration: 253.516µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:03.753353Z","caller":"traceutil/trace.go:171","msg":"trace[597153792] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"349.618157ms","start":"2026-08-22T14:37:03.403724Z","end":"2026-08-22T14:37:03.753342Z","steps":["trace[597153792] 'process raft request' (duration: 349.35887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:03.753463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:03.403714Z","time spent":"349.663788ms","remote":"127.0.0.1:37038","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-08-22T14:37:03.753681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.939156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T14:37:03.753765Z","caller":"traceutil/trace.go:171","msg":"trace[1639338711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"250.059869ms","start":"2026-08-22T14:37:03.503691Z","end":"2026-08-22T14:37:03.753751Z","steps":["trace[1639338711] 'agreement among raft nodes before linearized reading' (duration: 249.869034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:03.753779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.516774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T14:37:03.754059Z","caller":"traceutil/trace.go:171","msg":"trace[1917324140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"237.79172ms","start":"2026-08-22T14:37:03.516227Z","end":"2026-08-22T14:37:03.754019Z","steps":["trace[1917324140] 'agreement among raft nodes before linearized reading' (duration: 237.36279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:03.75434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.713576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T14:37:03.75444Z","caller":"traceutil/trace.go:171","msg":"trace[1289348074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"240.84761ms","start":"2026-08-22T14:37:03.513573Z","end":"2026-08-22T14:37:03.754421Z","steps":["trace[1289348074] 'agreement among raft nodes before linearized reading' (duration: 239.883305ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:03.900169Z","caller":"traceutil/trace.go:171","msg":"trace[1695426091] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"142.038859ms","start":"2026-08-22T14:37:03.758101Z","end":"2026-08-22T14:37:03.90014Z","steps":["trace[1695426091] 'process raft request' (duration: 84.55749ms)","trace[1695426091] 'compare' (duration: 57.359366ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:04.052948Z","caller":"traceutil/trace.go:171","msg":"trace[1325231834] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"292.968565ms","start":"2026-08-22T14:37:03.759963Z","end":"2026-08-22T14:37:04.052931Z","steps":["trace[1325231834] 'process raft request' (duration: 281.436667ms)","trace[1325231834] 'compare' (duration: 11.412144ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:04.053234Z","caller":"traceutil/trace.go:171","msg":"trace[109757612] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1065; }","duration":"235.990989ms","start":"2026-08-22T14:37:03.817235Z","end":"2026-08-22T14:37:04.053226Z","steps":["trace[109757612] 'read index received' (duration: 25.440279ms)","trace[109757612] 'applied index is now lower than readState.Index' (duration: 210.549969ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:04.053224Z","caller":"traceutil/trace.go:171","msg":"trace[168809995] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"292.169034ms","start":"2026-08-22T14:37:03.761031Z","end":"2026-08-22T14:37:04.0532Z","steps":["trace[168809995] 'process raft request' (duration: 291.869176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:04.053343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.103491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:04.05337Z","caller":"traceutil/trace.go:171","msg":"trace[1630277785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"236.207754ms","start":"2026-08-22T14:37:03.817155Z","end":"2026-08-22T14:37:04.053363Z","steps":["trace[1630277785] 'agreement among raft nodes before linearized reading' (duration: 236.109511ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:05.192206Z","caller":"traceutil/trace.go:171","msg":"trace[1851741944] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"147.794053ms","start":"2026-08-22T14:37:05.044388Z","end":"2026-08-22T14:37:05.192182Z","steps":["trace[1851741944] 'process raft request' (duration: 147.602898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:05.570586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.717913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:05.570664Z","caller":"traceutil/trace.go:171","msg":"trace[1774993011] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1031; }","duration":"126.849216ms","start":"2026-08-22T14:37:05.443797Z","end":"2026-08-22T14:37:05.570646Z","steps":["trace[1774993011] 'count revisions from in-memory index tree' (duration: 126.62325ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:12.253228Z","caller":"traceutil/trace.go:171","msg":"trace[683049754] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"154.099305ms","start":"2026-08-22T14:37:12.099098Z","end":"2026-08-22T14:37:12.253197Z","steps":["trace[683049754] 'process raft request' (duration: 128.504038ms)","trace[683049754] 'compare' (duration: 25.275859ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:12.263082Z","caller":"traceutil/trace.go:171","msg":"trace[1281372786] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"163.433636ms","start":"2026-08-22T14:37:12.09962Z","end":"2026-08-22T14:37:12.263054Z","steps":["trace[1281372786] 'process raft request' (duration: 163.290052ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:20.246258Z","caller":"traceutil/trace.go:171","msg":"trace[325300238] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"100.355502ms","start":"2026-08-22T14:37:20.145881Z","end":"2026-08-22T14:37:20.246237Z","steps":["trace[325300238] 'process raft request' (duration: 100.238699ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:24.451882Z","caller":"traceutil/trace.go:171","msg":"trace[904191808] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"143.980872ms","start":"2026-08-22T14:37:24.307876Z","end":"2026-08-22T14:37:24.451857Z","steps":["trace[904191808] 'process raft request' (duration: 143.701395ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:25.481219Z","caller":"traceutil/trace.go:171","msg":"trace[377888153] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"125.623436ms","start":"2026-08-22T14:37:25.355569Z","end":"2026-08-22T14:37:25.481193Z","steps":["trace[377888153] 'process raft request' (duration: 125.489553ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:26.662261Z","caller":"traceutil/trace.go:171","msg":"trace[980378013] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"190.194936ms","start":"2026-08-22T14:37:26.472041Z","end":"2026-08-22T14:37:26.662236Z","steps":["trace[980378013] 'process raft request' (duration: 190.062653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:26.829468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.408059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:37:26.829688Z","caller":"traceutil/trace.go:171","msg":"trace[603559815] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"357.037576ms","start":"2026-08-22T14:37:26.472634Z","end":"2026-08-22T14:37:26.829672Z","steps":["trace[603559815] 'process raft request' (duration: 356.915683ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:26.829693Z","caller":"traceutil/trace.go:171","msg":"trace[1718638621] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"357.558258ms","start":"2026-08-22T14:37:26.472115Z","end":"2026-08-22T14:37:26.829673Z","steps":["trace[1718638621] 'process raft request' (duration: 225.877622ms)","trace[1718638621] 'compare' (duration: 131.305027ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:26.829743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:26.472625Z","time spent":"357.090748ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:37:26.829781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:26.472098Z","time spent":"357.631899ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:38:33.308493Z","caller":"traceutil/trace.go:171","msg":"trace[1901524070] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"126.539908ms","start":"2026-08-22T14:38:33.181926Z","end":"2026-08-22T14:38:33.308466Z","steps":["trace[1901524070] 'process raft request' (duration: 126.313204ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:33.989797Z","caller":"traceutil/trace.go:171","msg":"trace[1435116176] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"125.776834ms","start":"2026-08-22T14:38:33.863974Z","end":"2026-08-22T14:38:33.989751Z","steps":["trace[1435116176] 'process raft request' (duration: 125.56669ms)"],"step_count":1}