{"level":"warn","ts":"2026-08-04T15:11:50.310764Z","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-04T15:11:50.310939Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.213:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.213:2380","--initial-cluster=instance=https://199.19.213.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.213: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-04T15:11:50.311137Z","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-04T15:11:50.31116Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-08-04T15:11:50.31119Z","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-04T15:11:50.312209Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"]} {"level":"info","ts":"2026-08-04T15:11:50.31232Z","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.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.213: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-04T15:11:50.31692Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.28203ms"} {"level":"info","ts":"2026-08-04T15:11:50.325741Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3faf6a3dfbe0100","cluster-id":"4c2e50e35fb92a79"} {"level":"info","ts":"2026-08-04T15:11:50.325897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:11:50.325964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 0"} {"level":"info","ts":"2026-08-04T15:11:50.325993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3faf6a3dfbe0100 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:11:50.326035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 1"} {"level":"info","ts":"2026-08-04T15:11:50.326109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"warn","ts":"2026-08-04T15:11:50.333792Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:11:50.335497Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:11:50.336766Z","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-04T15:11:50.339843Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3faf6a3dfbe0100","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:11:50.340079Z","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-04T15:11:50.34017Z","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-04T15:11:50.340194Z","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-04T15:11:50.343734Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3faf6a3dfbe0100","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:11:50.3442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"info","ts":"2026-08-04T15:11:50.344351Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","added-peer-id":"f3faf6a3dfbe0100","added-peer-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-08-04T15:11:50.34723Z","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-04T15:11:50.347384Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-08-04T15:11:50.347408Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-08-04T15:11:50.347721Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3faf6a3dfbe0100","initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:11:50.34779Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:11:50.834265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:11:50.834568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:11:50.834726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgPreVoteResp from f3faf6a3dfbe0100 at term 1"} {"level":"info","ts":"2026-08-04T15:11:50.834811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:11:50.834877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgVoteResp from f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-08-04T15:11:50.834997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became leader at term 2"} {"level":"info","ts":"2026-08-04T15:11:50.835111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3faf6a3dfbe0100 elected leader f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-08-04T15:11:50.841837Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:11:50.843237Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:11:50.843331Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:11:50.843234Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3faf6a3dfbe0100","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.213:2379]}","request-path":"/0/members/f3faf6a3dfbe0100/attributes","cluster-id":"4c2e50e35fb92a79","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:11:50.843649Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:11:50.84383Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:11:50.845677Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:11:50.846347Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:11:50.846565Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:11:50.850197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.213:2379"} {"level":"info","ts":"2026-08-04T15:11:50.851741Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:11:55.784067Z","caller":"traceutil/trace.go:171","msg":"trace[92525381] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"221.568406ms","start":"2026-08-04T15:11:55.562473Z","end":"2026-08-04T15:11:55.784041Z","steps":["trace[92525381] 'process raft request' (duration: 221.330522ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:11:55.932327Z","caller":"traceutil/trace.go:171","msg":"trace[414518859] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"136.576347ms","start":"2026-08-04T15:11:55.795728Z","end":"2026-08-04T15:11:55.932305Z","steps":["trace[414518859] 'process raft request' (duration: 89.247786ms)","trace[414518859] 'compare' (duration: 46.684741ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:11:56.123347Z","caller":"traceutil/trace.go:171","msg":"trace[995564129] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"147.471918ms","start":"2026-08-04T15:11:55.975844Z","end":"2026-08-04T15:11:56.123316Z","steps":["trace[995564129] 'process raft request' (duration: 114.731452ms)","trace[995564129] 'compare' (duration: 32.620624ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:11:56.360969Z","caller":"traceutil/trace.go:171","msg":"trace[612371807] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"150.531834ms","start":"2026-08-04T15:11:56.210392Z","end":"2026-08-04T15:11:56.360924Z","steps":["trace[612371807] 'process raft request' (duration: 150.210589ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:00.157094Z","caller":"traceutil/trace.go:171","msg":"trace[1336945666] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"119.200644ms","start":"2026-08-04T15:12:00.037873Z","end":"2026-08-04T15:12:00.157074Z","steps":["trace[1336945666] 'process raft request' (duration: 119.072492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:00.49685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.462091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:12:00.496997Z","caller":"traceutil/trace.go:171","msg":"trace[152825339] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"190.190085ms","start":"2026-08-04T15:12:00.306787Z","end":"2026-08-04T15:12:00.496977Z","steps":["trace[152825339] 'read index received' (duration: 75.067254ms)","trace[152825339] 'applied index is now lower than readState.Index' (duration: 115.121461ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:12:00.497144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.357598ms","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-04T15:12:00.497125Z","caller":"traceutil/trace.go:171","msg":"trace[1233560326] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"318.764923ms","start":"2026-08-04T15:12:00.178333Z","end":"2026-08-04T15:12:00.497098Z","steps":["trace[1233560326] 'process raft request' (duration: 203.48002ms)","trace[1233560326] 'compare' (duration: 114.291378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:12:00.497238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.745124ms","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-04T15:12:00.497184Z","caller":"traceutil/trace.go:171","msg":"trace[1335752220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:336; }","duration":"190.429059ms","start":"2026-08-04T15:12:00.306743Z","end":"2026-08-04T15:12:00.497172Z","steps":["trace[1335752220] 'agreement among raft nodes before linearized reading' (duration: 190.325927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:00.497268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:00.178316Z","time spent":"318.910305ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:12:00.497345Z","caller":"traceutil/trace.go:171","msg":"trace[2145208804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:336; }","duration":"125.891396ms","start":"2026-08-04T15:12:00.371428Z","end":"2026-08-04T15:12:00.49732Z","steps":["trace[2145208804] 'agreement among raft nodes before linearized reading' (duration: 125.710433ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:00.628203Z","caller":"traceutil/trace.go:171","msg":"trace[2125118275] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:347; }","duration":"124.587446ms","start":"2026-08-04T15:12:00.503594Z","end":"2026-08-04T15:12:00.628181Z","steps":["trace[2125118275] 'read index received' (duration: 91.158131ms)","trace[2125118275] 'applied index is now lower than readState.Index' (duration: 33.428165ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:12:00.628332Z","caller":"traceutil/trace.go:171","msg":"trace[519174512] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"126.334913ms","start":"2026-08-04T15:12:00.501962Z","end":"2026-08-04T15:12:00.628297Z","steps":["trace[519174512] 'process raft request' (duration: 126.048058ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:00.628362Z","caller":"traceutil/trace.go:171","msg":"trace[2140002341] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"126.248991ms","start":"2026-08-04T15:12:00.502086Z","end":"2026-08-04T15:12:00.628335Z","steps":["trace[2140002341] 'process raft request' (duration: 126.058958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:00.628419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.719928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-04T15:12:00.628537Z","caller":"traceutil/trace.go:171","msg":"trace[743652414] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:339; }","duration":"124.985522ms","start":"2026-08-04T15:12:00.50354Z","end":"2026-08-04T15:12:00.628525Z","steps":["trace[743652414] 'agreement among raft nodes before linearized reading' (duration: 124.730198ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:01.657031Z","caller":"traceutil/trace.go:171","msg":"trace[1143524369] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:361; }","duration":"179.111559ms","start":"2026-08-04T15:12:01.477896Z","end":"2026-08-04T15:12:01.657008Z","steps":["trace[1143524369] 'read index received' (duration: 66.516987ms)","trace[1143524369] 'applied index is now lower than readState.Index' (duration: 112.593502ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:12:01.657073Z","caller":"traceutil/trace.go:171","msg":"trace[1813067593] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"186.821586ms","start":"2026-08-04T15:12:01.470209Z","end":"2026-08-04T15:12:01.65703Z","steps":["trace[1813067593] 'process raft request' (duration: 107.171019ms)","trace[1813067593] 'compare' (duration: 79.354123ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:12:01.657301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.375583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-04T15:12:01.657378Z","caller":"traceutil/trace.go:171","msg":"trace[1871325295] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:355; }","duration":"179.490404ms","start":"2026-08-04T15:12:01.477867Z","end":"2026-08-04T15:12:01.657357Z","steps":["trace[1871325295] 'agreement among raft nodes before linearized reading' (duration: 179.23683ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:01.680992Z","caller":"traceutil/trace.go:171","msg":"trace[540720667] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"129.710412ms","start":"2026-08-04T15:12:01.551257Z","end":"2026-08-04T15:12:01.680967Z","steps":["trace[540720667] 'process raft request' (duration: 129.435958ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:01.681505Z","caller":"traceutil/trace.go:171","msg":"trace[1116198570] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"129.096683ms","start":"2026-08-04T15:12:01.552368Z","end":"2026-08-04T15:12:01.681465Z","steps":["trace[1116198570] 'process raft request' (duration: 128.523524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:01.681652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.905067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-08-04T15:12:01.681741Z","caller":"traceutil/trace.go:171","msg":"trace[545381158] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:357; }","duration":"102.042699ms","start":"2026-08-04T15:12:01.579676Z","end":"2026-08-04T15:12:01.681718Z","steps":["trace[545381158] 'agreement among raft nodes before linearized reading' (duration: 101.901687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:01.682145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.570751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-04T15:12:01.682201Z","caller":"traceutil/trace.go:171","msg":"trace[2115897148] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:357; }","duration":"152.669883ms","start":"2026-08-04T15:12:01.529517Z","end":"2026-08-04T15:12:01.682187Z","steps":["trace[2115897148] 'agreement among raft nodes before linearized reading' (duration: 151.890852ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:01.977632Z","caller":"traceutil/trace.go:171","msg":"trace[809222006] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"168.485925ms","start":"2026-08-04T15:12:01.809125Z","end":"2026-08-04T15:12:01.97761Z","steps":["trace[809222006] 'process raft request' (duration: 168.375383ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:01.977675Z","caller":"traceutil/trace.go:171","msg":"trace[243345255] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"167.678443ms","start":"2026-08-04T15:12:01.809965Z","end":"2026-08-04T15:12:01.977644Z","steps":["trace[243345255] 'process raft request' (duration: 167.584462ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:01.97784Z","caller":"traceutil/trace.go:171","msg":"trace[466556105] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"176.715771ms","start":"2026-08-04T15:12:01.800864Z","end":"2026-08-04T15:12:01.97758Z","steps":["trace[466556105] 'process raft request' (duration: 147.840279ms)","trace[466556105] 'compare' (duration: 28.648799ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:12:01.977918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.042147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-08-04T15:12:01.978106Z","caller":"traceutil/trace.go:171","msg":"trace[1208653690] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:373; }","duration":"179.278591ms","start":"2026-08-04T15:12:01.798806Z","end":"2026-08-04T15:12:01.978085Z","steps":["trace[1208653690] 'agreement among raft nodes before linearized reading' (duration: 178.968316ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:01.977635Z","caller":"traceutil/trace.go:171","msg":"trace[694920370] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"176.428367ms","start":"2026-08-04T15:12:01.801168Z","end":"2026-08-04T15:12:01.977596Z","steps":["trace[694920370] 'read index received' (duration: 82.725304ms)","trace[694920370] 'applied index is now lower than readState.Index' (duration: 93.701573ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:12:01.979328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.855451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-8mn7r\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-08-04T15:12:01.97971Z","caller":"traceutil/trace.go:171","msg":"trace[2050028248] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-8mn7r; range_end:; response_count:1; response_revision:373; }","duration":"173.258168ms","start":"2026-08-04T15:12:01.806436Z","end":"2026-08-04T15:12:01.979694Z","steps":["trace[2050028248] 'agreement among raft nodes before linearized reading' (duration: 172.830181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.290549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72233298276661873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:12:29.446683Z","caller":"traceutil/trace.go:171","msg":"trace[797497099] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"706.999089ms","start":"2026-08-04T15:12:28.739631Z","end":"2026-08-04T15:12:29.44663Z","steps":["trace[797497099] 'process raft request' (duration: 706.889088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.446808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.739574Z","time spent":"707.173082ms","remote":"127.0.0.1:60232","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-04T15:12:29.500707Z","caller":"traceutil/trace.go:171","msg":"trace[1343311344] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"755.473989ms","start":"2026-08-04T15:12:28.745207Z","end":"2026-08-04T15:12:29.500681Z","steps":["trace[1343311344] 'process raft request' (duration: 755.183575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.50083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.745178Z","time spent":"755.59666ms","remote":"127.0.0.1:60232","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-04T15:12:29.500972Z","caller":"traceutil/trace.go:171","msg":"trace[67770209] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"744.302321ms","start":"2026-08-04T15:12:28.756643Z","end":"2026-08-04T15:12:29.500945Z","steps":["trace[67770209] 'process raft request' (duration: 744.061748ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:29.501097Z","caller":"traceutil/trace.go:171","msg":"trace[1832061728] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"736.133453ms","start":"2026-08-04T15:12:28.764952Z","end":"2026-08-04T15:12:29.501085Z","steps":["trace[1832061728] 'process raft request' (duration: 735.89059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.501133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.756637Z","time spent":"744.415672ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:12:29.501158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.764943Z","time spent":"736.189863ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:12:29.501181Z","caller":"traceutil/trace.go:171","msg":"trace[128576896] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:541; }","duration":"711.396395ms","start":"2026-08-04T15:12:28.78976Z","end":"2026-08-04T15:12:29.501156Z","steps":["trace[128576896] 'read index received' (duration: 656.916016ms)","trace[128576896] 'applied index is now lower than readState.Index' (duration: 54.479159ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:12:29.501282Z","caller":"traceutil/trace.go:171","msg":"trace[1729142142] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"742.155852ms","start":"2026-08-04T15:12:28.759113Z","end":"2026-08-04T15:12:29.501269Z","steps":["trace[1729142142] 'process raft request' (duration: 741.631285ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:29.501322Z","caller":"traceutil/trace.go:171","msg":"trace[1325068855] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"713.032277ms","start":"2026-08-04T15:12:28.788283Z","end":"2026-08-04T15:12:29.501315Z","steps":["trace[1325068855] 'process raft request' (duration: 712.687012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.501337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.759091Z","time spent":"742.225403ms","remote":"127.0.0.1:60232","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-04T15:12:29.501403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.788272Z","time spent":"713.074617ms","remote":"127.0.0.1:60232","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-04T15:12:29.501292Z","caller":"traceutil/trace.go:171","msg":"trace[1013322137] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"721.581139ms","start":"2026-08-04T15:12:28.779699Z","end":"2026-08-04T15:12:29.50128Z","steps":["trace[1013322137] 'process raft request' (duration: 721.187514ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:29.501464Z","caller":"traceutil/trace.go:171","msg":"trace[2079688694] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"751.008649ms","start":"2026-08-04T15:12:28.750439Z","end":"2026-08-04T15:12:29.501447Z","steps":["trace[2079688694] 'process raft request' (duration: 750.163379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.50151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.779688Z","time spent":"721.799423ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:12:29.501517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.750425Z","time spent":"751.06757ms","remote":"127.0.0.1:60232","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-04T15:12:29.501599Z","caller":"traceutil/trace.go:171","msg":"trace[1414874745] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"686.317492ms","start":"2026-08-04T15:12:28.815272Z","end":"2026-08-04T15:12:29.50159Z","steps":["trace[1414874745] 'process raft request' (duration: 685.754045ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:29.501621Z","caller":"traceutil/trace.go:171","msg":"trace[1874730327] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"748.555297ms","start":"2026-08-04T15:12:28.753057Z","end":"2026-08-04T15:12:29.501612Z","steps":["trace[1874730327] 'process raft request' (duration: 747.592374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.501662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.75303Z","time spent":"748.611277ms","remote":"127.0.0.1:60232","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-04T15:12:29.501728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.815243Z","time spent":"686.386163ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:12:29.501419Z","caller":"traceutil/trace.go:171","msg":"trace[549426654] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"741.581425ms","start":"2026-08-04T15:12:28.759829Z","end":"2026-08-04T15:12:29.50141Z","steps":["trace[549426654] 'process raft request' (duration: 740.976457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.501823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.056583ms","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-04T15:12:29.501836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.759819Z","time spent":"741.99876ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:12:29.50186Z","caller":"traceutil/trace.go:171","msg":"trace[1617792110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"712.114184ms","start":"2026-08-04T15:12:28.789737Z","end":"2026-08-04T15:12:29.501851Z","steps":["trace[1617792110] 'agreement among raft nodes before linearized reading' (duration: 712.010962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.501889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.78973Z","time spent":"712.152634ms","remote":"127.0.0.1:60470","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-04T15:12:29.50194Z","caller":"traceutil/trace.go:171","msg":"trace[139761867] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"537.273748ms","start":"2026-08-04T15:12:28.964655Z","end":"2026-08-04T15:12:29.501929Z","steps":["trace[139761867] 'process raft request' (duration: 536.434777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.502002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.964629Z","time spent":"537.345888ms","remote":"127.0.0.1:60232","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-04T15:12:29.533017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.555029ms","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-04T15:12:29.533177Z","caller":"traceutil/trace.go:171","msg":"trace[870794622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"740.756471ms","start":"2026-08-04T15:12:28.792402Z","end":"2026-08-04T15:12:29.533159Z","steps":["trace[870794622] 'agreement among raft nodes before linearized reading' (duration: 740.515148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.533247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.792398Z","time spent":"740.820072ms","remote":"127.0.0.1:60470","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-04T15:12:29.533722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.658756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:29.53379Z","caller":"traceutil/trace.go:171","msg":"trace[1297964971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"445.763549ms","start":"2026-08-04T15:12:29.088009Z","end":"2026-08-04T15:12:29.533772Z","steps":["trace[1297964971] 'agreement among raft nodes before linearized reading' (duration: 445.051879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.53382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:29.087991Z","time spent":"445.82346ms","remote":"127.0.0.1:60156","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-04T15:12:29.533985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.815204ms","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-04T15:12:29.534023Z","caller":"traceutil/trace.go:171","msg":"trace[1868268433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"611.871764ms","start":"2026-08-04T15:12:28.922134Z","end":"2026-08-04T15:12:29.534005Z","steps":["trace[1868268433] 'agreement among raft nodes before linearized reading' (duration: 611.106623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.534044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.922118Z","time spent":"611.921416ms","remote":"127.0.0.1:60470","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-04T15:12:29.534218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.397438ms","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-04T15:12:29.534269Z","caller":"traceutil/trace.go:171","msg":"trace[1486214547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"731.443688ms","start":"2026-08-04T15:12:28.802809Z","end":"2026-08-04T15:12:29.534252Z","steps":["trace[1486214547] 'agreement among raft nodes before linearized reading' (duration: 730.775759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:29.534323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:28.802783Z","time spent":"731.529679ms","remote":"127.0.0.1:60470","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-04T15:12:34.217343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.57228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.213\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-04T15:12:34.21743Z","caller":"traceutil/trace.go:171","msg":"trace[271744120] range","detail":"{range_begin:/registry/masterleases/199.19.213.213; range_end:; response_count:1; response_revision:619; }","duration":"153.697242ms","start":"2026-08-04T15:12:34.063715Z","end":"2026-08-04T15:12:34.217412Z","steps":["trace[271744120] 'range keys from in-memory index tree' (duration: 153.48972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:34.217422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.186985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:34.217478Z","caller":"traceutil/trace.go:171","msg":"trace[1507868780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"132.253336ms","start":"2026-08-04T15:12:34.085209Z","end":"2026-08-04T15:12:34.217462Z","steps":["trace[1507868780] 'range keys from in-memory index tree' (duration: 132.146654ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:34.431506Z","caller":"traceutil/trace.go:171","msg":"trace[1120859579] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"212.564833ms","start":"2026-08-04T15:12:34.21885Z","end":"2026-08-04T15:12:34.431415Z","steps":["trace[1120859579] 'read index received' (duration: 81.459251ms)","trace[1120859579] 'applied index is now lower than readState.Index' (duration: 131.104332ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:12:34.431674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.740445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:34.431777Z","caller":"traceutil/trace.go:171","msg":"trace[1494885062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"212.960777ms","start":"2026-08-04T15:12:34.218792Z","end":"2026-08-04T15:12:34.431753Z","steps":["trace[1494885062] 'agreement among raft nodes before linearized reading' (duration: 212.705734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:36.58611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72233298276662123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:12:36.964094Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.093764579s","expected-duration":"1s"} {"level":"info","ts":"2026-08-04T15:12:36.964865Z","caller":"traceutil/trace.go:171","msg":"trace[88743272] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"879.143426ms","start":"2026-08-04T15:12:36.085703Z","end":"2026-08-04T15:12:36.964846Z","steps":["trace[88743272] 'read index received' (duration: 878.580919ms)","trace[88743272] 'applied index is now lower than readState.Index' (duration: 561.827µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:12:36.964943Z","caller":"traceutil/trace.go:171","msg":"trace[30738877] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.096008837s","start":"2026-08-04T15:12:35.868911Z","end":"2026-08-04T15:12:36.96492Z","steps":["trace[30738877] 'process raft request' (duration: 1.095585282s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:36.965017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.311648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:36.96504Z","caller":"traceutil/trace.go:171","msg":"trace[1699042774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"879.384938ms","start":"2026-08-04T15:12:36.085646Z","end":"2026-08-04T15:12:36.965031Z","steps":["trace[1699042774] 'agreement among raft nodes before linearized reading' (duration: 879.290537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:36.965058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:36.085626Z","time spent":"879.4281ms","remote":"127.0.0.1:60146","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-04T15:12:36.965103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.573831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c84dx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-04T15:12:36.965159Z","caller":"traceutil/trace.go:171","msg":"trace[1372953006] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c84dx; range_end:; response_count:1; response_revision:634; }","duration":"252.668622ms","start":"2026-08-04T15:12:36.712471Z","end":"2026-08-04T15:12:36.96514Z","steps":["trace[1372953006] 'agreement among raft nodes before linearized reading' (duration: 252.52746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:36.965207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:35.868883Z","time spent":"1.096132518s","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:12:37.587985Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72233298276662126,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:12:37.709448Z","caller":"traceutil/trace.go:171","msg":"trace[358440369] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"728.973397ms","start":"2026-08-04T15:12:36.980453Z","end":"2026-08-04T15:12:37.709426Z","steps":["trace[358440369] 'process raft request' (duration: 728.827095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:37.709564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:36.980432Z","time spent":"729.074868ms","remote":"127.0.0.1:60372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:12:37.718252Z","caller":"traceutil/trace.go:171","msg":"trace[199196022] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:658; }","duration":"630.764675ms","start":"2026-08-04T15:12:37.087473Z","end":"2026-08-04T15:12:37.718237Z","steps":["trace[199196022] 'read index received' (duration: 621.909403ms)","trace[199196022] 'applied index is now lower than readState.Index' (duration: 8.854622ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:12:37.718335Z","caller":"traceutil/trace.go:171","msg":"trace[1947211674] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"539.194828ms","start":"2026-08-04T15:12:37.179133Z","end":"2026-08-04T15:12:37.718328Z","steps":["trace[1947211674] 'process raft request' (duration: 539.073067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:37.718511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.386922ms","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-04T15:12:37.71857Z","caller":"traceutil/trace.go:171","msg":"trace[60254990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"138.491184ms","start":"2026-08-04T15:12:37.580062Z","end":"2026-08-04T15:12:37.718553Z","steps":["trace[60254990] 'agreement among raft nodes before linearized reading' (duration: 138.323392ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:37.718539Z","caller":"traceutil/trace.go:171","msg":"trace[1880132821] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"695.335537ms","start":"2026-08-04T15:12:37.023178Z","end":"2026-08-04T15:12:37.718513Z","steps":["trace[1880132821] 'process raft request' (duration: 694.950973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:37.71857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:37.179102Z","time spent":"539.286349ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:12:37.718739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:37.023123Z","time spent":"695.500539ms","remote":"127.0.0.1:60706","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-04T15:12:37.718795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.742257ms","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-04T15:12:37.718837Z","caller":"traceutil/trace.go:171","msg":"trace[283948600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"127.844957ms","start":"2026-08-04T15:12:37.590983Z","end":"2026-08-04T15:12:37.718828Z","steps":["trace[283948600] 'agreement among raft nodes before linearized reading' (duration: 127.694875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:37.719056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.600775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:37.719154Z","caller":"traceutil/trace.go:171","msg":"trace[1105034308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"631.715617ms","start":"2026-08-04T15:12:37.087386Z","end":"2026-08-04T15:12:37.719102Z","steps":["trace[1105034308] 'agreement among raft nodes before linearized reading' (duration: 631.342422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:37.719183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:37.087352Z","time spent":"631.824918ms","remote":"127.0.0.1:60156","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-04T15:12:38.947375Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72233298276662137,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:12:39.447722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72233298276662137,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:12:39.759177Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.568253367s","expected-duration":"1s"} {"level":"info","ts":"2026-08-04T15:12:39.760282Z","caller":"traceutil/trace.go:171","msg":"trace[746638622] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"1.31397365s","start":"2026-08-04T15:12:38.446278Z","end":"2026-08-04T15:12:39.760251Z","steps":["trace[746638622] 'read index received' (duration: 1.31315318s)","trace[746638622] 'applied index is now lower than readState.Index' (duration: 819.75µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:12:39.760388Z","caller":"traceutil/trace.go:171","msg":"trace[705211480] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.571415327s","start":"2026-08-04T15:12:38.188964Z","end":"2026-08-04T15:12:39.760379Z","steps":["trace[705211480] 'process raft request' (duration: 1.570637927s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:39.760493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:38.188933Z","time spent":"1.571481987s","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:12:39.760634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314348905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:39.760744Z","caller":"traceutil/trace.go:171","msg":"trace[1236368152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.314514697s","start":"2026-08-04T15:12:38.446206Z","end":"2026-08-04T15:12:39.760721Z","steps":["trace[1236368152] 'agreement among raft nodes before linearized reading' (duration: 1.314314855s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:39.760832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:38.446181Z","time spent":"1.314639188s","remote":"127.0.0.1:60156","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-04T15:12:39.780926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.111133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:39.781012Z","caller":"traceutil/trace.go:171","msg":"trace[8879292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"695.267594ms","start":"2026-08-04T15:12:39.085725Z","end":"2026-08-04T15:12:39.780993Z","steps":["trace[8879292] 'agreement among raft nodes before linearized reading' (duration: 695.133052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:39.78105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:39.085698Z","time spent":"695.344046ms","remote":"127.0.0.1:60146","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-04T15:12:40.913782Z","caller":"traceutil/trace.go:171","msg":"trace[1051211562] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"178.707499ms","start":"2026-08-04T15:12:40.735044Z","end":"2026-08-04T15:12:40.913751Z","steps":["trace[1051211562] 'process raft request' (duration: 114.674513ms)","trace[1051211562] 'compare' (duration: 63.915254ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:12:46.094314Z","caller":"traceutil/trace.go:171","msg":"trace[2000249989] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"120.712976ms","start":"2026-08-04T15:12:45.973575Z","end":"2026-08-04T15:12:46.094288Z","steps":["trace[2000249989] 'process raft request' (duration: 119.971227ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:49.671632Z","caller":"traceutil/trace.go:171","msg":"trace[2046395737] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"144.472716ms","start":"2026-08-04T15:12:49.527126Z","end":"2026-08-04T15:12:49.671598Z","steps":["trace[2046395737] 'read index received' (duration: 82.480172ms)","trace[2046395737] 'applied index is now lower than readState.Index' (duration: 61.990984ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:12:49.671824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.661409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:49.671911Z","caller":"traceutil/trace.go:171","msg":"trace[1916889940] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:752; }","duration":"144.7687ms","start":"2026-08-04T15:12:49.527121Z","end":"2026-08-04T15:12:49.67189Z","steps":["trace[1916889940] 'agreement among raft nodes before linearized reading' (duration: 144.613148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:49.672025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.842841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:49.672125Z","caller":"traceutil/trace.go:171","msg":"trace[838543873] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:752; }","duration":"144.991072ms","start":"2026-08-04T15:12:49.52711Z","end":"2026-08-04T15:12:49.672101Z","steps":["trace[838543873] 'agreement among raft nodes before linearized reading' (duration: 144.81115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:49.672067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.161003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:49.672258Z","caller":"traceutil/trace.go:171","msg":"trace[733762437] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:752; }","duration":"144.408456ms","start":"2026-08-04T15:12:49.527826Z","end":"2026-08-04T15:12:49.672235Z","steps":["trace[733762437] 'agreement among raft nodes before linearized reading' (duration: 144.166433ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:12:57.826829Z","caller":"traceutil/trace.go:171","msg":"trace[420893656] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"294.026081ms","start":"2026-08-04T15:12:57.532785Z","end":"2026-08-04T15:12:57.826811Z","steps":["trace[420893656] 'read index received' (duration: 293.834948ms)","trace[420893656] 'applied index is now lower than readState.Index' (duration: 190.342µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:12:57.826893Z","caller":"traceutil/trace.go:171","msg":"trace[1201733012] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"990.210607ms","start":"2026-08-04T15:12:56.83665Z","end":"2026-08-04T15:12:57.826861Z","steps":["trace[1201733012] 'process raft request' (duration: 990.044455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:57.826945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.111061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:12:57.82697Z","caller":"traceutil/trace.go:171","msg":"trace[402757885] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:811; }","duration":"294.222652ms","start":"2026-08-04T15:12:57.532742Z","end":"2026-08-04T15:12:57.826965Z","steps":["trace[402757885] 'agreement among raft nodes before linearized reading' (duration: 294.133341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:12:57.827276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:12:56.836636Z","time spent":"990.341818ms","remote":"127.0.0.1:60372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:13:05.39965Z","caller":"traceutil/trace.go:171","msg":"trace[1021363246] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"140.25714ms","start":"2026-08-04T15:13:05.259374Z","end":"2026-08-04T15:13:05.399631Z","steps":["trace[1021363246] 'process raft request' (duration: 82.153488ms)","trace[1021363246] 'compare' (duration: 57.99731ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:13:05.400788Z","caller":"traceutil/trace.go:171","msg":"trace[1918792223] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"136.307054ms","start":"2026-08-04T15:13:05.264463Z","end":"2026-08-04T15:13:05.400771Z","steps":["trace[1918792223] 'process raft request' (duration: 136.273004ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:13:05.400949Z","caller":"traceutil/trace.go:171","msg":"trace[1810054587] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"140.652894ms","start":"2026-08-04T15:13:05.260275Z","end":"2026-08-04T15:13:05.400928Z","steps":["trace[1810054587] 'process raft request' (duration: 140.371851ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:13:08.256572Z","caller":"traceutil/trace.go:171","msg":"trace[1502188401] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"134.989765ms","start":"2026-08-04T15:13:08.121554Z","end":"2026-08-04T15:13:08.256544Z","steps":["trace[1502188401] 'read index received' (duration: 87.450721ms)","trace[1502188401] 'applied index is now lower than readState.Index' (duration: 47.538064ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:13:08.256907Z","caller":"traceutil/trace.go:171","msg":"trace[259083815] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"140.55279ms","start":"2026-08-04T15:13:08.116338Z","end":"2026-08-04T15:13:08.256891Z","steps":["trace[259083815] 'process raft request' (duration: 92.687191ms)","trace[259083815] 'compare' (duration: 47.238231ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:13:08.25691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.339499ms","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-04T15:13:08.257058Z","caller":"traceutil/trace.go:171","msg":"trace[586391151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"135.533282ms","start":"2026-08-04T15:13:08.121505Z","end":"2026-08-04T15:13:08.257038Z","steps":["trace[586391151] 'agreement among raft nodes before linearized reading' (duration: 135.239559ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:13:08.831559Z","caller":"traceutil/trace.go:171","msg":"trace[3746252] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"524.321503ms","start":"2026-08-04T15:13:08.307211Z","end":"2026-08-04T15:13:08.831533Z","steps":["trace[3746252] 'process raft request' (duration: 459.551851ms)","trace[3746252] 'compare' (duration: 64.234725ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:13:08.831685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:13:08.307191Z","time spent":"524.429274ms","remote":"127.0.0.1:60470","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-04T15:13:08.898266Z","caller":"traceutil/trace.go:171","msg":"trace[233119823] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"525.974561ms","start":"2026-08-04T15:13:08.37227Z","end":"2026-08-04T15:13:08.898245Z","steps":["trace[233119823] 'process raft request' (duration: 525.864009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:13:08.898385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:13:08.372254Z","time spent":"526.070343ms","remote":"127.0.0.1:60470","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-04T15:13:12.165004Z","caller":"traceutil/trace.go:171","msg":"trace[655191673] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"105.658607ms","start":"2026-08-04T15:13:12.059321Z","end":"2026-08-04T15:13:12.16498Z","steps":["trace[655191673] 'read index received' (duration: 105.501975ms)","trace[655191673] 'applied index is now lower than readState.Index' (duration: 155.702µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:13:12.165084Z","caller":"traceutil/trace.go:171","msg":"trace[1478351085] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"155.637342ms","start":"2026-08-04T15:13:12.009397Z","end":"2026-08-04T15:13:12.165034Z","steps":["trace[1478351085] 'process raft request' (duration: 155.45186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:13:12.165188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.862619ms","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-04T15:13:12.165225Z","caller":"traceutil/trace.go:171","msg":"trace[277288426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"105.95563ms","start":"2026-08-04T15:13:12.059258Z","end":"2026-08-04T15:13:12.165213Z","steps":["trace[277288426] 'agreement among raft nodes before linearized reading' (duration: 105.819179ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:13:34.401672Z","caller":"traceutil/trace.go:171","msg":"trace[1938575103] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"106.824199ms","start":"2026-08-04T15:13:34.294585Z","end":"2026-08-04T15:13:34.401409Z","steps":["trace[1938575103] 'process raft request' (duration: 106.713598ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:34.91022Z","caller":"traceutil/trace.go:171","msg":"trace[1464950507] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"149.368866ms","start":"2026-08-04T15:14:34.760819Z","end":"2026-08-04T15:14:34.910188Z","steps":["trace[1464950507] 'process raft request' (duration: 120.676008ms)","trace[1464950507] 'compare' (duration: 28.482462ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:14:34.910201Z","caller":"traceutil/trace.go:171","msg":"trace[1107182101] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1312; }","duration":"130.067329ms","start":"2026-08-04T15:14:34.780101Z","end":"2026-08-04T15:14:34.910168Z","steps":["trace[1107182101] 'read index received' (duration: 26.623774ms)","trace[1107182101] 'applied index is now lower than readState.Index' (duration: 103.442355ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:14:34.910413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.292255ms","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-04T15:14:34.910234Z","caller":"traceutil/trace.go:171","msg":"trace[1557313043] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"148.663995ms","start":"2026-08-04T15:14:34.761535Z","end":"2026-08-04T15:14:34.910199Z","steps":["trace[1557313043] 'process raft request' (duration: 148.566962ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:34.910481Z","caller":"traceutil/trace.go:171","msg":"trace[534317485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"130.394049ms","start":"2026-08-04T15:14:34.78007Z","end":"2026-08-04T15:14:34.910464Z","steps":["trace[534317485] 'agreement among raft nodes before linearized reading' (duration: 130.198013ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:35.456389Z","caller":"traceutil/trace.go:171","msg":"trace[890502698] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"109.323761ms","start":"2026-08-04T15:14:35.347036Z","end":"2026-08-04T15:14:35.456359Z","steps":["trace[890502698] 'process raft request' (duration: 109.137556ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:37.083472Z","caller":"traceutil/trace.go:171","msg":"trace[1969488397] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"158.323068ms","start":"2026-08-04T15:14:36.925124Z","end":"2026-08-04T15:14:37.083448Z","steps":["trace[1969488397] 'process raft request' (duration: 141.463253ms)","trace[1969488397] 'compare' (duration: 16.238396ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:14:38.726137Z","caller":"traceutil/trace.go:171","msg":"trace[1305551546] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"150.271178ms","start":"2026-08-04T15:14:38.575839Z","end":"2026-08-04T15:14:38.72611Z","steps":["trace[1305551546] 'process raft request' (duration: 87.175471ms)","trace[1305551546] 'compare' (duration: 62.8468ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:14:38.726723Z","caller":"traceutil/trace.go:171","msg":"trace[13596711] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"149.468503ms","start":"2026-08-04T15:14:38.576579Z","end":"2026-08-04T15:14:38.726048Z","steps":["trace[13596711] 'read index received' (duration: 86.431638ms)","trace[13596711] 'applied index is now lower than readState.Index' (duration: 63.035545ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:14:38.726546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.862445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:14:38.733806Z","caller":"traceutil/trace.go:171","msg":"trace[1244481610] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1281; }","duration":"157.2293ms","start":"2026-08-04T15:14:38.576536Z","end":"2026-08-04T15:14:38.733765Z","steps":["trace[1244481610] 'agreement among raft nodes before linearized reading' (duration: 149.68724ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:39.769501Z","caller":"traceutil/trace.go:171","msg":"trace[970691569] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"120.484456ms","start":"2026-08-04T15:14:39.648991Z","end":"2026-08-04T15:14:39.769475Z","steps":["trace[970691569] 'process raft request' (duration: 120.26115ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:49.79324Z","caller":"traceutil/trace.go:171","msg":"trace[781992819] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1397; }","duration":"193.905465ms","start":"2026-08-04T15:14:49.599306Z","end":"2026-08-04T15:14:49.793211Z","steps":["trace[781992819] 'read index received' (duration: 193.72196ms)","trace[781992819] 'applied index is now lower than readState.Index' (duration: 182.115µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:14:49.793297Z","caller":"traceutil/trace.go:171","msg":"trace[1229597908] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"214.894062ms","start":"2026-08-04T15:14:49.578363Z","end":"2026-08-04T15:14:49.793257Z","steps":["trace[1229597908] 'process raft request' (duration: 214.677506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:14:49.793566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.235575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-04T15:14:49.793638Z","caller":"traceutil/trace.go:171","msg":"trace[939458022] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1343; }","duration":"194.361259ms","start":"2026-08-04T15:14:49.599262Z","end":"2026-08-04T15:14:49.793623Z","steps":["trace[939458022] 'agreement among raft nodes before linearized reading' (duration: 194.103551ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:51.150509Z","caller":"traceutil/trace.go:171","msg":"trace[1407048604] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"139.506706ms","start":"2026-08-04T15:14:51.010976Z","end":"2026-08-04T15:14:51.150482Z","steps":["trace[1407048604] 'process raft request' (duration: 139.337072ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:51.908116Z","caller":"traceutil/trace.go:171","msg":"trace[2046743865] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"101.903ms","start":"2026-08-04T15:14:51.806188Z","end":"2026-08-04T15:14:51.908091Z","steps":["trace[2046743865] 'process raft request' (duration: 101.408496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:14:53.729468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.903516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-08-04T15:14:53.729549Z","caller":"traceutil/trace.go:171","msg":"trace[897256356] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1355; }","duration":"175.120613ms","start":"2026-08-04T15:14:53.554409Z","end":"2026-08-04T15:14:53.72953Z","steps":["trace[897256356] 'range keys from in-memory index tree' (duration: 174.540686ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:54.037541Z","caller":"traceutil/trace.go:171","msg":"trace[949072370] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"119.8673ms","start":"2026-08-04T15:14:53.91765Z","end":"2026-08-04T15:14:54.037517Z","steps":["trace[949072370] 'process raft request' (duration: 119.683794ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:57.411344Z","caller":"traceutil/trace.go:171","msg":"trace[840800518] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"146.849947ms","start":"2026-08-04T15:14:57.264468Z","end":"2026-08-04T15:14:57.411318Z","steps":["trace[840800518] 'process raft request' (duration: 146.714643ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:14:57.688094Z","caller":"traceutil/trace.go:171","msg":"trace[1924207771] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"118.604799ms","start":"2026-08-04T15:14:57.569451Z","end":"2026-08-04T15:14:57.688056Z","steps":["trace[1924207771] 'process raft request' (duration: 68.200583ms)","trace[1924207771] 'compare' (duration: 50.246971ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:14:59.808901Z","caller":"traceutil/trace.go:171","msg":"trace[1014252512] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"239.662638ms","start":"2026-08-04T15:14:59.569221Z","end":"2026-08-04T15:14:59.808883Z","steps":["trace[1014252512] 'read index received' (duration: 239.528014ms)","trace[1014252512] 'applied index is now lower than readState.Index' (duration: 134.014µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:14:59.80906Z","caller":"traceutil/trace.go:171","msg":"trace[756654118] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"277.460884ms","start":"2026-08-04T15:14:59.531589Z","end":"2026-08-04T15:14:59.80905Z","steps":["trace[756654118] 'process raft request' (duration: 277.206907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:14:59.809263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.028517ms","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-04T15:14:59.809285Z","caller":"traceutil/trace.go:171","msg":"trace[2013965132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1379; }","duration":"240.088719ms","start":"2026-08-04T15:14:59.569189Z","end":"2026-08-04T15:14:59.809278Z","steps":["trace[2013965132] 'agreement among raft nodes before linearized reading' (duration: 239.996037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:14:59.809358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.433708ms","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-04T15:14:59.809404Z","caller":"traceutil/trace.go:171","msg":"trace[1215189956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1379; }","duration":"117.51098ms","start":"2026-08-04T15:14:59.691881Z","end":"2026-08-04T15:14:59.809392Z","steps":["trace[1215189956] 'agreement among raft nodes before linearized reading' (duration: 117.368597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:00.245662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.332578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:00.245804Z","caller":"traceutil/trace.go:171","msg":"trace[62624558] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"432.359118ms","start":"2026-08-04T15:14:59.813424Z","end":"2026-08-04T15:15:00.245783Z","steps":["trace[62624558] 'process raft request' (duration: 174.815654ms)","trace[62624558] 'compare' (duration: 257.134913ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:00.245874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:14:59.81341Z","time spent":"432.430371ms","remote":"127.0.0.1:60470","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-04T15:15:00.325338Z","caller":"traceutil/trace.go:171","msg":"trace[697195617] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1437; }","duration":"238.95809ms","start":"2026-08-04T15:15:00.086353Z","end":"2026-08-04T15:15:00.325312Z","steps":["trace[697195617] 'read index received' (duration: 238.705013ms)","trace[697195617] 'applied index is now lower than readState.Index' (duration: 252.177µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:00.325457Z","caller":"traceutil/trace.go:171","msg":"trace[97795829] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"364.305227ms","start":"2026-08-04T15:14:59.961139Z","end":"2026-08-04T15:15:00.325444Z","steps":["trace[97795829] 'process raft request' (duration: 364.134853ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:00.325476Z","caller":"traceutil/trace.go:171","msg":"trace[1508361487] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"511.976111ms","start":"2026-08-04T15:14:59.813446Z","end":"2026-08-04T15:15:00.325422Z","steps":["trace[1508361487] 'process raft request' (duration: 511.709913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:00.325566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:14:59.961121Z","time spent":"364.36326ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:00.325777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:14:59.813416Z","time spent":"512.194328ms","remote":"127.0.0.1:60470","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-04T15:15:00.32585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.500616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:00.325889Z","caller":"traceutil/trace.go:171","msg":"trace[542677987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1382; }","duration":"239.575538ms","start":"2026-08-04T15:15:00.086305Z","end":"2026-08-04T15:15:00.32588Z","steps":["trace[542677987] 'agreement among raft nodes before linearized reading' (duration: 239.497916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:00.326216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.14711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-08-04T15:15:00.326258Z","caller":"traceutil/trace.go:171","msg":"trace[1333072323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"217.218112ms","start":"2026-08-04T15:15:00.10903Z","end":"2026-08-04T15:15:00.326248Z","steps":["trace[1333072323] 'agreement among raft nodes before linearized reading' (duration: 217.093689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:00.697097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.087441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:00.697182Z","caller":"traceutil/trace.go:171","msg":"trace[2110651134] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1439; }","duration":"359.069738ms","start":"2026-08-04T15:15:00.338097Z","end":"2026-08-04T15:15:00.697167Z","steps":["trace[2110651134] 'read index received' (duration: 122.645558ms)","trace[2110651134] 'applied index is now lower than readState.Index' (duration: 236.42319ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:00.697244Z","caller":"traceutil/trace.go:171","msg":"trace[1679487744] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"366.272916ms","start":"2026-08-04T15:15:00.330958Z","end":"2026-08-04T15:15:00.697231Z","steps":["trace[1679487744] 'process raft request' (duration: 129.97493ms)","trace[1679487744] 'compare' (duration: 235.994748ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:00.697306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:00.330939Z","time spent":"366.338078ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:00.697408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.318104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:00.697431Z","caller":"traceutil/trace.go:171","msg":"trace[1113471567] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1383; }","duration":"359.374406ms","start":"2026-08-04T15:15:00.33805Z","end":"2026-08-04T15:15:00.697425Z","steps":["trace[1113471567] 'agreement among raft nodes before linearized reading' (duration: 359.336695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:00.69745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:00.338034Z","time spent":"359.412017ms","remote":"127.0.0.1:60328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-08-04T15:15:01.197514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72233298276663848,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:15:01.680512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.178276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:01.68063Z","caller":"traceutil/trace.go:171","msg":"trace[168536844] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"983.40869ms","start":"2026-08-04T15:15:00.697202Z","end":"2026-08-04T15:15:01.680611Z","steps":["trace[168536844] 'read index received' (duration: 852.001667ms)","trace[168536844] 'applied index is now lower than readState.Index' (duration: 131.405413ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:01.680711Z","caller":"traceutil/trace.go:171","msg":"trace[1985375730] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"1.06913882s","start":"2026-08-04T15:15:00.61156Z","end":"2026-08-04T15:15:01.680699Z","steps":["trace[1985375730] 'process raft request' (duration: 937.678306ms)","trace[1985375730] 'compare' (duration: 130.58231ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:01.680776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:00.611532Z","time spent":"1.069211072s","remote":"127.0.0.1:60364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5898,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:01.680862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104276668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:15:01.680951Z","caller":"traceutil/trace.go:171","msg":"trace[654674307] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1384; }","duration":"1.104440672s","start":"2026-08-04T15:15:00.576488Z","end":"2026-08-04T15:15:01.680929Z","steps":["trace[654674307] 'agreement among raft nodes before linearized reading' (duration: 1.104281108s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:01.681116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:00.576464Z","time spent":"1.104639038s","remote":"127.0.0.1:59790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:15:02.039768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.037378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:02.039861Z","caller":"traceutil/trace.go:171","msg":"trace[1632444809] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1441; }","duration":"359.181794ms","start":"2026-08-04T15:15:01.680662Z","end":"2026-08-04T15:15:02.039844Z","steps":["trace[1632444809] 'read index received' (duration: 192.93161ms)","trace[1632444809] 'applied index is now lower than readState.Index' (duration: 166.248914ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:02.03994Z","caller":"traceutil/trace.go:171","msg":"trace[1607539099] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"538.48894ms","start":"2026-08-04T15:15:01.50144Z","end":"2026-08-04T15:15:02.039929Z","steps":["trace[1607539099] 'process raft request' (duration: 372.213685ms)","trace[1607539099] 'compare' (duration: 165.828182ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:02.039996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:01.501403Z","time spent":"538.566453ms","remote":"127.0.0.1:60470","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-04T15:15:02.040066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030015978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:02.040144Z","caller":"traceutil/trace.go:171","msg":"trace[528513658] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1385; }","duration":"1.030177463s","start":"2026-08-04T15:15:01.009948Z","end":"2026-08-04T15:15:02.040126Z","steps":["trace[528513658] 'agreement among raft nodes before linearized reading' (duration: 1.030033629s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.040192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337799892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:15:02.040197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:01.009927Z","time spent":"1.030259794s","remote":"127.0.0.1:60424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-08-04T15:15:02.040229Z","caller":"traceutil/trace.go:171","msg":"trace[1808848672] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1385; }","duration":"1.337857584s","start":"2026-08-04T15:15:00.702362Z","end":"2026-08-04T15:15:02.040219Z","steps":["trace[1808848672] 'agreement among raft nodes before linearized reading' (duration: 1.337800073s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.040262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:00.702342Z","time spent":"1.337914356s","remote":"127.0.0.1:60328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-08-04T15:15:02.040415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.858892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:02.04054Z","caller":"traceutil/trace.go:171","msg":"trace[486429938] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1385; }","duration":"903.026227ms","start":"2026-08-04T15:15:01.1375Z","end":"2026-08-04T15:15:02.040527Z","steps":["trace[486429938] 'agreement among raft nodes before linearized reading' (duration: 902.867802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.040597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.106631ms","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-04T15:15:02.040687Z","caller":"traceutil/trace.go:171","msg":"trace[1633627244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1385; }","duration":"596.242554ms","start":"2026-08-04T15:15:01.444423Z","end":"2026-08-04T15:15:02.040666Z","steps":["trace[1633627244] 'agreement among raft nodes before linearized reading' (duration: 596.038939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.040734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:01.444398Z","time spent":"596.327016ms","remote":"127.0.0.1:60470","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-04T15:15:02.040442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.233124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:02.040828Z","caller":"traceutil/trace.go:171","msg":"trace[2028783318] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1385; }","duration":"968.618564ms","start":"2026-08-04T15:15:01.072197Z","end":"2026-08-04T15:15:02.040815Z","steps":["trace[2028783318] 'agreement among raft nodes before linearized reading' (duration: 968.215693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.040875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:01.07215Z","time spent":"968.711447ms","remote":"127.0.0.1:56006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:15:02.040484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.692429ms","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-04T15:15:02.040938Z","caller":"traceutil/trace.go:171","msg":"trace[1895435469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1385; }","duration":"228.172022ms","start":"2026-08-04T15:15:01.812749Z","end":"2026-08-04T15:15:02.040921Z","steps":["trace[1895435469] 'agreement among raft nodes before linearized reading' (duration: 227.650798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.04064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:01.13748Z","time spent":"903.141679ms","remote":"127.0.0.1:60444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:15:02.605266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.151866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:02.605568Z","caller":"traceutil/trace.go:171","msg":"trace[783552808] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"561.168ms","start":"2026-08-04T15:15:02.044338Z","end":"2026-08-04T15:15:02.605506Z","steps":["trace[783552808] 'process raft request' (duration: 135.690305ms)","trace[783552808] 'compare' (duration: 424.867749ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:02.605871Z","caller":"traceutil/trace.go:171","msg":"trace[2734911] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1442; }","duration":"519.238906ms","start":"2026-08-04T15:15:02.086615Z","end":"2026-08-04T15:15:02.605854Z","steps":["trace[2734911] 'read index received' (duration: 93.422731ms)","trace[2734911] 'applied index is now lower than readState.Index' (duration: 425.815305ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:02.605772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.04432Z","time spent":"561.342565ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:15:02.605946Z","caller":"traceutil/trace.go:171","msg":"trace[1827585947] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"561.178992ms","start":"2026-08-04T15:15:02.044746Z","end":"2026-08-04T15:15:02.605925Z","steps":["trace[1827585947] 'process raft request' (duration: 560.674527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.606052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.044723Z","time spent":"561.283794ms","remote":"127.0.0.1:60470","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-04T15:15:02.606088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.476263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:02.606216Z","caller":"traceutil/trace.go:171","msg":"trace[323210011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1387; }","duration":"519.593346ms","start":"2026-08-04T15:15:02.086562Z","end":"2026-08-04T15:15:02.606156Z","steps":["trace[323210011] 'agreement among raft nodes before linearized reading' (duration: 519.434382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.606302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.086542Z","time spent":"519.710538ms","remote":"127.0.0.1:60156","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-04T15:15:02.953331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.04893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:02.953409Z","caller":"traceutil/trace.go:171","msg":"trace[2078490684] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"347.481057ms","start":"2026-08-04T15:15:02.605915Z","end":"2026-08-04T15:15:02.953396Z","steps":["trace[2078490684] 'read index received' (duration: 224.235602ms)","trace[2078490684] 'applied index is now lower than readState.Index' (duration: 123.244305ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:02.95345Z","caller":"traceutil/trace.go:171","msg":"trace[1468498215] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"621.781706ms","start":"2026-08-04T15:15:02.331637Z","end":"2026-08-04T15:15:02.953419Z","steps":["trace[1468498215] 'process raft request' (duration: 498.583132ms)","trace[1468498215] 'compare' (duration: 122.857274ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:02.953545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.990517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:15:02.95359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.331602Z","time spent":"621.915699ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:15:02.953622Z","caller":"traceutil/trace.go:171","msg":"trace[1352883511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"346.09856ms","start":"2026-08-04T15:15:02.607506Z","end":"2026-08-04T15:15:02.953605Z","steps":["trace[1352883511] 'agreement among raft nodes before linearized reading' (duration: 345.989337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.953641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.078236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"warn","ts":"2026-08-04T15:15:02.953643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.541335ms","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-04T15:15:02.953663Z","caller":"traceutil/trace.go:171","msg":"trace[2139029938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1388; }","duration":"251.134137ms","start":"2026-08-04T15:15:02.702522Z","end":"2026-08-04T15:15:02.953656Z","steps":["trace[2139029938] 'agreement among raft nodes before linearized reading' (duration: 251.080886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.953664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.607494Z","time spent":"346.163311ms","remote":"127.0.0.1:60146","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-04T15:15:02.953582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.102472ms","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-04T15:15:02.953685Z","caller":"traceutil/trace.go:171","msg":"trace[1977387305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1388; }","duration":"704.619308ms","start":"2026-08-04T15:15:02.249053Z","end":"2026-08-04T15:15:02.953672Z","steps":["trace[1977387305] 'agreement among raft nodes before linearized reading' (duration: 704.505284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.953724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.249033Z","time spent":"704.683249ms","remote":"127.0.0.1:60470","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-04T15:15:02.953747Z","caller":"traceutil/trace.go:171","msg":"trace[383880869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1388; }","duration":"623.287857ms","start":"2026-08-04T15:15:02.330429Z","end":"2026-08-04T15:15:02.953717Z","steps":["trace[383880869] 'agreement among raft nodes before linearized reading' (duration: 623.05092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:02.953854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.330405Z","time spent":"623.417811ms","remote":"127.0.0.1:60470","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-04T15:15:03.28286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.53676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:03.283218Z","caller":"traceutil/trace.go:171","msg":"trace[440983025] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"325.267587ms","start":"2026-08-04T15:15:02.957936Z","end":"2026-08-04T15:15:03.283203Z","steps":["trace[440983025] 'process raft request' (duration: 325.039371ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:03.283237Z","caller":"traceutil/trace.go:171","msg":"trace[1759683936] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"325.600946ms","start":"2026-08-04T15:15:02.957599Z","end":"2026-08-04T15:15:03.2832Z","steps":["trace[1759683936] 'process raft request' (duration: 170.648475ms)","trace[1759683936] 'compare' (duration: 154.288363ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:03.283349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.267409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:03.283262Z","caller":"traceutil/trace.go:171","msg":"trace[1203254623] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"324.052543ms","start":"2026-08-04T15:15:02.959188Z","end":"2026-08-04T15:15:03.28324Z","steps":["trace[1203254623] 'process raft request' (duration: 323.969511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:03.283445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.957583Z","time spent":"325.76101ms","remote":"127.0.0.1:60470","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-04T15:15:03.283474Z","caller":"traceutil/trace.go:171","msg":"trace[1049842387] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1391; }","duration":"216.392341ms","start":"2026-08-04T15:15:03.067039Z","end":"2026-08-04T15:15:03.283431Z","steps":["trace[1049842387] 'agreement among raft nodes before linearized reading' (duration: 216.207377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:03.283296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.957916Z","time spent":"325.341619ms","remote":"127.0.0.1:60470","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-04T15:15:03.283653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:02.95918Z","time spent":"324.272909ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:15:03.283216Z","caller":"traceutil/trace.go:171","msg":"trace[665049575] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1445; }","duration":"216.121294ms","start":"2026-08-04T15:15:03.067077Z","end":"2026-08-04T15:15:03.283198Z","steps":["trace[665049575] 'read index received' (duration: 61.028449ms)","trace[665049575] 'applied index is now lower than readState.Index' (duration: 155.091955ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:04.172323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.397929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:04.172404Z","caller":"traceutil/trace.go:171","msg":"trace[774560658] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1391; }","duration":"441.525903ms","start":"2026-08-04T15:15:03.730857Z","end":"2026-08-04T15:15:04.172383Z","steps":["trace[774560658] 'count revisions from in-memory index tree' (duration: 441.319268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:04.172486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:03.730841Z","time spent":"441.591805ms","remote":"127.0.0.1:50414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:15:04.172429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.472266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.213\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-04T15:15:04.172707Z","caller":"traceutil/trace.go:171","msg":"trace[956767262] range","detail":"{range_begin:/registry/masterleases/199.19.213.213; range_end:; response_count:1; response_revision:1391; }","duration":"101.783376ms","start":"2026-08-04T15:15:04.070905Z","end":"2026-08-04T15:15:04.172689Z","steps":["trace[956767262] 'range keys from in-memory index tree' (duration: 101.344653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:04.173017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.935402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:15:04.173061Z","caller":"traceutil/trace.go:171","msg":"trace[1179113698] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1391; }","duration":"125.021305ms","start":"2026-08-04T15:15:04.048029Z","end":"2026-08-04T15:15:04.173051Z","steps":["trace[1179113698] 'count revisions from in-memory index tree' (duration: 124.840549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:04.651361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.024233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:04.651463Z","caller":"traceutil/trace.go:171","msg":"trace[1150285287] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"223.51712ms","start":"2026-08-04T15:15:04.427932Z","end":"2026-08-04T15:15:04.651449Z","steps":["trace[1150285287] 'read index received' (duration: 50.561µs)","trace[1150285287] 'applied index is now lower than readState.Index' (duration: 223.465329ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:04.651537Z","caller":"traceutil/trace.go:171","msg":"trace[1838346079] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"411.574461ms","start":"2026-08-04T15:15:04.239951Z","end":"2026-08-04T15:15:04.651526Z","steps":["trace[1838346079] 'process raft request' (duration: 98.322192ms)","trace[1838346079] 'compare' (duration: 312.931401ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:04.651621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:04.239931Z","time spent":"411.634812ms","remote":"127.0.0.1:60200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:04.651762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.943311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:04.651861Z","caller":"traceutil/trace.go:171","msg":"trace[1751924158] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1392; }","duration":"156.113326ms","start":"2026-08-04T15:15:04.495723Z","end":"2026-08-04T15:15:04.651836Z","steps":["trace[1751924158] 'agreement among raft nodes before linearized reading' (duration: 155.90884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:04.652008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.049304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:04.652098Z","caller":"traceutil/trace.go:171","msg":"trace[1982623338] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1392; }","duration":"224.186958ms","start":"2026-08-04T15:15:04.427891Z","end":"2026-08-04T15:15:04.652078Z","steps":["trace[1982623338] 'agreement among raft nodes before linearized reading' (duration: 223.988693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.49433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.901112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:05.494591Z","caller":"traceutil/trace.go:171","msg":"trace[2029075692] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"838.128161ms","start":"2026-08-04T15:15:04.656388Z","end":"2026-08-04T15:15:05.494516Z","steps":["trace[2029075692] 'process raft request' (duration: 179.960292ms)","trace[2029075692] 'compare' (duration: 657.179022ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:05.494627Z","caller":"traceutil/trace.go:171","msg":"trace[1730156990] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1450; }","duration":"837.565064ms","start":"2026-08-04T15:15:04.656971Z","end":"2026-08-04T15:15:05.494536Z","steps":["trace[1730156990] 'read index received' (duration: 179.458937ms)","trace[1730156990] 'applied index is now lower than readState.Index' (duration: 658.104917ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:05.494846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:04.656374Z","time spent":"838.350796ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:05.494904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.910213ms","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-04T15:15:05.49495Z","caller":"traceutil/trace.go:171","msg":"trace[750896115] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1394; }","duration":"837.985766ms","start":"2026-08-04T15:15:04.656954Z","end":"2026-08-04T15:15:05.494939Z","steps":["trace[750896115] 'agreement among raft nodes before linearized reading' (duration: 837.854453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.494984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:04.656947Z","time spent":"838.029547ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-08-04T15:15:05.494601Z","caller":"traceutil/trace.go:171","msg":"trace[2136569676] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"838.15128ms","start":"2026-08-04T15:15:04.656417Z","end":"2026-08-04T15:15:05.494568Z","steps":["trace[2136569676] 'process raft request' (duration: 838.026617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.495099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:04.656408Z","time spent":"838.627563ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:15:05.920699Z","caller":"traceutil/trace.go:171","msg":"trace[333525835] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1452; }","duration":"425.891988ms","start":"2026-08-04T15:15:05.494777Z","end":"2026-08-04T15:15:05.920669Z","steps":["trace[333525835] 'read index received' (duration: 425.727104ms)","trace[333525835] 'applied index is now lower than readState.Index' (duration: 163.674µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:05.920826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.464744ms","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-04T15:15:05.920869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.544476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:05.920878Z","caller":"traceutil/trace.go:171","msg":"trace[1720087907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1395; }","duration":"633.570406ms","start":"2026-08-04T15:15:05.287293Z","end":"2026-08-04T15:15:05.920863Z","steps":["trace[1720087907] 'agreement among raft nodes before linearized reading' (duration: 633.389822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.920861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.520952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:15:05.920915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:05.287271Z","time spent":"633.638588ms","remote":"127.0.0.1:60470","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-04T15:15:05.920933Z","caller":"traceutil/trace.go:171","msg":"trace[656431348] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1395; }","duration":"785.617515ms","start":"2026-08-04T15:15:05.135298Z","end":"2026-08-04T15:15:05.920916Z","steps":["trace[656431348] 'agreement among raft nodes before linearized reading' (duration: 785.477291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.920926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.562086ms","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-04T15:15:05.920918Z","caller":"traceutil/trace.go:171","msg":"trace[1677571110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1395; }","duration":"830.665068ms","start":"2026-08-04T15:15:05.090236Z","end":"2026-08-04T15:15:05.920901Z","steps":["trace[1677571110] 'agreement among raft nodes before linearized reading' (duration: 830.546615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.921027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:05.135279Z","time spent":"785.729568ms","remote":"127.0.0.1:35984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-04T15:15:05.921039Z","caller":"traceutil/trace.go:171","msg":"trace[686486276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1395; }","duration":"633.728481ms","start":"2026-08-04T15:15:05.28729Z","end":"2026-08-04T15:15:05.921018Z","steps":["trace[686486276] 'agreement among raft nodes before linearized reading' (duration: 633.457813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.921037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.633868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-04T15:15:05.921089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:05.287269Z","time spent":"633.811182ms","remote":"127.0.0.1:60470","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-04T15:15:05.921116Z","caller":"traceutil/trace.go:171","msg":"trace[345296788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1395; }","duration":"633.740181ms","start":"2026-08-04T15:15:05.287354Z","end":"2026-08-04T15:15:05.921095Z","steps":["trace[345296788] 'agreement among raft nodes before linearized reading' (duration: 633.472403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.921161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:05.287347Z","time spent":"633.805962ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-04T15:15:05.921106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:05.09022Z","time spent":"830.853034ms","remote":"127.0.0.1:60156","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-04T15:15:05.921333Z","caller":"traceutil/trace.go:171","msg":"trace[503580158] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"960.234176ms","start":"2026-08-04T15:15:04.960501Z","end":"2026-08-04T15:15:05.920735Z","steps":["trace[503580158] 'process raft request' (duration: 960.00409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:05.921418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:04.960421Z","time spent":"960.939755ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:15:05.963491Z","caller":"traceutil/trace.go:171","msg":"trace[1482766277] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"185.813847ms","start":"2026-08-04T15:15:05.777623Z","end":"2026-08-04T15:15:05.963436Z","steps":["trace[1482766277] 'process raft request' (duration: 185.567661ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:39.90382Z","caller":"traceutil/trace.go:171","msg":"trace[724845679] linearizableReadLoop","detail":"{readStateIndex:1576; appliedIndex:1575; }","duration":"112.492176ms","start":"2026-08-04T15:15:39.791305Z","end":"2026-08-04T15:15:39.903798Z","steps":["trace[724845679] 'read index received' (duration: 40.481µs)","trace[724845679] 'applied index is now lower than readState.Index' (duration: 112.450515ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:39.903962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.640971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-08-04T15:15:39.903996Z","caller":"traceutil/trace.go:171","msg":"trace[449723537] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1510; }","duration":"112.705203ms","start":"2026-08-04T15:15:39.791279Z","end":"2026-08-04T15:15:39.903985Z","steps":["trace[449723537] 'agreement among raft nodes before linearized reading' (duration: 112.608641ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:39.90427Z","caller":"traceutil/trace.go:171","msg":"trace[335918306] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"112.826656ms","start":"2026-08-04T15:15:39.79128Z","end":"2026-08-04T15:15:39.904107Z","steps":["trace[335918306] 'process raft request' (duration: 110.332487ms)"],"step_count":1}