{"level":"warn","ts":"2026-08-20T20:22:24.003517Z","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-20T20:22:24.003649Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.182: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.182:2380","--initial-cluster=instance=https://199.204.45.182:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.182:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.182: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-20T20:22:24.003744Z","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-20T20:22:24.003762Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-08-20T20:22:24.003799Z","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-20T20:22:24.004516Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"]} {"level":"info","ts":"2026-08-20T20:22:24.004608Z","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.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.182: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-20T20:22:24.013755Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.848319ms"} {"level":"info","ts":"2026-08-20T20:22:24.076366Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e44ae1545340c14b","cluster-id":"7284b69afd367451"} {"level":"info","ts":"2026-08-20T20:22:24.076581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T20:22:24.076679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 0"} {"level":"info","ts":"2026-08-20T20:22:24.076712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e44ae1545340c14b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T20:22:24.076764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 1"} {"level":"info","ts":"2026-08-20T20:22:24.076864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"warn","ts":"2026-08-20T20:22:24.083945Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T20:22:24.08569Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T20:22:24.087045Z","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-20T20:22:24.09057Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e44ae1545340c14b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T20:22:24.098519Z","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-20T20:22:24.099257Z","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-20T20:22:24.099356Z","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-20T20:22:24.099369Z","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-20T20:22:24.099505Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e44ae1545340c14b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T20:22:24.099643Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-08-20T20:22:24.099668Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-08-20T20:22:24.10071Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T20:22:24.10066Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e44ae1545340c14b","initial-advertise-peer-urls":["https://199.204.45.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T20:22:24.101108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"info","ts":"2026-08-20T20:22:24.10133Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","added-peer-id":"e44ae1545340c14b","added-peer-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-08-20T20:22:24.177415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T20:22:24.177478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T20:22:24.177503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgPreVoteResp from e44ae1545340c14b at term 1"} {"level":"info","ts":"2026-08-20T20:22:24.177636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became candidate at term 2"} {"level":"info","ts":"2026-08-20T20:22:24.17773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgVoteResp from e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-08-20T20:22:24.177751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became leader at term 2"} {"level":"info","ts":"2026-08-20T20:22:24.177766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e44ae1545340c14b elected leader e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-08-20T20:22:24.1788Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e44ae1545340c14b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.182:2379]}","request-path":"/0/members/e44ae1545340c14b/attributes","cluster-id":"7284b69afd367451","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T20:22:24.178891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:22:24.179318Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T20:22:24.179412Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T20:22:24.178982Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T20:22:24.179971Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:22:24.180069Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:22:24.180099Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T20:22:24.179011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T20:22:24.183135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.182:2379"} {"level":"info","ts":"2026-08-20T20:22:24.183175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T20:22:34.726619Z","caller":"traceutil/trace.go:171","msg":"trace[756313380] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"333.140736ms","start":"2026-08-20T20:22:34.393443Z","end":"2026-08-20T20:22:34.726584Z","steps":["trace[756313380] 'process raft request' (duration: 246.819562ms)","trace[756313380] 'compare' (duration: 86.20504ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:22:34.72741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:22:34.393394Z","time spent":"333.363554ms","remote":"127.0.0.1:40044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:22:42.860716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.698142ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T20:22:42.860834Z","caller":"traceutil/trace.go:171","msg":"trace[2025981489] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"142.547901ms","start":"2026-08-20T20:22:42.718266Z","end":"2026-08-20T20:22:42.860814Z","steps":["trace[2025981489] 'read index received' (duration: 44.371µs)","trace[2025981489] 'applied index is now lower than readState.Index' (duration: 142.50215ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:22:42.860903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.629295ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:22:42.861116Z","caller":"traceutil/trace.go:171","msg":"trace[1480315970] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:375; }","duration":"142.826581ms","start":"2026-08-20T20:22:42.718255Z","end":"2026-08-20T20:22:42.861081Z","steps":["trace[1480315970] 'agreement among raft nodes before linearized reading' (duration: 142.610984ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:22:43.075515Z","caller":"traceutil/trace.go:171","msg":"trace[1252833188] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"124.478893ms","start":"2026-08-20T20:22:42.951004Z","end":"2026-08-20T20:22:43.075483Z","steps":["trace[1252833188] 'process raft request' (duration: 99.101523ms)","trace[1252833188] 'compare' (duration: 25.236885ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:23:05.969727Z","caller":"traceutil/trace.go:171","msg":"trace[1486131210] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"163.153774ms","start":"2026-08-20T20:23:05.80655Z","end":"2026-08-20T20:23:05.969703Z","steps":["trace[1486131210] 'process raft request' (duration: 163.00126ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:31.829476Z","caller":"traceutil/trace.go:171","msg":"trace[1600632653] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"152.113864ms","start":"2026-08-20T20:23:31.677325Z","end":"2026-08-20T20:23:31.829439Z","steps":["trace[1600632653] 'process raft request' (duration: 151.789567ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.593122Z","caller":"traceutil/trace.go:171","msg":"trace[1783364483] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"220.486345ms","start":"2026-08-20T20:23:32.372612Z","end":"2026-08-20T20:23:32.593099Z","steps":["trace[1783364483] 'process raft request' (duration: 220.303082ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.606481Z","caller":"traceutil/trace.go:171","msg":"trace[2027275854] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"222.536434ms","start":"2026-08-20T20:23:32.383929Z","end":"2026-08-20T20:23:32.606466Z","steps":["trace[2027275854] 'process raft request' (duration: 222.420602ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.60649Z","caller":"traceutil/trace.go:171","msg":"trace[802307388] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"230.970803ms","start":"2026-08-20T20:23:32.375498Z","end":"2026-08-20T20:23:32.606468Z","steps":["trace[802307388] 'process raft request' (duration: 230.742559ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.606665Z","caller":"traceutil/trace.go:171","msg":"trace[964035112] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"220.6747ms","start":"2026-08-20T20:23:32.385977Z","end":"2026-08-20T20:23:32.606652Z","steps":["trace[964035112] 'process raft request' (duration: 220.419525ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.606817Z","caller":"traceutil/trace.go:171","msg":"trace[90229794] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"220.75079ms","start":"2026-08-20T20:23:32.386056Z","end":"2026-08-20T20:23:32.606807Z","steps":["trace[90229794] 'process raft request' (duration: 220.561647ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.607044Z","caller":"traceutil/trace.go:171","msg":"trace[1748954707] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"220.256022ms","start":"2026-08-20T20:23:32.386779Z","end":"2026-08-20T20:23:32.607035Z","steps":["trace[1748954707] 'process raft request' (duration: 219.972046ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.607162Z","caller":"traceutil/trace.go:171","msg":"trace[1111674282] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"219.568439ms","start":"2026-08-20T20:23:32.387579Z","end":"2026-08-20T20:23:32.607147Z","steps":["trace[1111674282] 'process raft request' (duration: 219.359155ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.607226Z","caller":"traceutil/trace.go:171","msg":"trace[1889791793] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"212.091398ms","start":"2026-08-20T20:23:32.395128Z","end":"2026-08-20T20:23:32.607219Z","steps":["trace[1889791793] 'process raft request' (duration: 211.881804ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.607328Z","caller":"traceutil/trace.go:171","msg":"trace[1828234184] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"188.574566ms","start":"2026-08-20T20:23:32.418742Z","end":"2026-08-20T20:23:32.607317Z","steps":["trace[1828234184] 'process raft request' (duration: 188.371252ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.60748Z","caller":"traceutil/trace.go:171","msg":"trace[2084279840] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"185.505128ms","start":"2026-08-20T20:23:32.421967Z","end":"2026-08-20T20:23:32.607472Z","steps":["trace[2084279840] 'process raft request' (duration: 185.267743ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.608017Z","caller":"traceutil/trace.go:171","msg":"trace[1115383148] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"170.584987ms","start":"2026-08-20T20:23:32.437398Z","end":"2026-08-20T20:23:32.607983Z","steps":["trace[1115383148] 'process raft request' (duration: 169.978846ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:32.608283Z","caller":"traceutil/trace.go:171","msg":"trace[1725122758] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:580; }","duration":"168.817004ms","start":"2026-08-20T20:23:32.439452Z","end":"2026-08-20T20:23:32.608269Z","steps":["trace[1725122758] 'read index received' (duration: 153.511026ms)","trace[1725122758] 'applied index is now lower than readState.Index' (duration: 15.303508ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:23:32.608687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.17813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-20T20:23:32.608736Z","caller":"traceutil/trace.go:171","msg":"trace[1135759106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"169.302483ms","start":"2026-08-20T20:23:32.439422Z","end":"2026-08-20T20:23:32.608724Z","steps":["trace[1135759106] 'agreement among raft nodes before linearized reading' (duration: 168.933286ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:37.659275Z","caller":"traceutil/trace.go:171","msg":"trace[1045764688] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"208.241925ms","start":"2026-08-20T20:23:37.451011Z","end":"2026-08-20T20:23:37.659253Z","steps":["trace[1045764688] 'read index received' (duration: 208.135123ms)","trace[1045764688] 'applied index is now lower than readState.Index' (duration: 105.422µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:23:37.659645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.21658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T20:23:37.65972Z","caller":"traceutil/trace.go:171","msg":"trace[894140140] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:640; }","duration":"132.334643ms","start":"2026-08-20T20:23:37.527368Z","end":"2026-08-20T20:23:37.659703Z","steps":["trace[894140140] 'agreement among raft nodes before linearized reading' (duration: 132.17682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:37.659801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.782774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:23:37.659856Z","caller":"traceutil/trace.go:171","msg":"trace[1289192318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"208.873296ms","start":"2026-08-20T20:23:37.450969Z","end":"2026-08-20T20:23:37.659842Z","steps":["trace[1289192318] 'agreement among raft nodes before linearized reading' (duration: 208.758794ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:38.992745Z","caller":"traceutil/trace.go:171","msg":"trace[706156944] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"237.053083ms","start":"2026-08-20T20:23:38.755663Z","end":"2026-08-20T20:23:38.992716Z","steps":["trace[706156944] 'process raft request' (duration: 235.529746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:39.659505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.664705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T20:23:39.659573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.353517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j9s58\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-20T20:23:39.659637Z","caller":"traceutil/trace.go:171","msg":"trace[931518375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"208.852799ms","start":"2026-08-20T20:23:39.450759Z","end":"2026-08-20T20:23:39.659612Z","steps":["trace[931518375] 'range keys from in-memory index tree' (duration: 208.490452ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:39.659667Z","caller":"traceutil/trace.go:171","msg":"trace[1049608869] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j9s58; range_end:; response_count:1; response_revision:654; }","duration":"164.481458ms","start":"2026-08-20T20:23:39.495159Z","end":"2026-08-20T20:23:39.659641Z","steps":["trace[1049608869] 'range keys from in-memory index tree' (duration: 164.178053ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:39.884871Z","caller":"traceutil/trace.go:171","msg":"trace[2084381037] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"224.08252ms","start":"2026-08-20T20:23:39.660763Z","end":"2026-08-20T20:23:39.884846Z","steps":["trace[2084381037] 'read index received' (duration: 223.296036ms)","trace[2084381037] 'applied index is now lower than readState.Index' (duration: 785.464µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:23:39.884974Z","caller":"traceutil/trace.go:171","msg":"trace[42989224] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"322.323589ms","start":"2026-08-20T20:23:39.562591Z","end":"2026-08-20T20:23:39.884915Z","steps":["trace[42989224] 'process raft request' (duration: 321.668348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:39.885039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.205372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T20:23:39.885275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:39.562565Z","time spent":"322.604074ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:23:39.885276Z","caller":"traceutil/trace.go:171","msg":"trace[7185019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"224.526028ms","start":"2026-08-20T20:23:39.660736Z","end":"2026-08-20T20:23:39.885262Z","steps":["trace[7185019] 'agreement among raft nodes before linearized reading' (duration: 224.206792ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:39.941439Z","caller":"traceutil/trace.go:171","msg":"trace[1682756538] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"268.204424ms","start":"2026-08-20T20:23:39.673178Z","end":"2026-08-20T20:23:39.941382Z","steps":["trace[1682756538] 'process raft request' (duration: 268.040431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:41.183906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402335476765445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:23:41.582362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365787569s","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-20T20:23:41.582442Z","caller":"traceutil/trace.go:171","msg":"trace[1037742879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"1.365915162s","start":"2026-08-20T20:23:40.216509Z","end":"2026-08-20T20:23:41.582424Z","steps":["trace[1037742879] 'range keys from in-memory index tree' (duration: 1.365628897s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:41.582483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:40.216489Z","time spent":"1.365983142s","remote":"127.0.0.1:39820","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-20T20:23:41.684922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402335476765445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T20:23:42.185019Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.776076587s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-20T20:23:42.185157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402335476765445,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T20:23:42.188544Z","caller":"traceutil/trace.go:171","msg":"trace[1902725825] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.778821925s","start":"2026-08-20T20:23:40.407652Z","end":"2026-08-20T20:23:42.186474Z","steps":["trace[1902725825] 'process raft request' (duration: 1.777830108s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:42.1891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:40.407632Z","time spent":"1.781107845s","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T20:23:42.201166Z","caller":"traceutil/trace.go:171","msg":"trace[1226740385] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:689; }","duration":"1.517920332s","start":"2026-08-20T20:23:40.683108Z","end":"2026-08-20T20:23:42.201028Z","steps":["trace[1226740385] 'read index received' (duration: 1.502144787s)","trace[1226740385] 'applied index is now lower than readState.Index' (duration: 15.774594ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:23:42.201479Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":13928402335476765449,"received-request-id":13928402335476765445} {"level":"warn","ts":"2026-08-20T20:23:42.201854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518737056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-20T20:23:42.202536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16337912s","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-20T20:23:42.202575Z","caller":"traceutil/trace.go:171","msg":"trace[437536341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"1.163454401s","start":"2026-08-20T20:23:41.039112Z","end":"2026-08-20T20:23:42.202566Z","steps":["trace[437536341] 'agreement among raft nodes before linearized reading' (duration: 1.16337491s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:42.202607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:41.039091Z","time spent":"1.163507422s","remote":"127.0.0.1:39820","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":"warn","ts":"2026-08-20T20:23:42.20206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500758827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T20:23:42.202705Z","caller":"traceutil/trace.go:171","msg":"trace[1832811314] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:661; }","duration":"1.501442819s","start":"2026-08-20T20:23:40.701249Z","end":"2026-08-20T20:23:42.202692Z","steps":["trace[1832811314] 'agreement among raft nodes before linearized reading' (duration: 1.500773048s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:42.20275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:40.701229Z","time spent":"1.50150633s","remote":"127.0.0.1:39906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-20T20:23:42.202412Z","caller":"traceutil/trace.go:171","msg":"trace[1812891702] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.526941s","start":"2026-08-20T20:23:40.675454Z","end":"2026-08-20T20:23:42.202395Z","steps":["trace[1812891702] 'process raft request' (duration: 1.525310332s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:42.202937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:40.67543Z","time spent":"1.527447099s","remote":"127.0.0.1:39820","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-20T20:23:42.202439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.868014ms","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-20T20:23:42.203104Z","caller":"traceutil/trace.go:171","msg":"trace[2067118982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"179.568386ms","start":"2026-08-20T20:23:42.023526Z","end":"2026-08-20T20:23:42.203094Z","steps":["trace[2067118982] 'agreement among raft nodes before linearized reading' (duration: 178.839824ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:42.202459Z","caller":"traceutil/trace.go:171","msg":"trace[307336103] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"615.344637ms","start":"2026-08-20T20:23:41.587107Z","end":"2026-08-20T20:23:42.202451Z","steps":["trace[307336103] 'process raft request' (duration: 613.849441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:42.203565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:41.587079Z","time spent":"616.458477ms","remote":"127.0.0.1:39820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:23:42.202474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.985897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:23:42.203765Z","caller":"traceutil/trace.go:171","msg":"trace[2124271431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"752.289499ms","start":"2026-08-20T20:23:41.451451Z","end":"2026-08-20T20:23:42.203741Z","steps":["trace[2124271431] 'agreement among raft nodes before linearized reading' (duration: 751.006026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:42.203839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:41.451436Z","time spent":"752.371001ms","remote":"127.0.0.1:39566","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-20T20:23:42.203336Z","caller":"traceutil/trace.go:171","msg":"trace[1589212929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"1.519487288s","start":"2026-08-20T20:23:40.683067Z","end":"2026-08-20T20:23:42.202554Z","steps":["trace[1589212929] 'agreement among raft nodes before linearized reading' (duration: 1.518688814s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:42.204064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:40.683051Z","time spent":"1.520966645s","remote":"127.0.0.1:39820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-20T20:23:43.595207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.209824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:23:43.595281Z","caller":"traceutil/trace.go:171","msg":"trace[1561197465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"143.334837ms","start":"2026-08-20T20:23:43.451929Z","end":"2026-08-20T20:23:43.595263Z","steps":["trace[1561197465] 'range keys from in-memory index tree' (duration: 143.120733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:44.668431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.877324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T20:23:44.668541Z","caller":"traceutil/trace.go:171","msg":"trace[374829069] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"461.390406ms","start":"2026-08-20T20:23:44.207135Z","end":"2026-08-20T20:23:44.668525Z","steps":["trace[374829069] 'read index received' (duration: 60.731µs)","trace[374829069] 'applied index is now lower than readState.Index' (duration: 461.328135ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:23:44.668576Z","caller":"traceutil/trace.go:171","msg":"trace[478430540] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"887.083647ms","start":"2026-08-20T20:23:43.781469Z","end":"2026-08-20T20:23:44.668553Z","steps":["trace[478430540] 'process raft request' (duration: 114.040521ms)","trace[478430540] 'compare' (duration: 772.244843ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:23:44.668669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:43.781446Z","time spent":"887.17599ms","remote":"127.0.0.1:40082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T20:23:44.668722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.580289ms","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-20T20:23:44.668765Z","caller":"traceutil/trace.go:171","msg":"trace[1163984260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"461.652921ms","start":"2026-08-20T20:23:44.207101Z","end":"2026-08-20T20:23:44.668754Z","steps":["trace[1163984260] 'agreement among raft nodes before linearized reading' (duration: 461.490758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:44.668803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:44.207086Z","time spent":"461.710092ms","remote":"127.0.0.1:39820","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-20T20:23:44.670904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.939855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-20T20:23:44.67094Z","caller":"traceutil/trace.go:171","msg":"trace[1640201118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"459.017655ms","start":"2026-08-20T20:23:44.211912Z","end":"2026-08-20T20:23:44.67093Z","steps":["trace[1640201118] 'agreement among raft nodes before linearized reading' (duration: 458.887343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:44.670956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:44.211877Z","time spent":"459.075937ms","remote":"127.0.0.1:39820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-20T20:23:44.671125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.137551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j9s58\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-08-20T20:23:44.671145Z","caller":"traceutil/trace.go:171","msg":"trace[1420210279] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j9s58; range_end:; response_count:1; response_revision:681; }","duration":"155.197483ms","start":"2026-08-20T20:23:44.515942Z","end":"2026-08-20T20:23:44.67114Z","steps":["trace[1420210279] 'agreement among raft nodes before linearized reading' (duration: 155.146882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:44.671233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.301435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:23:44.671251Z","caller":"traceutil/trace.go:171","msg":"trace[693696533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"218.318206ms","start":"2026-08-20T20:23:44.452927Z","end":"2026-08-20T20:23:44.671246Z","steps":["trace[693696533] 'agreement among raft nodes before linearized reading' (duration: 218.292885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:44.671332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.467499ms","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-20T20:23:44.671349Z","caller":"traceutil/trace.go:171","msg":"trace[1296072512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"457.503989ms","start":"2026-08-20T20:23:44.213841Z","end":"2026-08-20T20:23:44.671345Z","steps":["trace[1296072512] 'agreement among raft nodes before linearized reading' (duration: 457.460929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:44.671361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:44.213832Z","time spent":"457.52679ms","remote":"127.0.0.1:39820","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-20T20:23:44.671439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.060326ms","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-20T20:23:44.671454Z","caller":"traceutil/trace.go:171","msg":"trace[177563099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"459.092537ms","start":"2026-08-20T20:23:44.212358Z","end":"2026-08-20T20:23:44.671451Z","steps":["trace[177563099] 'agreement among raft nodes before linearized reading' (duration: 459.051856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:44.671468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:44.21235Z","time spent":"459.115177ms","remote":"127.0.0.1:39820","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-20T20:23:45.159744Z","caller":"traceutil/trace.go:171","msg":"trace[481104469] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"131.747784ms","start":"2026-08-20T20:23:45.027974Z","end":"2026-08-20T20:23:45.159722Z","steps":["trace[481104469] 'read index received' (duration: 131.674893ms)","trace[481104469] 'applied index is now lower than readState.Index' (duration: 71.871µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:23:45.159836Z","caller":"traceutil/trace.go:171","msg":"trace[1632006386] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"174.76618ms","start":"2026-08-20T20:23:44.985041Z","end":"2026-08-20T20:23:45.159807Z","steps":["trace[1632006386] 'process raft request' (duration: 174.556806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:45.159875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.881916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:23:45.159912Z","caller":"traceutil/trace.go:171","msg":"trace[1973193857] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:698; }","duration":"131.948167ms","start":"2026-08-20T20:23:45.027951Z","end":"2026-08-20T20:23:45.159899Z","steps":["trace[1973193857] 'agreement among raft nodes before linearized reading' (duration: 131.879886ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:45.23388Z","caller":"traceutil/trace.go:171","msg":"trace[377257211] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"203.541017ms","start":"2026-08-20T20:23:45.030308Z","end":"2026-08-20T20:23:45.233849Z","steps":["trace[377257211] 'process raft request' (duration: 203.339223ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:45.36444Z","caller":"traceutil/trace.go:171","msg":"trace[1066188069] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"199.248934ms","start":"2026-08-20T20:23:45.165162Z","end":"2026-08-20T20:23:45.364411Z","steps":["trace[1066188069] 'process raft request' (duration: 198.865397ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:45.365015Z","caller":"traceutil/trace.go:171","msg":"trace[1992131392] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:730; }","duration":"136.097489ms","start":"2026-08-20T20:23:45.228895Z","end":"2026-08-20T20:23:45.364993Z","steps":["trace[1992131392] 'read index received' (duration: 4.796622ms)","trace[1992131392] 'applied index is now lower than readState.Index' (duration: 131.299746ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:23:45.365082Z","caller":"traceutil/trace.go:171","msg":"trace[478852469] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"199.489598ms","start":"2026-08-20T20:23:45.16558Z","end":"2026-08-20T20:23:45.36507Z","steps":["trace[478852469] 'process raft request' (duration: 198.834777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:45.36517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.256651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:23:45.365402Z","caller":"traceutil/trace.go:171","msg":"trace[910366497] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:701; }","duration":"136.509016ms","start":"2026-08-20T20:23:45.228839Z","end":"2026-08-20T20:23:45.365348Z","steps":["trace[910366497] 'agreement among raft nodes before linearized reading' (duration: 136.262321ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:45.380764Z","caller":"traceutil/trace.go:171","msg":"trace[1393134625] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"141.518311ms","start":"2026-08-20T20:23:45.239225Z","end":"2026-08-20T20:23:45.380743Z","steps":["trace[1393134625] 'process raft request' (duration: 141.431339ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:47.718047Z","caller":"traceutil/trace.go:171","msg":"trace[228564081] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"150.311265ms","start":"2026-08-20T20:23:47.56769Z","end":"2026-08-20T20:23:47.718001Z","steps":["trace[228564081] 'process raft request' (duration: 99.565873ms)","trace[228564081] 'compare' (duration: 50.576089ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:23:55.00684Z","caller":"traceutil/trace.go:171","msg":"trace[1346317543] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"211.572976ms","start":"2026-08-20T20:23:54.795244Z","end":"2026-08-20T20:23:55.006817Z","steps":["trace[1346317543] 'read index received' (duration: 211.341273ms)","trace[1346317543] 'applied index is now lower than readState.Index' (duration: 230.813µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:23:55.007095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.83861ms","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-20T20:23:55.007063Z","caller":"traceutil/trace.go:171","msg":"trace[339978689] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"220.083782ms","start":"2026-08-20T20:23:54.786922Z","end":"2026-08-20T20:23:55.007006Z","steps":["trace[339978689] 'process raft request' (duration: 219.756827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:55.007187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.673282ms","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-20T20:23:55.007274Z","caller":"traceutil/trace.go:171","msg":"trace[1035057836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"205.807444ms","start":"2026-08-20T20:23:54.801448Z","end":"2026-08-20T20:23:55.007255Z","steps":["trace[1035057836] 'agreement among raft nodes before linearized reading' (duration: 205.583451ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:55.00714Z","caller":"traceutil/trace.go:171","msg":"trace[1609079921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:806; }","duration":"211.939662ms","start":"2026-08-20T20:23:54.79519Z","end":"2026-08-20T20:23:55.007129Z","steps":["trace[1609079921] 'agreement among raft nodes before linearized reading' (duration: 211.754469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:55.007559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.678572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-20T20:23:55.007599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.839464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T20:23:55.007634Z","caller":"traceutil/trace.go:171","msg":"trace[568009463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"205.775403ms","start":"2026-08-20T20:23:54.801841Z","end":"2026-08-20T20:23:55.007616Z","steps":["trace[568009463] 'agreement among raft nodes before linearized reading' (duration: 205.56811ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T20:23:55.007715Z","caller":"traceutil/trace.go:171","msg":"trace[394814952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"105.967426ms","start":"2026-08-20T20:23:54.90172Z","end":"2026-08-20T20:23:55.007687Z","steps":["trace[394814952] 'agreement among raft nodes before linearized reading' (duration: 105.661091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:57.933755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928402335476765766,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T20:23:57.938709Z","caller":"traceutil/trace.go:171","msg":"trace[804537534] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:858; }","duration":"505.604683ms","start":"2026-08-20T20:23:57.433082Z","end":"2026-08-20T20:23:57.938687Z","steps":["trace[804537534] 'read index received' (duration: 505.40868ms)","trace[804537534] 'applied index is now lower than readState.Index' (duration: 195.123µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:23:57.938846Z","caller":"traceutil/trace.go:171","msg":"trace[640441694] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"580.851654ms","start":"2026-08-20T20:23:57.35796Z","end":"2026-08-20T20:23:57.938812Z","steps":["trace[640441694] 'process raft request' (duration: 580.58968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:57.939001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:57.357937Z","time spent":"580.977056ms","remote":"127.0.0.1:39820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T20:23:57.939115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.013479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.182\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T20:23:57.939211Z","caller":"traceutil/trace.go:171","msg":"trace[477123017] range","detail":"{range_begin:/registry/masterleases/199.204.45.182; range_end:; response_count:1; response_revision:824; }","duration":"506.171152ms","start":"2026-08-20T20:23:57.433026Z","end":"2026-08-20T20:23:57.939197Z","steps":["trace[477123017] 'agreement among raft nodes before linearized reading' (duration: 505.772986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:57.939255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:57.433008Z","time spent":"506.233143ms","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.182\" "} {"level":"warn","ts":"2026-08-20T20:23:57.93926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.236314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-62nfn\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-20T20:23:57.939335Z","caller":"traceutil/trace.go:171","msg":"trace[1204706167] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-62nfn; range_end:; response_count:1; response_revision:824; }","duration":"341.364546ms","start":"2026-08-20T20:23:57.597953Z","end":"2026-08-20T20:23:57.939317Z","steps":["trace[1204706167] 'agreement among raft nodes before linearized reading' (duration: 341.133172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:57.939368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:57.597921Z","time spent":"341.440727ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-62nfn\" "} {"level":"warn","ts":"2026-08-20T20:23:57.939464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.758533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:23:57.939883Z","caller":"traceutil/trace.go:171","msg":"trace[1716755300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"488.166139ms","start":"2026-08-20T20:23:57.451671Z","end":"2026-08-20T20:23:57.939837Z","steps":["trace[1716755300] 'agreement among raft nodes before linearized reading' (duration: 487.404477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:23:57.940038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T20:23:57.451662Z","time spent":"488.359112ms","remote":"127.0.0.1:39566","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-20T20:24:03.219704Z","caller":"traceutil/trace.go:171","msg":"trace[1257039569] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:921; }","duration":"168.151411ms","start":"2026-08-20T20:24:03.051529Z","end":"2026-08-20T20:24:03.21968Z","steps":["trace[1257039569] 'read index received' (duration: 11.160179ms)","trace[1257039569] 'applied index is now lower than readState.Index' (duration: 156.990372ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T20:24:03.219825Z","caller":"traceutil/trace.go:171","msg":"trace[763160236] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"169.744175ms","start":"2026-08-20T20:24:03.050069Z","end":"2026-08-20T20:24:03.219813Z","steps":["trace[763160236] 'process raft request' (duration: 131.733971ms)","trace[763160236] 'compare' (duration: 37.563717ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T20:24:03.220118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.549327ms","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-20T20:24:03.220223Z","caller":"traceutil/trace.go:171","msg":"trace[1167648876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"168.700499ms","start":"2026-08-20T20:24:03.0515Z","end":"2026-08-20T20:24:03.2202Z","steps":["trace[1167648876] 'agreement among raft nodes before linearized reading' (duration: 168.416935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:24:03.22067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.928038ms","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-20T20:24:03.220723Z","caller":"traceutil/trace.go:171","msg":"trace[1367539148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"168.006549ms","start":"2026-08-20T20:24:03.052707Z","end":"2026-08-20T20:24:03.220713Z","steps":["trace[1367539148] 'agreement among raft nodes before linearized reading' (duration: 167.882347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:24:03.221117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.916753ms","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-20T20:24:03.221171Z","caller":"traceutil/trace.go:171","msg":"trace[407870362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"168.995863ms","start":"2026-08-20T20:24:03.052161Z","end":"2026-08-20T20:24:03.221157Z","steps":["trace[407870362] 'agreement among raft nodes before linearized reading' (duration: 168.12511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T20:24:39.83965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.664632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T20:24:39.839742Z","caller":"traceutil/trace.go:171","msg":"trace[1787445622] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1100; }","duration":"180.814573ms","start":"2026-08-20T20:24:39.658908Z","end":"2026-08-20T20:24:39.839723Z","steps":["trace[1787445622] 'count revisions from in-memory index tree' (duration: 180.581811ms)"],"step_count":1}