{"level":"warn","ts":"2026-04-27T11:58:45.124977Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T11:58:45.125088Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.117: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.117:2380","--initial-cluster=instance=https://199.19.213.117:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.117:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.117: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-04-27T11:58:45.125161Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T11:58:45.125175Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-04-27T11:58:45.125193Z","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-04-27T11:58:45.125854Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"]} {"level":"info","ts":"2026-04-27T11:58:45.125934Z","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.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.117: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-04-27T11:58:45.161006Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"34.819651ms"} {"level":"info","ts":"2026-04-27T11:58:45.201373Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f9f905057b5b268","cluster-id":"293172f52aeb3f03"} {"level":"info","ts":"2026-04-27T11:58:45.201502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T11:58:45.201531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 0"} {"level":"info","ts":"2026-04-27T11:58:45.201737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f9f905057b5b268 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T11:58:45.201745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 1"} {"level":"info","ts":"2026-04-27T11:58:45.201796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"warn","ts":"2026-04-27T11:58:45.294823Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T11:58:45.302005Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T11:58:45.311369Z","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-04-27T11:58:45.329058Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f9f905057b5b268","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T11:58:45.334549Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f9f905057b5b268","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T11:58:45.334647Z","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-04-27T11:58:45.334888Z","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-04-27T11:58:45.335065Z","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-04-27T11:58:45.341172Z","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-04-27T11:58:45.3415Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f9f905057b5b268","initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T11:58:45.341562Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T11:58:45.342201Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-04-27T11:58:45.342267Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-04-27T11:58:45.362408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"info","ts":"2026-04-27T11:58:45.362692Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","added-peer-id":"5f9f905057b5b268","added-peer-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-04-27T11:58:45.902361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T11:58:45.902426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T11:58:45.902463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgPreVoteResp from 5f9f905057b5b268 at term 1"} {"level":"info","ts":"2026-04-27T11:58:45.902481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became candidate at term 2"} {"level":"info","ts":"2026-04-27T11:58:45.902491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgVoteResp from 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-04-27T11:58:45.902519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became leader at term 2"} {"level":"info","ts":"2026-04-27T11:58:45.902531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f9f905057b5b268 elected leader 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-04-27T11:58:45.90647Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:58:45.910694Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f9f905057b5b268","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.117:2379]}","request-path":"/0/members/5f9f905057b5b268/attributes","cluster-id":"293172f52aeb3f03","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T11:58:45.910844Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T11:58:45.913002Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.117:2379"} {"level":"info","ts":"2026-04-27T11:58:45.913203Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T11:58:45.913256Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T11:58:45.91349Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T11:58:45.915859Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:58:45.915953Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:58:45.915991Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:58:45.91669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T11:58:48.834219Z","caller":"traceutil/trace.go:171","msg":"trace[2099128279] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"229.357125ms","start":"2026-04-27T11:58:48.604835Z","end":"2026-04-27T11:58:48.834193Z","steps":["trace[2099128279] 'process raft request' (duration: 229.21551ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:52.520539Z","caller":"traceutil/trace.go:171","msg":"trace[1583376546] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"113.499374ms","start":"2026-04-27T11:58:52.406995Z","end":"2026-04-27T11:58:52.520494Z","steps":["trace[1583376546] 'process raft request' (duration: 113.05206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:58:52.520472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.720896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:58:52.520708Z","caller":"traceutil/trace.go:171","msg":"trace[1767602210] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:274; }","duration":"109.009879ms","start":"2026-04-27T11:58:52.411635Z","end":"2026-04-27T11:58:52.520645Z","steps":["trace[1767602210] 'agreement among raft nodes before linearized reading' (duration: 108.677776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:58:56.593422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.137027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-h4rrm\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-04-27T11:58:56.59353Z","caller":"traceutil/trace.go:171","msg":"trace[2012785746] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-h4rrm; range_end:; response_count:1; response_revision:348; }","duration":"188.293959ms","start":"2026-04-27T11:58:56.405212Z","end":"2026-04-27T11:58:56.593506Z","steps":["trace[2012785746] 'range keys from in-memory index tree' (duration: 188.040706ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:58.943516Z","caller":"traceutil/trace.go:171","msg":"trace[2049204484] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"188.205347ms","start":"2026-04-27T11:58:58.755284Z","end":"2026-04-27T11:58:58.943489Z","steps":["trace[2049204484] 'process raft request' (duration: 188.033146ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:00.149713Z","caller":"traceutil/trace.go:171","msg":"trace[1347913247] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"240.880971ms","start":"2026-04-27T11:58:59.908805Z","end":"2026-04-27T11:59:00.149686Z","steps":["trace[1347913247] 'read index received' (duration: 215.978923ms)","trace[1347913247] 'applied index is now lower than readState.Index' (duration: 24.900928ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:00.149888Z","caller":"traceutil/trace.go:171","msg":"trace[534653870] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"242.144814ms","start":"2026-04-27T11:58:59.907703Z","end":"2026-04-27T11:59:00.149848Z","steps":["trace[534653870] 'process raft request' (duration: 217.188856ms)","trace[534653870] 'compare' (duration: 24.664895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:00.149922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.088674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-27T11:59:00.150004Z","caller":"traceutil/trace.go:171","msg":"trace[1428999373] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:362; }","duration":"241.209945ms","start":"2026-04-27T11:58:59.908774Z","end":"2026-04-27T11:59:00.149984Z","steps":["trace[1428999373] 'agreement among raft nodes before linearized reading' (duration: 241.067983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:00.150071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.387783ms","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-04-27T11:59:00.150187Z","caller":"traceutil/trace.go:171","msg":"trace[2143963408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"170.501504ms","start":"2026-04-27T11:58:59.979637Z","end":"2026-04-27T11:59:00.150139Z","steps":["trace[2143963408] 'agreement among raft nodes before linearized reading' (duration: 170.377233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:00.150182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.809779ms","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-04-27T11:59:00.150335Z","caller":"traceutil/trace.go:171","msg":"trace[462265168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:362; }","duration":"213.061042ms","start":"2026-04-27T11:58:59.937255Z","end":"2026-04-27T11:59:00.150316Z","steps":["trace[462265168] 'agreement among raft nodes before linearized reading' (duration: 212.759389ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:01.138031Z","caller":"traceutil/trace.go:171","msg":"trace[89202534] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"186.239251ms","start":"2026-04-27T11:59:00.951762Z","end":"2026-04-27T11:59:01.138001Z","steps":["trace[89202534] 'process raft request' (duration: 186.054068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:03.101333Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855698647880422497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T11:59:03.464277Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072720557s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T11:59:03.47806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119766712s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T11:59:03.478983Z","caller":"traceutil/trace.go:171","msg":"trace[2021969463] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:378; }","duration":"877.943573ms","start":"2026-04-27T11:59:02.600965Z","end":"2026-04-27T11:59:03.478908Z","steps":["trace[2021969463] 'read index received' (duration: 863.619933ms)","trace[2021969463] 'applied index is now lower than readState.Index' (duration: 14.099267ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:03.479545Z","caller":"traceutil/trace.go:171","msg":"trace[1903808728] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"1.259223328s","start":"2026-04-27T11:59:02.220299Z","end":"2026-04-27T11:59:03.479522Z","steps":["trace[1903808728] 'process raft request' (duration: 135.564903ms)","trace[1903808728] 'compare' (duration: 1.11957106s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:03.480114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:02.220278Z","time spent":"1.25934442s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:03.480766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.157308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T11:59:03.480843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.771493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:03.480886Z","caller":"traceutil/trace.go:171","msg":"trace[162052865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"339.28523ms","start":"2026-04-27T11:59:03.14158Z","end":"2026-04-27T11:59:03.480865Z","steps":["trace[162052865] 'agreement among raft nodes before linearized reading' (duration: 338.962516ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:03.48093Z","caller":"traceutil/trace.go:171","msg":"trace[105058973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"880.001236ms","start":"2026-04-27T11:59:02.600906Z","end":"2026-04-27T11:59:03.480907Z","steps":["trace[105058973] 'agreement among raft nodes before linearized reading' (duration: 879.301579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:03.480994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:03.141523Z","time spent":"339.431792ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T11:59:03.480992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:02.600883Z","time spent":"880.088577ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T11:59:03.481503Z","caller":"traceutil/trace.go:171","msg":"trace[229216307] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"1.261077339s","start":"2026-04-27T11:59:02.220407Z","end":"2026-04-27T11:59:03.481485Z","steps":["trace[229216307] 'process raft request' (duration: 1.257880354s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:03.481662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:02.220369Z","time spent":"1.261178081s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:03.606918Z","caller":"traceutil/trace.go:171","msg":"trace[1419307456] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"114.756738ms","start":"2026-04-27T11:59:03.492123Z","end":"2026-04-27T11:59:03.60688Z","steps":["trace[1419307456] 'process raft request' (duration: 98.145823ms)","trace[1419307456] 'compare' (duration: 16.468304ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:12.358354Z","caller":"traceutil/trace.go:171","msg":"trace[1955264891] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"153.052115ms","start":"2026-04-27T11:59:12.205257Z","end":"2026-04-27T11:59:12.358309Z","steps":["trace[1955264891] 'process raft request' (duration: 152.831632ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:12.68918Z","caller":"traceutil/trace.go:171","msg":"trace[1257661915] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"249.686445ms","start":"2026-04-27T11:59:12.439475Z","end":"2026-04-27T11:59:12.689162Z","steps":["trace[1257661915] 'process raft request' (duration: 249.621675ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:12.689255Z","caller":"traceutil/trace.go:171","msg":"trace[1392458200] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"250.201082ms","start":"2026-04-27T11:59:12.439031Z","end":"2026-04-27T11:59:12.689232Z","steps":["trace[1392458200] 'process raft request' (duration: 170.285454ms)","trace[1392458200] 'compare' (duration: 79.565044ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:18.35545Z","caller":"traceutil/trace.go:171","msg":"trace[1967424591] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"236.677404ms","start":"2026-04-27T11:59:18.118739Z","end":"2026-04-27T11:59:18.355416Z","steps":["trace[1967424591] 'process raft request' (duration: 235.830091ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:24.971188Z","caller":"traceutil/trace.go:171","msg":"trace[28010823] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"244.212686ms","start":"2026-04-27T11:59:24.726956Z","end":"2026-04-27T11:59:24.971169Z","steps":["trace[28010823] 'read index received' (duration: 244.048263ms)","trace[28010823] 'applied index is now lower than readState.Index' (duration: 163.693µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:24.971326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.359068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:24.971348Z","caller":"traceutil/trace.go:171","msg":"trace[1189804182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"244.434669ms","start":"2026-04-27T11:59:24.726905Z","end":"2026-04-27T11:59:24.97134Z","steps":["trace[1189804182] 'agreement among raft nodes before linearized reading' (duration: 244.361188ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:24.971446Z","caller":"traceutil/trace.go:171","msg":"trace[130914274] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"478.912881ms","start":"2026-04-27T11:59:24.492489Z","end":"2026-04-27T11:59:24.971402Z","steps":["trace[130914274] 'process raft request' (duration: 478.492115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:24.971653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:24.492432Z","time spent":"479.118504ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:25.393602Z","caller":"traceutil/trace.go:171","msg":"trace[1413613735] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"420.535577ms","start":"2026-04-27T11:59:24.972992Z","end":"2026-04-27T11:59:25.393528Z","steps":["trace[1413613735] 'read index received' (duration: 334.524866ms)","trace[1413613735] 'applied index is now lower than readState.Index' (duration: 86.009151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:25.393611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.607288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:25.393687Z","caller":"traceutil/trace.go:171","msg":"trace[25293639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"420.72144ms","start":"2026-04-27T11:59:24.972951Z","end":"2026-04-27T11:59:25.393672Z","steps":["trace[25293639] 'agreement among raft nodes before linearized reading' (duration: 420.588148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:25.393733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:24.972934Z","time spent":"420.782101ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T11:59:26.265833Z","caller":"traceutil/trace.go:171","msg":"trace[2070264908] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"502.737902ms","start":"2026-04-27T11:59:25.763061Z","end":"2026-04-27T11:59:26.265799Z","steps":["trace[2070264908] 'process raft request' (duration: 502.497368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:26.266057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.763037Z","time spent":"502.919164ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:26.28291Z","caller":"traceutil/trace.go:171","msg":"trace[1010576902] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"503.349275ms","start":"2026-04-27T11:59:25.779536Z","end":"2026-04-27T11:59:26.282886Z","steps":["trace[1010576902] 'process raft request' (duration: 503.158671ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:26.282959Z","caller":"traceutil/trace.go:171","msg":"trace[518128078] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"503.239523ms","start":"2026-04-27T11:59:25.779677Z","end":"2026-04-27T11:59:26.282916Z","steps":["trace[518128078] 'process raft request' (duration: 503.128252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:26.283048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.77952Z","time spent":"503.455487ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:26.283067Z","caller":"traceutil/trace.go:171","msg":"trace[1985039557] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"498.452027ms","start":"2026-04-27T11:59:25.784558Z","end":"2026-04-27T11:59:26.28301Z","steps":["trace[1985039557] 'process raft request' (duration: 498.310505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:26.283115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.779672Z","time spent":"503.351635ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:26.283184Z","caller":"traceutil/trace.go:171","msg":"trace[629778745] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"501.294142ms","start":"2026-04-27T11:59:25.781879Z","end":"2026-04-27T11:59:26.283173Z","steps":["trace[629778745] 'process raft request' (duration: 500.960107ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:26.283256Z","caller":"traceutil/trace.go:171","msg":"trace[1654758247] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"325.888556ms","start":"2026-04-27T11:59:25.957356Z","end":"2026-04-27T11:59:26.283245Z","steps":["trace[1654758247] 'process raft request' (duration: 325.829665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:26.283256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.781844Z","time spent":"501.388773ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:26.283297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.957337Z","time spent":"325.940227ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:26.28333Z","caller":"traceutil/trace.go:171","msg":"trace[1624304867] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"489.742327ms","start":"2026-04-27T11:59:25.79358Z","end":"2026-04-27T11:59:26.283322Z","steps":["trace[1624304867] 'process raft request' (duration: 489.369001ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:26.28335Z","caller":"traceutil/trace.go:171","msg":"trace[1932823556] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"488.505377ms","start":"2026-04-27T11:59:25.79484Z","end":"2026-04-27T11:59:26.283346Z","steps":["trace[1932823556] 'process raft request' (duration: 488.143071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:26.283374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.794825Z","time spent":"488.536108ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:26.283266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.784539Z","time spent":"498.633689ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:26.283391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.793568Z","time spent":"489.786647ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:26.283421Z","caller":"traceutil/trace.go:171","msg":"trace[1855146052] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"480.725442ms","start":"2026-04-27T11:59:25.802691Z","end":"2026-04-27T11:59:26.283416Z","steps":["trace[1855146052] 'process raft request' (duration: 480.321626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:26.283443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.802674Z","time spent":"480.759033ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:26.283453Z","caller":"traceutil/trace.go:171","msg":"trace[2137025829] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"458.845991ms","start":"2026-04-27T11:59:25.824586Z","end":"2026-04-27T11:59:26.283432Z","steps":["trace[2137025829] 'process raft request' (duration: 458.508905ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:26.28352Z","caller":"traceutil/trace.go:171","msg":"trace[1477909510] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"497.451761ms","start":"2026-04-27T11:59:25.786063Z","end":"2026-04-27T11:59:26.283515Z","steps":["trace[1477909510] 'process raft request' (duration: 496.828831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:26.283548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.786054Z","time spent":"497.479081ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:26.283543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.824553Z","time spent":"458.948703ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:26.283603Z","caller":"traceutil/trace.go:171","msg":"trace[1265979049] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"491.084389ms","start":"2026-04-27T11:59:25.792513Z","end":"2026-04-27T11:59:26.283598Z","steps":["trace[1265979049] 'process raft request' (duration: 490.401808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:26.283628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:25.792505Z","time spent":"491.109219ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:26.668403Z","caller":"traceutil/trace.go:171","msg":"trace[928311361] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"373.542477ms","start":"2026-04-27T11:59:26.29483Z","end":"2026-04-27T11:59:26.668372Z","steps":["trace[928311361] 'process raft request' (duration: 310.684857ms)","trace[928311361] 'compare' (duration: 62.557296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:26.668624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.294802Z","time spent":"373.733021ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:28.606068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596686719s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T11:59:28.606559Z","caller":"traceutil/trace.go:171","msg":"trace[2030899235] transaction","detail":"{read_only:false; number_of_response:1; response_revision:508; }","duration":"2.298686054s","start":"2026-04-27T11:59:26.307855Z","end":"2026-04-27T11:59:28.606541Z","steps":["trace[2030899235] 'process raft request' (duration: 2.29845398s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.60666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.307825Z","time spent":"2.298785566s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:28.606694Z","caller":"traceutil/trace.go:171","msg":"trace[978708335] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"2.298973789s","start":"2026-04-27T11:59:26.307694Z","end":"2026-04-27T11:59:28.606667Z","steps":["trace[978708335] 'process raft request' (duration: 2.298590003s)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:28.606731Z","caller":"traceutil/trace.go:171","msg":"trace[1351261572] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"2.29905438s","start":"2026-04-27T11:59:26.307627Z","end":"2026-04-27T11:59:28.606681Z","steps":["trace[1351261572] 'process raft request' (duration: 2.298630073s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.606805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.307689Z","time spent":"2.29905965s","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:28.606819Z","caller":"traceutil/trace.go:171","msg":"trace[1038846470] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"2.309369197s","start":"2026-04-27T11:59:26.297409Z","end":"2026-04-27T11:59:28.606778Z","steps":["trace[1038846470] 'process raft request' (duration: 711.900025ms)","trace[1038846470] 'compare' (duration: 1.596544087s)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:28.606835Z","caller":"traceutil/trace.go:171","msg":"trace[1290878993] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"2.308026456s","start":"2026-04-27T11:59:26.298768Z","end":"2026-04-27T11:59:28.606795Z","steps":["trace[1290878993] 'process raft request' (duration: 2.307398076s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.606886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.307604Z","time spent":"2.299216802s","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:28.606928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.297387Z","time spent":"2.309492809s","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:28.606944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.298756Z","time spent":"2.308136727s","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:28.607074Z","caller":"traceutil/trace.go:171","msg":"trace[1717886789] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"2.306660563s","start":"2026-04-27T11:59:26.300401Z","end":"2026-04-27T11:59:28.607062Z","steps":["trace[1717886789] 'process raft request' (duration: 2.305812539s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.607153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.30039Z","time spent":"2.306712544s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:28.607217Z","caller":"traceutil/trace.go:171","msg":"trace[757945853] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"2.292054946s","start":"2026-04-27T11:59:26.315139Z","end":"2026-04-27T11:59:28.607194Z","steps":["trace[757945853] 'process raft request' (duration: 2.291329555s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.607281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.311271Z","time spent":"2.295968639s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:28.607308Z","caller":"traceutil/trace.go:171","msg":"trace[456117736] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"2.291826902s","start":"2026-04-27T11:59:26.315471Z","end":"2026-04-27T11:59:28.607298Z","steps":["trace[456117736] 'process raft request' (duration: 2.291625249s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.607368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.315464Z","time spent":"2.291865784s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:28.60749Z","caller":"traceutil/trace.go:171","msg":"trace[1553728384] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"2.291923304s","start":"2026-04-27T11:59:26.315553Z","end":"2026-04-27T11:59:28.607476Z","steps":["trace[1553728384] 'process raft request' (duration: 2.29168307s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.607567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.315546Z","time spent":"2.291985535s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:28.607618Z","caller":"traceutil/trace.go:171","msg":"trace[156431565] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"2.260676387s","start":"2026-04-27T11:59:26.346932Z","end":"2026-04-27T11:59:28.607609Z","steps":["trace[156431565] 'process raft request' (duration: 2.260626916s)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:28.607658Z","caller":"traceutil/trace.go:171","msg":"trace[329011502] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"2.277578802s","start":"2026-04-27T11:59:26.330071Z","end":"2026-04-27T11:59:28.60765Z","steps":["trace[329011502] 'process raft request' (duration: 2.277334728s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.607666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.346902Z","time spent":"2.260744868s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:28.607699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.33004Z","time spent":"2.277637512s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:28.610185Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.600817907s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T11:59:28.61048Z","caller":"traceutil/trace.go:171","msg":"trace[1651429497] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:519; }","duration":"1.885248609s","start":"2026-04-27T11:59:26.725203Z","end":"2026-04-27T11:59:28.610452Z","steps":["trace[1651429497] 'read index received' (duration: 284.117807ms)","trace[1651429497] 'applied index is now lower than readState.Index' (duration: 1.601129782s)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:28.610549Z","caller":"traceutil/trace.go:171","msg":"trace[1231532420] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.926901715s","start":"2026-04-27T11:59:26.683635Z","end":"2026-04-27T11:59:28.610537Z","steps":["trace[1231532420] 'process raft request' (duration: 1.926705001s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.610642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.683615Z","time spent":"1.926957945s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:28.610831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.885636346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:28.610862Z","caller":"traceutil/trace.go:171","msg":"trace[1103823968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"1.885705997s","start":"2026-04-27T11:59:26.725147Z","end":"2026-04-27T11:59:28.610853Z","steps":["trace[1103823968] 'agreement among raft nodes before linearized reading' (duration: 1.885632875s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.610882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.725124Z","time spent":"1.885753698s","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T11:59:28.611172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635512583s","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-04-27T11:59:28.611203Z","caller":"traceutil/trace.go:171","msg":"trace[1669011573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"1.635573513s","start":"2026-04-27T11:59:26.97562Z","end":"2026-04-27T11:59:28.611194Z","steps":["trace[1669011573] 'agreement among raft nodes before linearized reading' (duration: 1.63537149s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.611488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:26.975465Z","time spent":"1.636011661s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T11:59:28.611716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.138269ms","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-04-27T11:59:28.61174Z","caller":"traceutil/trace.go:171","msg":"trace[460045838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"905.188179ms","start":"2026-04-27T11:59:27.706545Z","end":"2026-04-27T11:59:28.611733Z","steps":["trace[460045838] 'agreement among raft nodes before linearized reading' (duration: 905.120468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.611759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:27.706533Z","time spent":"905.22104ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T11:59:28.611873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.225791ms","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-04-27T11:59:28.611891Z","caller":"traceutil/trace.go:171","msg":"trace[350741732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"930.270451ms","start":"2026-04-27T11:59:27.681615Z","end":"2026-04-27T11:59:28.611886Z","steps":["trace[350741732] 'agreement among raft nodes before linearized reading' (duration: 930.214661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.611909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:27.681604Z","time spent":"930.301252ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T11:59:28.61202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.991361ms","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-04-27T11:59:28.612042Z","caller":"traceutil/trace.go:171","msg":"trace[1818241747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"932.058312ms","start":"2026-04-27T11:59:27.679976Z","end":"2026-04-27T11:59:28.612034Z","steps":["trace[1818241747] 'agreement among raft nodes before linearized reading' (duration: 931.999381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.612065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:27.679952Z","time spent":"932.106622ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T11:59:29.794239Z","caller":"traceutil/trace.go:171","msg":"trace[251341336] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"356.113617ms","start":"2026-04-27T11:59:29.438098Z","end":"2026-04-27T11:59:29.794211Z","steps":["trace[251341336] 'process raft request' (duration: 355.957624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:29.794387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:29.438078Z","time spent":"356.25014ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:30.504108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.172176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T11:59:30.504183Z","caller":"traceutil/trace.go:171","msg":"trace[154293034] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:579; }","duration":"786.938787ms","start":"2026-04-27T11:59:29.717232Z","end":"2026-04-27T11:59:30.504171Z","steps":["trace[154293034] 'read index received' (duration: 76.759289ms)","trace[154293034] 'applied index is now lower than readState.Index' (duration: 710.178728ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:30.504233Z","caller":"traceutil/trace.go:171","msg":"trace[1365613540] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"980.818271ms","start":"2026-04-27T11:59:29.523405Z","end":"2026-04-27T11:59:30.504224Z","steps":["trace[1365613540] 'process raft request' (duration: 488.451151ms)","trace[1365613540] 'compare' (duration: 491.870811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:30.504288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:29.523377Z","time spent":"980.886491ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:30.504304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.059459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T11:59:30.504362Z","caller":"traceutil/trace.go:171","msg":"trace[1236552923] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:562; }","duration":"787.1466ms","start":"2026-04-27T11:59:29.717199Z","end":"2026-04-27T11:59:30.504346Z","steps":["trace[1236552923] 'agreement among raft nodes before linearized reading' (duration: 787.026368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:30.504421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:29.717181Z","time spent":"787.230491ms","remote":"127.0.0.1:47838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.117\" "} {"level":"info","ts":"2026-04-27T11:59:30.531368Z","caller":"traceutil/trace.go:171","msg":"trace[738531402] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"588.232616ms","start":"2026-04-27T11:59:29.942923Z","end":"2026-04-27T11:59:30.531156Z","steps":["trace[738531402] 'process raft request' (duration: 588.014952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:30.531174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.186226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T11:59:30.531598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:29.942887Z","time spent":"588.644973ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:30.531617Z","caller":"traceutil/trace.go:171","msg":"trace[277463506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"805.654795ms","start":"2026-04-27T11:59:29.725937Z","end":"2026-04-27T11:59:30.531592Z","steps":["trace[277463506] 'agreement among raft nodes before linearized reading' (duration: 805.149346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:30.531714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:29.725928Z","time spent":"805.731216ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T11:59:32.901626Z","caller":"traceutil/trace.go:171","msg":"trace[1033798473] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"239.726096ms","start":"2026-04-27T11:59:32.661878Z","end":"2026-04-27T11:59:32.901605Z","steps":["trace[1033798473] 'read index received' (duration: 238.812811ms)","trace[1033798473] 'applied index is now lower than readState.Index' (duration: 912.675µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:32.901708Z","caller":"traceutil/trace.go:171","msg":"trace[980755875] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"357.136597ms","start":"2026-04-27T11:59:32.544548Z","end":"2026-04-27T11:59:32.901685Z","steps":["trace[980755875] 'process raft request' (duration: 356.317034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:32.901828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:32.544526Z","time spent":"357.23859ms","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:32.902044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.136393ms","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-04-27T11:59:32.902091Z","caller":"traceutil/trace.go:171","msg":"trace[35337332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"240.235745ms","start":"2026-04-27T11:59:32.661846Z","end":"2026-04-27T11:59:32.902082Z","steps":["trace[35337332] 'agreement among raft nodes before linearized reading' (duration: 239.862649ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:33.088662Z","caller":"traceutil/trace.go:171","msg":"trace[530113095] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"181.553885ms","start":"2026-04-27T11:59:32.90708Z","end":"2026-04-27T11:59:33.088634Z","steps":["trace[530113095] 'process raft request' (duration: 181.475704ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:33.088693Z","caller":"traceutil/trace.go:171","msg":"trace[739213316] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"392.506814ms","start":"2026-04-27T11:59:32.696135Z","end":"2026-04-27T11:59:33.088642Z","steps":["trace[739213316] 'process raft request' (duration: 352.248611ms)","trace[739213316] 'compare' (duration: 40.003589ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:33.08903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.313149ms","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-04-27T11:59:33.089089Z","caller":"traceutil/trace.go:171","msg":"trace[97760705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"426.40634ms","start":"2026-04-27T11:59:32.662672Z","end":"2026-04-27T11:59:33.089079Z","steps":["trace[97760705] 'agreement among raft nodes before linearized reading' (duration: 426.214607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:33.089136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:32.662664Z","time spent":"426.452741ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T11:59:33.089297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.82157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:33.089342Z","caller":"traceutil/trace.go:171","msg":"trace[1390386976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"362.878381ms","start":"2026-04-27T11:59:32.726448Z","end":"2026-04-27T11:59:33.089327Z","steps":["trace[1390386976] 'agreement among raft nodes before linearized reading' (duration: 362.81628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:33.089386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:32.726438Z","time spent":"362.937753ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T11:59:33.089467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:32.696125Z","time spent":"392.85768ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:33.089611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.421821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-27T11:59:33.089596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.321924ms","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-04-27T11:59:33.089652Z","caller":"traceutil/trace.go:171","msg":"trace[788645241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"411.476103ms","start":"2026-04-27T11:59:32.678166Z","end":"2026-04-27T11:59:33.089642Z","steps":["trace[788645241] 'agreement among raft nodes before linearized reading' (duration: 411.371151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:33.08968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:32.67816Z","time spent":"411.513464ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T11:59:33.089689Z","caller":"traceutil/trace.go:171","msg":"trace[572836432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"406.425455ms","start":"2026-04-27T11:59:32.68323Z","end":"2026-04-27T11:59:33.089655Z","steps":["trace[572836432] 'agreement among raft nodes before linearized reading' (duration: 406.14127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:33.089755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:32.683224Z","time spent":"406.522097ms","remote":"127.0.0.1:48070","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-04-27T11:59:33.088824Z","caller":"traceutil/trace.go:171","msg":"trace[1885187199] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"187.098891ms","start":"2026-04-27T11:59:32.90169Z","end":"2026-04-27T11:59:33.088788Z","steps":["trace[1885187199] 'read index received' (duration: 146.705966ms)","trace[1885187199] 'applied index is now lower than readState.Index' (duration: 40.391084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:33.089955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.664029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:33.090003Z","caller":"traceutil/trace.go:171","msg":"trace[50945095] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:579; }","duration":"132.71179ms","start":"2026-04-27T11:59:32.957277Z","end":"2026-04-27T11:59:33.089989Z","steps":["trace[50945095] 'agreement among raft nodes before linearized reading' (duration: 132.641069ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:33.850062Z","caller":"traceutil/trace.go:171","msg":"trace[1313753712] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"122.663053ms","start":"2026-04-27T11:59:33.727383Z","end":"2026-04-27T11:59:33.850046Z","steps":["trace[1313753712] 'read index received' (duration: 121.608994ms)","trace[1313753712] 'applied index is now lower than readState.Index' (duration: 1.053229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:33.85014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.739504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:33.850156Z","caller":"traceutil/trace.go:171","msg":"trace[930281684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"122.772634ms","start":"2026-04-27T11:59:33.727378Z","end":"2026-04-27T11:59:33.85015Z","steps":["trace[930281684] 'agreement among raft nodes before linearized reading' (duration: 122.727694ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:33.850258Z","caller":"traceutil/trace.go:171","msg":"trace[353782846] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"348.783343ms","start":"2026-04-27T11:59:33.50144Z","end":"2026-04-27T11:59:33.850223Z","steps":["trace[353782846] 'process raft request' (duration: 347.699083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:33.850545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:33.501419Z","time spent":"348.992506ms","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:34.638305Z","caller":"traceutil/trace.go:171","msg":"trace[1029690073] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"235.675915ms","start":"2026-04-27T11:59:34.402604Z","end":"2026-04-27T11:59:34.63828Z","steps":["trace[1029690073] 'process raft request' (duration: 235.153096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:35.214071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.222715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T11:59:35.214265Z","caller":"traceutil/trace.go:171","msg":"trace[1858117684] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"492.775142ms","start":"2026-04-27T11:59:34.721453Z","end":"2026-04-27T11:59:35.214228Z","steps":["trace[1858117684] 'process raft request' (duration: 161.349995ms)","trace[1858117684] 'compare' (duration: 331.117962ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:35.214405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:34.721423Z","time spent":"492.922114ms","remote":"127.0.0.1:48140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:35.214552Z","caller":"traceutil/trace.go:171","msg":"trace[1359160425] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"489.27283ms","start":"2026-04-27T11:59:34.725249Z","end":"2026-04-27T11:59:35.214522Z","steps":["trace[1359160425] 'read index received' (duration: 157.555198ms)","trace[1359160425] 'applied index is now lower than readState.Index' (duration: 331.713112ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:35.241044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.716749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T11:59:35.241044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.760481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T11:59:35.241109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.737443ms","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-04-27T11:59:35.241119Z","caller":"traceutil/trace.go:171","msg":"trace[1650251433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"146.831332ms","start":"2026-04-27T11:59:35.094272Z","end":"2026-04-27T11:59:35.241103Z","steps":["trace[1650251433] 'agreement among raft nodes before linearized reading' (duration: 146.70019ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:35.241149Z","caller":"traceutil/trace.go:171","msg":"trace[1083438759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"122.791214ms","start":"2026-04-27T11:59:35.118343Z","end":"2026-04-27T11:59:35.241134Z","steps":["trace[1083438759] 'agreement among raft nodes before linearized reading' (duration: 122.56213ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:35.241145Z","caller":"traceutil/trace.go:171","msg":"trace[788023491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"515.918904ms","start":"2026-04-27T11:59:34.7252Z","end":"2026-04-27T11:59:35.241119Z","steps":["trace[788023491] 'agreement among raft nodes before linearized reading' (duration: 489.453614ms)","trace[788023491] 'range keys from in-memory index tree' (duration: 26.313438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:35.241227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:34.725176Z","time spent":"516.033236ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T11:59:35.241373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.327267ms","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-04-27T11:59:35.241406Z","caller":"traceutil/trace.go:171","msg":"trace[688593251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"126.397459ms","start":"2026-04-27T11:59:35.115001Z","end":"2026-04-27T11:59:35.241399Z","steps":["trace[688593251] 'agreement among raft nodes before linearized reading' (duration: 125.983092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:35.241056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.710013ms","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-04-27T11:59:35.241478Z","caller":"traceutil/trace.go:171","msg":"trace[1844032696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"123.16151ms","start":"2026-04-27T11:59:35.118303Z","end":"2026-04-27T11:59:35.241464Z","steps":["trace[1844032696] 'agreement among raft nodes before linearized reading' (duration: 122.628761ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:35.372832Z","caller":"traceutil/trace.go:171","msg":"trace[1882909317] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"121.037273ms","start":"2026-04-27T11:59:35.251771Z","end":"2026-04-27T11:59:35.372809Z","steps":["trace[1882909317] 'process raft request' (duration: 120.86929ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:35.391619Z","caller":"traceutil/trace.go:171","msg":"trace[1170820567] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"139.673724ms","start":"2026-04-27T11:59:35.251925Z","end":"2026-04-27T11:59:35.391598Z","steps":["trace[1170820567] 'process raft request' (duration: 139.643974ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:35.391979Z","caller":"traceutil/trace.go:171","msg":"trace[82976526] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"140.132263ms","start":"2026-04-27T11:59:35.251838Z","end":"2026-04-27T11:59:35.391971Z","steps":["trace[82976526] 'process raft request' (duration: 139.611364ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:35.392073Z","caller":"traceutil/trace.go:171","msg":"trace[8966972] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"140.168504ms","start":"2026-04-27T11:59:35.251898Z","end":"2026-04-27T11:59:35.392067Z","steps":["trace[8966972] 'process raft request' (duration: 139.643164ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:36.169094Z","caller":"traceutil/trace.go:171","msg":"trace[186385507] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"418.91109ms","start":"2026-04-27T11:59:35.750164Z","end":"2026-04-27T11:59:36.169075Z","steps":["trace[186385507] 'process raft request' (duration: 418.776227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:36.169259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:35.750143Z","time spent":"419.057472ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:37.227046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855698647880422929,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T11:59:37.381691Z","caller":"traceutil/trace.go:171","msg":"trace[1213111221] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"858.308524ms","start":"2026-04-27T11:59:36.523357Z","end":"2026-04-27T11:59:37.381665Z","steps":["trace[1213111221] 'process raft request' (duration: 858.0566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:37.381856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:36.523329Z","time spent":"858.442327ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:38.736358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010092012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T11:59:38.73645Z","caller":"traceutil/trace.go:171","msg":"trace[1333528838] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.010228525s","start":"2026-04-27T11:59:36.726202Z","end":"2026-04-27T11:59:38.73643Z","steps":["trace[1333528838] 'agreement among raft nodes before linearized reading' (duration: 2.010116183s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:38.736501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:36.726187Z","time spent":"2.010300606s","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T11:59:40.427851Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.045256114s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T11:59:40.429471Z","caller":"traceutil/trace.go:171","msg":"trace[1434800007] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"3.703202774s","start":"2026-04-27T11:59:36.72624Z","end":"2026-04-27T11:59:40.429442Z","steps":["trace[1434800007] 'read index received' (duration: 655.21428ms)","trace[1434800007] 'applied index is now lower than readState.Index' (duration: 3.047987614s)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:40.429595Z","caller":"traceutil/trace.go:171","msg":"trace[966137084] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"3.826481916s","start":"2026-04-27T11:59:36.60307Z","end":"2026-04-27T11:59:40.429552Z","steps":["trace[966137084] 'process raft request' (duration: 3.825877935s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:40.429809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:36.603036Z","time spent":"3.82665128s","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:40.578332Z","caller":"traceutil/trace.go:171","msg":"trace[435644478] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.500584541s","start":"2026-04-27T11:59:39.077708Z","end":"2026-04-27T11:59:40.578293Z","steps":["trace[435644478] 'process raft request' (duration: 1.49571488s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:40.578551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:39.077684Z","time spent":"1.500764955s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:59:40.578623Z","caller":"traceutil/trace.go:171","msg":"trace[991688390] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"148.999526ms","start":"2026-04-27T11:59:40.429543Z","end":"2026-04-27T11:59:40.578542Z","steps":["trace[991688390] 'read index received' (duration: 143.910831ms)","trace[991688390] 'applied index is now lower than readState.Index' (duration: 4.931872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:40.579014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.202215514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T11:59:40.579095Z","caller":"traceutil/trace.go:171","msg":"trace[1183881957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"3.202335787s","start":"2026-04-27T11:59:37.376741Z","end":"2026-04-27T11:59:40.579077Z","steps":["trace[1183881957] 'agreement among raft nodes before linearized reading' (duration: 3.2019653s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:40.579152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:37.37672Z","time spent":"3.202418638s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T11:59:40.579262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.126558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-27T11:59:40.579272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.18320349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T11:59:40.579406Z","caller":"traceutil/trace.go:171","msg":"trace[440567024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"3.183401344s","start":"2026-04-27T11:59:37.395961Z","end":"2026-04-27T11:59:40.579362Z","steps":["trace[440567024] 'agreement among raft nodes before linearized reading' (duration: 3.182788913s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:40.579464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:37.395941Z","time spent":"3.183510146s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T11:59:40.579696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.18374601s","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-04-27T11:59:40.579782Z","caller":"traceutil/trace.go:171","msg":"trace[203549632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"3.183852882s","start":"2026-04-27T11:59:37.395907Z","end":"2026-04-27T11:59:40.57976Z","steps":["trace[203549632] 'agreement among raft nodes before linearized reading' (duration: 3.183673249s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:40.579844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:37.395894Z","time spent":"3.183924803s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T11:59:40.580328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.842844591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:40.580396Z","caller":"traceutil/trace.go:171","msg":"trace[1600622066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.842914603s","start":"2026-04-27T11:59:38.737451Z","end":"2026-04-27T11:59:40.580366Z","steps":["trace[1600622066] 'agreement among raft nodes before linearized reading' (duration: 1.842818542s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:40.580437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:38.737441Z","time spent":"1.842987904s","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T11:59:40.580607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.184656397s","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-04-27T11:59:40.580653Z","caller":"traceutil/trace.go:171","msg":"trace[1786218690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"3.184767178s","start":"2026-04-27T11:59:37.395872Z","end":"2026-04-27T11:59:40.580639Z","steps":["trace[1786218690] 'agreement among raft nodes before linearized reading' (duration: 3.184600655s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:40.580698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:37.395853Z","time spent":"3.18483799s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T11:59:40.57934Z","caller":"traceutil/trace.go:171","msg":"trace[11911121] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:599; }","duration":"861.218891ms","start":"2026-04-27T11:59:39.718089Z","end":"2026-04-27T11:59:40.579308Z","steps":["trace[11911121] 'agreement among raft nodes before linearized reading' (duration: 861.009727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:40.581451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:39.718075Z","time spent":"863.362241ms","remote":"127.0.0.1:47838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.117\" "} {"level":"info","ts":"2026-04-27T11:59:42.1732Z","caller":"traceutil/trace.go:171","msg":"trace[1086614489] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"445.59257ms","start":"2026-04-27T11:59:41.727581Z","end":"2026-04-27T11:59:42.173174Z","steps":["trace[1086614489] 'read index received' (duration: 442.650384ms)","trace[1086614489] 'applied index is now lower than readState.Index' (duration: 2.941116ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:42.173354Z","caller":"traceutil/trace.go:171","msg":"trace[771445204] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"475.478839ms","start":"2026-04-27T11:59:41.697842Z","end":"2026-04-27T11:59:42.17332Z","steps":["trace[771445204] 'process raft request' (duration: 472.448632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:42.173503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:41.697823Z","time spent":"475.610281ms","remote":"127.0.0.1:48300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T11:59:42.173338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.753303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:42.173643Z","caller":"traceutil/trace.go:171","msg":"trace[893311427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"446.108609ms","start":"2026-04-27T11:59:41.727519Z","end":"2026-04-27T11:59:42.173628Z","steps":["trace[893311427] 'agreement among raft nodes before linearized reading' (duration: 445.755402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:42.17369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:41.727498Z","time spent":"446.178261ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T11:59:42.732472Z","caller":"traceutil/trace.go:171","msg":"trace[1182742631] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"110.245419ms","start":"2026-04-27T11:59:42.622199Z","end":"2026-04-27T11:59:42.732444Z","steps":["trace[1182742631] 'process raft request' (duration: 102.110065ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:43.647183Z","caller":"traceutil/trace.go:171","msg":"trace[1988905421] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"103.20005ms","start":"2026-04-27T11:59:43.543961Z","end":"2026-04-27T11:59:43.647161Z","steps":["trace[1988905421] 'process raft request' (duration: 102.593179ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:43.879265Z","caller":"traceutil/trace.go:171","msg":"trace[1088077158] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"152.252872ms","start":"2026-04-27T11:59:43.726987Z","end":"2026-04-27T11:59:43.87924Z","steps":["trace[1088077158] 'read index received' (duration: 74.912272ms)","trace[1088077158] 'applied index is now lower than readState.Index' (duration: 77.33951ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:43.879357Z","caller":"traceutil/trace.go:171","msg":"trace[1736014083] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"174.981464ms","start":"2026-04-27T11:59:43.704346Z","end":"2026-04-27T11:59:43.879328Z","steps":["trace[1736014083] 'process raft request' (duration: 97.754057ms)","trace[1736014083] 'compare' (duration: 77.000933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:43.879444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.451985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:43.879489Z","caller":"traceutil/trace.go:171","msg":"trace[228766007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"152.552687ms","start":"2026-04-27T11:59:43.726924Z","end":"2026-04-27T11:59:43.879477Z","steps":["trace[228766007] 'agreement among raft nodes before linearized reading' (duration: 152.449495ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:44.385876Z","caller":"traceutil/trace.go:171","msg":"trace[1182303208] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"100.736987ms","start":"2026-04-27T11:59:44.285107Z","end":"2026-04-27T11:59:44.385844Z","steps":["trace[1182303208] 'process raft request' (duration: 68.110082ms)","trace[1182303208] 'compare' (duration: 32.456572ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:44.776589Z","caller":"traceutil/trace.go:171","msg":"trace[1201450227] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"122.177587ms","start":"2026-04-27T11:59:44.654391Z","end":"2026-04-27T11:59:44.776568Z","steps":["trace[1201450227] 'process raft request' (duration: 122.073365ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:44.776594Z","caller":"traceutil/trace.go:171","msg":"trace[894744261] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"122.78742ms","start":"2026-04-27T11:59:44.653773Z","end":"2026-04-27T11:59:44.776561Z","steps":["trace[894744261] 'process raft request' (duration: 88.386532ms)","trace[894744261] 'compare' (duration: 34.132553ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:44.776759Z","caller":"traceutil/trace.go:171","msg":"trace[1042714876] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"121.80244ms","start":"2026-04-27T11:59:44.654916Z","end":"2026-04-27T11:59:44.776719Z","steps":["trace[1042714876] 'process raft request' (duration: 121.607566ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:45.288562Z","caller":"traceutil/trace.go:171","msg":"trace[708157454] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"126.136561ms","start":"2026-04-27T11:59:45.162396Z","end":"2026-04-27T11:59:45.288533Z","steps":["trace[708157454] 'process raft request' (duration: 125.986298ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:45.801873Z","caller":"traceutil/trace.go:171","msg":"trace[1419710060] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"380.699806ms","start":"2026-04-27T11:59:45.421151Z","end":"2026-04-27T11:59:45.80185Z","steps":["trace[1419710060] 'read index received' (duration: 380.623734ms)","trace[1419710060] 'applied index is now lower than readState.Index' (duration: 75.082µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:45.801921Z","caller":"traceutil/trace.go:171","msg":"trace[1054212845] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"575.899557ms","start":"2026-04-27T11:59:45.225995Z","end":"2026-04-27T11:59:45.801894Z","steps":["trace[1054212845] 'process raft request' (duration: 575.721713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:45.802167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.62362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-shxhq\" ","response":"range_response_count:1 size:18067"} {"level":"warn","ts":"2026-04-27T11:59:45.802194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.840694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-04-27T11:59:45.802191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.019381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-27T11:59:45.802234Z","caller":"traceutil/trace.go:171","msg":"trace[403161092] range","detail":"{range_begin:/registry/pods/kube-system/cilium-shxhq; range_end:; response_count:1; response_revision:678; }","duration":"249.727842ms","start":"2026-04-27T11:59:45.552487Z","end":"2026-04-27T11:59:45.802215Z","steps":["trace[403161092] 'agreement among raft nodes before linearized reading' (duration: 249.515618ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:45.802233Z","caller":"traceutil/trace.go:171","msg":"trace[776072036] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:678; }","duration":"181.930196ms","start":"2026-04-27T11:59:45.620291Z","end":"2026-04-27T11:59:45.802221Z","steps":["trace[776072036] 'agreement among raft nodes before linearized reading' (duration: 181.777663ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:45.802286Z","caller":"traceutil/trace.go:171","msg":"trace[1730890899] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:678; }","duration":"381.125773ms","start":"2026-04-27T11:59:45.421133Z","end":"2026-04-27T11:59:45.802259Z","steps":["trace[1730890899] 'agreement among raft nodes before linearized reading' (duration: 380.807477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:45.802347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:45.420969Z","time spent":"381.367298ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-04-27T11:59:45.802498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:45.225967Z","time spent":"576.010668ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T11:59:45.94313Z","caller":"traceutil/trace.go:171","msg":"trace[530390357] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"129.072777ms","start":"2026-04-27T11:59:45.81403Z","end":"2026-04-27T11:59:45.943103Z","steps":["trace[530390357] 'process raft request' (duration: 128.658608ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:45.972545Z","caller":"traceutil/trace.go:171","msg":"trace[258602807] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"156.908083ms","start":"2026-04-27T11:59:45.815611Z","end":"2026-04-27T11:59:45.972519Z","steps":["trace[258602807] 'process raft request' (duration: 156.554627ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:45.972947Z","caller":"traceutil/trace.go:171","msg":"trace[32865307] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"122.53266ms","start":"2026-04-27T11:59:45.850385Z","end":"2026-04-27T11:59:45.972918Z","steps":["trace[32865307] 'process raft request' (duration: 122.157853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:46.847478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.169131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T11:59:46.847541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.92196ms","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-04-27T11:59:46.847602Z","caller":"traceutil/trace.go:171","msg":"trace[721778727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"120.328215ms","start":"2026-04-27T11:59:46.727259Z","end":"2026-04-27T11:59:46.847587Z","steps":["trace[721778727] 'range keys from in-memory index tree' (duration: 120.11193ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:46.847631Z","caller":"traceutil/trace.go:171","msg":"trace[752001610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"166.058263ms","start":"2026-04-27T11:59:46.681552Z","end":"2026-04-27T11:59:46.84761Z","steps":["trace[752001610] 'range keys from in-memory index tree' (duration: 165.636954ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:57.170779Z","caller":"traceutil/trace.go:171","msg":"trace[1657706344] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"265.230356ms","start":"2026-04-27T11:59:56.905524Z","end":"2026-04-27T11:59:57.170754Z","steps":["trace[1657706344] 'read index received' (duration: 265.136314ms)","trace[1657706344] 'applied index is now lower than readState.Index' (duration: 93.062µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:57.170891Z","caller":"traceutil/trace.go:171","msg":"trace[1417268753] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"265.520192ms","start":"2026-04-27T11:59:56.905362Z","end":"2026-04-27T11:59:57.170882Z","steps":["trace[1417268753] 'process raft request' (duration: 265.259926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:57.17104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.493081ms","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-04-27T11:59:57.171119Z","caller":"traceutil/trace.go:171","msg":"trace[1319045942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"265.589153ms","start":"2026-04-27T11:59:56.905511Z","end":"2026-04-27T11:59:57.1711Z","steps":["trace[1319045942] 'agreement among raft nodes before linearized reading' (duration: 265.382969ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:57.191907Z","caller":"traceutil/trace.go:171","msg":"trace[1323544399] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"284.316232ms","start":"2026-04-27T11:59:56.907548Z","end":"2026-04-27T11:59:57.191864Z","steps":["trace[1323544399] 'process raft request' (duration: 284.097767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:57.192011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.810966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T11:59:57.192034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.425296ms","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-04-27T11:59:57.192096Z","caller":"traceutil/trace.go:171","msg":"trace[1201106204] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:764; }","duration":"213.951089ms","start":"2026-04-27T11:59:56.978126Z","end":"2026-04-27T11:59:57.192077Z","steps":["trace[1201106204] 'agreement among raft nodes before linearized reading' (duration: 213.782136ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:57.192115Z","caller":"traceutil/trace.go:171","msg":"trace[2081983639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"286.538148ms","start":"2026-04-27T11:59:56.905555Z","end":"2026-04-27T11:59:57.192093Z","steps":["trace[2081983639] 'agreement among raft nodes before linearized reading' (duration: 286.235332ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:57.348744Z","caller":"traceutil/trace.go:171","msg":"trace[858300669] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"151.587167ms","start":"2026-04-27T11:59:57.197133Z","end":"2026-04-27T11:59:57.348721Z","steps":["trace[858300669] 'process raft request' (duration: 65.256715ms)","trace[858300669] 'compare' (duration: 86.20253ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:58.701095Z","caller":"traceutil/trace.go:171","msg":"trace[411717903] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"190.452896ms","start":"2026-04-27T11:59:58.510618Z","end":"2026-04-27T11:59:58.701071Z","steps":["trace[411717903] 'read index received' (duration: 190.209571ms)","trace[411717903] 'applied index is now lower than readState.Index' (duration: 242.105µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:58.701187Z","caller":"traceutil/trace.go:171","msg":"trace[1740374833] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"281.14287ms","start":"2026-04-27T11:59:58.420005Z","end":"2026-04-27T11:59:58.701148Z","steps":["trace[1740374833] 'process raft request' (duration: 280.910785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:58.701589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.949486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T11:59:58.701705Z","caller":"traceutil/trace.go:171","msg":"trace[548917367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"191.040688ms","start":"2026-04-27T11:59:58.510604Z","end":"2026-04-27T11:59:58.701645Z","steps":["trace[548917367] 'agreement among raft nodes before linearized reading' (duration: 190.586238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:59.697242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855698647880423342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:00:00.198322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855698647880423342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:00:00.699008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855698647880423342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:00:00.93672Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.756627074s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T12:00:00.93741Z","caller":"traceutil/trace.go:171","msg":"trace[1505131240] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.757405069s","start":"2026-04-27T11:59:59.179971Z","end":"2026-04-27T12:00:00.937377Z","steps":["trace[1505131240] 'process raft request' (duration: 1.757196545s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:00.937581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:59.17995Z","time spent":"1.757549472s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:00:00.938428Z","caller":"traceutil/trace.go:171","msg":"trace[818191088] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:803; }","duration":"1.741906844s","start":"2026-04-27T11:59:59.196503Z","end":"2026-04-27T12:00:00.93841Z","steps":["trace[818191088] 'read index received' (duration: 1.740643038s)","trace[818191088] 'applied index is now lower than readState.Index' (duration: 1.261596ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:00.938517Z","caller":"traceutil/trace.go:171","msg":"trace[1628793304] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"1.180920533s","start":"2026-04-27T11:59:59.757574Z","end":"2026-04-27T12:00:00.938495Z","steps":["trace[1628793304] 'process raft request' (duration: 1.180743528s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:00.93863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742117149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T12:00:00.938639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.611314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-27T12:00:00.938647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.733577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T12:00:00.93865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:59.757548Z","time spent":"1.181035515s","remote":"127.0.0.1:48034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:00:00.938667Z","caller":"traceutil/trace.go:171","msg":"trace[595162473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"202.672476ms","start":"2026-04-27T12:00:00.735987Z","end":"2026-04-27T12:00:00.938659Z","steps":["trace[595162473] 'agreement among raft nodes before linearized reading' (duration: 202.579274ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:00.938673Z","caller":"traceutil/trace.go:171","msg":"trace[168521901] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:778; }","duration":"985.805758ms","start":"2026-04-27T11:59:59.95286Z","end":"2026-04-27T12:00:00.938666Z","steps":["trace[168521901] 'agreement among raft nodes before linearized reading' (duration: 985.751127ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:00.938662Z","caller":"traceutil/trace.go:171","msg":"trace[1565794110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"1.742202731s","start":"2026-04-27T11:59:59.196451Z","end":"2026-04-27T12:00:00.938653Z","steps":["trace[1565794110] 'agreement among raft nodes before linearized reading' (duration: 1.742022277s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:00.938692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:59.952837Z","time spent":"985.85091ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:00:00.938703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:59.196428Z","time spent":"1.742267532s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T12:00:00.938711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702388316s","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-04-27T12:00:00.938762Z","caller":"traceutil/trace.go:171","msg":"trace[1896580788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"1.702482708s","start":"2026-04-27T11:59:59.236267Z","end":"2026-04-27T12:00:00.938749Z","steps":["trace[1896580788] 'agreement among raft nodes before linearized reading' (duration: 1.702309954s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:00.938803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:59.236245Z","time spent":"1.702543649s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T12:00:00.938828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.556513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:00:00.938862Z","caller":"traceutil/trace.go:171","msg":"trace[395441636] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:778; }","duration":"632.669325ms","start":"2026-04-27T12:00:00.306187Z","end":"2026-04-27T12:00:00.938856Z","steps":["trace[395441636] 'agreement among raft nodes before linearized reading' (duration: 632.557783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:00.938878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:00.306165Z","time spent":"632.707156ms","remote":"127.0.0.1:48228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:00:00.938906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212549306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:00:00.938952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.423463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:00:00.93896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220007073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T12:00:00.938976Z","caller":"traceutil/trace.go:171","msg":"trace[173947329] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:778; }","duration":"397.484234ms","start":"2026-04-27T12:00:00.541485Z","end":"2026-04-27T12:00:00.938969Z","steps":["trace[173947329] 'agreement among raft nodes before linearized reading' (duration: 397.442964ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:00.938986Z","caller":"traceutil/trace.go:171","msg":"trace[1778129322] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:778; }","duration":"1.220053904s","start":"2026-04-27T11:59:59.718925Z","end":"2026-04-27T12:00:00.938979Z","steps":["trace[1778129322] 'agreement among raft nodes before linearized reading' (duration: 1.219969082s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:00.938997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:00.541466Z","time spent":"397.526125ms","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:00:00.939001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:59.718911Z","time spent":"1.220086655s","remote":"127.0.0.1:47838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.117\" "} {"level":"info","ts":"2026-04-27T12:00:00.938947Z","caller":"traceutil/trace.go:171","msg":"trace[693023976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"1.212600708s","start":"2026-04-27T11:59:59.726327Z","end":"2026-04-27T12:00:00.938928Z","steps":["trace[693023976] 'agreement among raft nodes before linearized reading' (duration: 1.212553087s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:00.939072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585723737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T12:00:00.939177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:59.72631Z","time spent":"1.21273327s","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T12:00:00.939135Z","caller":"traceutil/trace.go:171","msg":"trace[86905624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"1.58584464s","start":"2026-04-27T11:59:59.353274Z","end":"2026-04-27T12:00:00.939119Z","steps":["trace[86905624] 'agreement among raft nodes before linearized reading' (duration: 1.585706037s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:00.939304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:59.353258Z","time spent":"1.586029663s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T12:00:04.840839Z","caller":"traceutil/trace.go:171","msg":"trace[2073272536] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"109.923806ms","start":"2026-04-27T12:00:04.73089Z","end":"2026-04-27T12:00:04.840813Z","steps":["trace[2073272536] 'process raft request' (duration: 109.753531ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:12.874936Z","caller":"traceutil/trace.go:171","msg":"trace[845070763] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"147.258129ms","start":"2026-04-27T12:00:12.727651Z","end":"2026-04-27T12:00:12.874909Z","steps":["trace[845070763] 'read index received' (duration: 147.007383ms)","trace[845070763] 'applied index is now lower than readState.Index' (duration: 249.606µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:12.874974Z","caller":"traceutil/trace.go:171","msg":"trace[2017386327] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"357.318668ms","start":"2026-04-27T12:00:12.517622Z","end":"2026-04-27T12:00:12.874941Z","steps":["trace[2017386327] 'process raft request' (duration: 357.108853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:12.875101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:12.517597Z","time spent":"357.442961ms","remote":"127.0.0.1:47884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T12:00:12.875141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.464174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:00:12.875178Z","caller":"traceutil/trace.go:171","msg":"trace[484030654] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:902; }","duration":"147.548916ms","start":"2026-04-27T12:00:12.727616Z","end":"2026-04-27T12:00:12.875165Z","steps":["trace[484030654] 'agreement among raft nodes before linearized reading' (duration: 147.433483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:12.875373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.108107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:00:12.87543Z","caller":"traceutil/trace.go:171","msg":"trace[854460166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"147.215219ms","start":"2026-04-27T12:00:12.728197Z","end":"2026-04-27T12:00:12.875413Z","steps":["trace[854460166] 'agreement among raft nodes before linearized reading' (duration: 147.078446ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:15.447801Z","caller":"traceutil/trace.go:171","msg":"trace[335468609] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"112.847703ms","start":"2026-04-27T12:00:15.334933Z","end":"2026-04-27T12:00:15.447781Z","steps":["trace[335468609] 'process raft request' (duration: 112.696969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:16.016346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.060415ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:00:16.016476Z","caller":"traceutil/trace.go:171","msg":"trace[416849281] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:944; }","duration":"380.217559ms","start":"2026-04-27T12:00:15.636239Z","end":"2026-04-27T12:00:16.016457Z","steps":["trace[416849281] 'range keys from in-memory index tree' (duration: 380.028964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:16.01727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.27253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:00:16.017353Z","caller":"traceutil/trace.go:171","msg":"trace[1644330039] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"562.708222ms","start":"2026-04-27T12:00:15.454626Z","end":"2026-04-27T12:00:16.017335Z","steps":["trace[1644330039] 'process raft request' (duration: 174.3145ms)","trace[1644330039] 'compare' (duration: 387.379269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:00:16.01795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:15.454599Z","time spent":"563.208822ms","remote":"127.0.0.1:47960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1104,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:00:16.211739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855698647880423644,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T12:00:16.226988Z","caller":"traceutil/trace.go:171","msg":"trace[786966412] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"771.622836ms","start":"2026-04-27T12:00:15.455345Z","end":"2026-04-27T12:00:16.226967Z","steps":["trace[786966412] 'process raft request' (duration: 767.735469ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:16.227193Z","caller":"traceutil/trace.go:171","msg":"trace[1393619184] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"770.190494ms","start":"2026-04-27T12:00:15.456984Z","end":"2026-04-27T12:00:16.227174Z","steps":["trace[1393619184] 'process raft request' (duration: 769.917678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:16.227191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:15.455329Z","time spent":"771.765479ms","remote":"127.0.0.1:48072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:00:16.227328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:15.456964Z","time spent":"770.300537ms","remote":"127.0.0.1:48272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:00:16.266185Z","caller":"traceutil/trace.go:171","msg":"trace[573714867] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:975; }","duration":"582.835405ms","start":"2026-04-27T12:00:15.683328Z","end":"2026-04-27T12:00:16.266164Z","steps":["trace[573714867] 'read index received' (duration: 539.762351ms)","trace[573714867] 'applied index is now lower than readState.Index' (duration: 43.072234ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:16.266434Z","caller":"traceutil/trace.go:171","msg":"trace[839598389] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"202.831298ms","start":"2026-04-27T12:00:16.063593Z","end":"2026-04-27T12:00:16.266425Z","steps":["trace[839598389] 'process raft request' (duration: 202.446349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:16.266695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.344947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-j8bjk\" ","response":"range_response_count:1 size:5072"} {"level":"info","ts":"2026-04-27T12:00:16.266785Z","caller":"traceutil/trace.go:171","msg":"trace[1497245284] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-j8bjk; range_end:; response_count:1; response_revision:948; }","duration":"583.479299ms","start":"2026-04-27T12:00:15.683286Z","end":"2026-04-27T12:00:16.266765Z","steps":["trace[1497245284] 'agreement among raft nodes before linearized reading' (duration: 583.244034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:16.266832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:15.683266Z","time spent":"583.555821ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5094,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-j8bjk\" "} {"level":"info","ts":"2026-04-27T12:00:16.361757Z","caller":"traceutil/trace.go:171","msg":"trace[587126810] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"123.647486ms","start":"2026-04-27T12:00:16.238079Z","end":"2026-04-27T12:00:16.361727Z","steps":["trace[587126810] 'process raft request' (duration: 123.207586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:16.362116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.243154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:00:16.362201Z","caller":"traceutil/trace.go:171","msg":"trace[1136542905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"635.382427ms","start":"2026-04-27T12:00:15.726799Z","end":"2026-04-27T12:00:16.362182Z","steps":["trace[1136542905] 'agreement among raft nodes before linearized reading' (duration: 635.150612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:16.362279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:15.726781Z","time spent":"635.48579ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T12:00:17.230284Z","caller":"traceutil/trace.go:171","msg":"trace[548046471] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"110.683442ms","start":"2026-04-27T12:00:17.119577Z","end":"2026-04-27T12:00:17.230261Z","steps":["trace[548046471] 'process raft request' (duration: 75.890643ms)","trace[548046471] 'compare' (duration: 34.695827ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:17.235415Z","caller":"traceutil/trace.go:171","msg":"trace[1839064795] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"115.620855ms","start":"2026-04-27T12:00:17.119775Z","end":"2026-04-27T12:00:17.235395Z","steps":["trace[1839064795] 'process raft request' (duration: 115.320478ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:17.235424Z","caller":"traceutil/trace.go:171","msg":"trace[1822909793] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"115.222326ms","start":"2026-04-27T12:00:17.120175Z","end":"2026-04-27T12:00:17.235397Z","steps":["trace[1822909793] 'process raft request' (duration: 115.126813ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:17.235604Z","caller":"traceutil/trace.go:171","msg":"trace[910813976] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"114.370927ms","start":"2026-04-27T12:00:17.121223Z","end":"2026-04-27T12:00:17.235593Z","steps":["trace[910813976] 'process raft request' (duration: 114.134822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:25.478571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.022766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:00:25.478765Z","caller":"traceutil/trace.go:171","msg":"trace[26697234] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"178.552734ms","start":"2026-04-27T12:00:25.300188Z","end":"2026-04-27T12:00:25.47874Z","steps":["trace[26697234] 'process raft request' (duration: 178.518194ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:25.478897Z","caller":"traceutil/trace.go:171","msg":"trace[1966802539] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"209.623048ms","start":"2026-04-27T12:00:25.26924Z","end":"2026-04-27T12:00:25.478863Z","steps":["trace[1966802539] 'process raft request' (duration: 209.403003ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:25.478878Z","caller":"traceutil/trace.go:171","msg":"trace[347013896] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"210.079439ms","start":"2026-04-27T12:00:25.268687Z","end":"2026-04-27T12:00:25.478767Z","steps":["trace[347013896] 'process raft request' (duration: 87.793277ms)","trace[347013896] 'compare' (duration: 121.916132ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:29.834631Z","caller":"traceutil/trace.go:171","msg":"trace[902322129] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"109.531826ms","start":"2026-04-27T12:00:29.725082Z","end":"2026-04-27T12:00:29.834614Z","steps":["trace[902322129] 'read index received' (duration: 109.424012ms)","trace[902322129] 'applied index is now lower than readState.Index' (duration: 107.024µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:00:29.834761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.659449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:00:29.834791Z","caller":"traceutil/trace.go:171","msg":"trace[1352716497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"109.71219ms","start":"2026-04-27T12:00:29.725067Z","end":"2026-04-27T12:00:29.834779Z","steps":["trace[1352716497] 'agreement among raft nodes before linearized reading' (duration: 109.629658ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:31.776742Z","caller":"traceutil/trace.go:171","msg":"trace[561386635] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1041; }","duration":"217.456102ms","start":"2026-04-27T12:00:31.559269Z","end":"2026-04-27T12:00:31.776726Z","steps":["trace[561386635] 'read index received' (duration: 212.255928ms)","trace[561386635] 'applied index is now lower than readState.Index' (duration: 5.199604ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:31.77682Z","caller":"traceutil/trace.go:171","msg":"trace[801821234] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"245.535359ms","start":"2026-04-27T12:00:31.531246Z","end":"2026-04-27T12:00:31.776782Z","steps":["trace[801821234] 'process raft request' (duration: 240.290004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:31.776856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.572905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:00:31.776914Z","caller":"traceutil/trace.go:171","msg":"trace[396232306] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1009; }","duration":"217.667397ms","start":"2026-04-27T12:00:31.559238Z","end":"2026-04-27T12:00:31.776905Z","steps":["trace[396232306] 'agreement among raft nodes before linearized reading' (duration: 217.574545ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:31.777059Z","caller":"traceutil/trace.go:171","msg":"trace[861245397] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"244.740939ms","start":"2026-04-27T12:00:31.532296Z","end":"2026-04-27T12:00:31.777037Z","steps":["trace[861245397] 'process raft request' (duration: 244.36939ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:32.231435Z","caller":"traceutil/trace.go:171","msg":"trace[1051851632] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"154.990239ms","start":"2026-04-27T12:00:32.076415Z","end":"2026-04-27T12:00:32.231405Z","steps":["trace[1051851632] 'process raft request' (duration: 154.790324ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:32.43571Z","caller":"traceutil/trace.go:171","msg":"trace[1873946684] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"107.635121ms","start":"2026-04-27T12:00:32.327873Z","end":"2026-04-27T12:00:32.435508Z","steps":["trace[1873946684] 'process raft request' (duration: 21.44462ms)","trace[1873946684] 'compare' (duration: 85.425923ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:35.915915Z","caller":"traceutil/trace.go:171","msg":"trace[2071634449] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"115.134953ms","start":"2026-04-27T12:00:35.800743Z","end":"2026-04-27T12:00:35.915878Z","steps":["trace[2071634449] 'process raft request' (duration: 114.954579ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:47.589615Z","caller":"traceutil/trace.go:171","msg":"trace[259870139] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"103.824812ms","start":"2026-04-27T12:00:47.485767Z","end":"2026-04-27T12:00:47.589591Z","steps":["trace[259870139] 'process raft request' (duration: 103.673129ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:56.208061Z","caller":"traceutil/trace.go:171","msg":"trace[114441829] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"136.739193ms","start":"2026-04-27T12:00:56.071283Z","end":"2026-04-27T12:00:56.208022Z","steps":["trace[114441829] 'process raft request' (duration: 136.444656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:01.843514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.032952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:01.843592Z","caller":"traceutil/trace.go:171","msg":"trace[507164160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"115.147225ms","start":"2026-04-27T12:01:01.728423Z","end":"2026-04-27T12:01:01.84357Z","steps":["trace[507164160] 'range keys from in-memory index tree' (duration: 114.93285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:01.843601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.650569ms","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-04-27T12:01:01.843669Z","caller":"traceutil/trace.go:171","msg":"trace[817422745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1108; }","duration":"132.756792ms","start":"2026-04-27T12:01:01.710898Z","end":"2026-04-27T12:01:01.843655Z","steps":["trace[817422745] 'range keys from in-memory index tree' (duration: 132.550947ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:02.126179Z","caller":"traceutil/trace.go:171","msg":"trace[857532836] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"124.246701ms","start":"2026-04-27T12:01:02.001903Z","end":"2026-04-27T12:01:02.126149Z","steps":["trace[857532836] 'process raft request' (duration: 124.078726ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:11.020264Z","caller":"traceutil/trace.go:171","msg":"trace[130945215] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"244.738332ms","start":"2026-04-27T12:01:10.775498Z","end":"2026-04-27T12:01:11.020236Z","steps":["trace[130945215] 'process raft request' (duration: 244.553947ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:25.292665Z","caller":"traceutil/trace.go:171","msg":"trace[1370244850] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"222.23302ms","start":"2026-04-27T12:01:25.070406Z","end":"2026-04-27T12:01:25.292639Z","steps":["trace[1370244850] 'process raft request' (duration: 222.086996ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:40.541571Z","caller":"traceutil/trace.go:171","msg":"trace[1542562556] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"103.205247ms","start":"2026-04-27T12:01:40.438331Z","end":"2026-04-27T12:01:40.541536Z","steps":["trace[1542562556] 'process raft request' (duration: 103.015971ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:42.464814Z","caller":"traceutil/trace.go:171","msg":"trace[1330491273] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"169.678337ms","start":"2026-04-27T12:01:42.295113Z","end":"2026-04-27T12:01:42.464791Z","steps":["trace[1330491273] 'read index received' (duration: 169.515873ms)","trace[1330491273] 'applied index is now lower than readState.Index' (duration: 161.144µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:42.46494Z","caller":"traceutil/trace.go:171","msg":"trace[1000971604] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"187.015498ms","start":"2026-04-27T12:01:42.277886Z","end":"2026-04-27T12:01:42.464901Z","steps":["trace[1000971604] 'process raft request' (duration: 186.766242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:42.465077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.941274ms","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-04-27T12:01:42.465138Z","caller":"traceutil/trace.go:171","msg":"trace[595950313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"170.051798ms","start":"2026-04-27T12:01:42.295073Z","end":"2026-04-27T12:01:42.465125Z","steps":["trace[595950313] 'agreement among raft nodes before linearized reading' (duration: 169.853313ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:42.575183Z","caller":"traceutil/trace.go:171","msg":"trace[64568710] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"104.055094ms","start":"2026-04-27T12:01:42.471094Z","end":"2026-04-27T12:01:42.575149Z","steps":["trace[64568710] 'process raft request' (duration: 103.777596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.084802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.751893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:01:43.085073Z","caller":"traceutil/trace.go:171","msg":"trace[1997875919] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"505.759936ms","start":"2026-04-27T12:01:42.579267Z","end":"2026-04-27T12:01:43.085027Z","steps":["trace[1997875919] 'process raft request' (duration: 356.240933ms)","trace[1997875919] 'compare' (duration: 148.327032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:01:43.085241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:42.579196Z","time spent":"505.970983ms","remote":"127.0.0.1:47948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:01:43.085264Z","caller":"traceutil/trace.go:171","msg":"trace[1515657422] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"505.45627ms","start":"2026-04-27T12:01:42.579785Z","end":"2026-04-27T12:01:43.085241Z","steps":["trace[1515657422] 'process raft request' (duration: 505.301135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.085368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:42.579764Z","time spent":"505.550102ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:01:43.085683Z","caller":"traceutil/trace.go:171","msg":"trace[354588155] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1294; }","duration":"505.729157ms","start":"2026-04-27T12:01:42.579939Z","end":"2026-04-27T12:01:43.085668Z","steps":["trace[354588155] 'read index received' (duration: 355.566936ms)","trace[354588155] 'applied index is now lower than readState.Index' (duration: 150.161191ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:01:43.085807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.147002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:43.085829Z","caller":"traceutil/trace.go:171","msg":"trace[2082107157] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1247; }","duration":"342.196342ms","start":"2026-04-27T12:01:42.743626Z","end":"2026-04-27T12:01:43.085822Z","steps":["trace[2082107157] 'agreement among raft nodes before linearized reading' (duration: 342.141991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.085852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:42.743615Z","time spent":"342.232333ms","remote":"127.0.0.1:48090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:01:43.086073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.288436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T12:01:43.086147Z","caller":"traceutil/trace.go:171","msg":"trace[1601949829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"391.476921ms","start":"2026-04-27T12:01:42.694655Z","end":"2026-04-27T12:01:43.086132Z","steps":["trace[1601949829] 'agreement among raft nodes before linearized reading' (duration: 391.114642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.086193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:42.694625Z","time spent":"391.559574ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T12:01:43.086091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.150548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-04-27T12:01:43.086316Z","caller":"traceutil/trace.go:171","msg":"trace[1540659978] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1247; }","duration":"506.388784ms","start":"2026-04-27T12:01:42.579913Z","end":"2026-04-27T12:01:43.086302Z","steps":["trace[1540659978] 'agreement among raft nodes before linearized reading' (duration: 506.081826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.086352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:42.579905Z","time spent":"506.438746ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1512,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-04-27T12:01:43.086337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.041951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:43.086414Z","caller":"traceutil/trace.go:171","msg":"trace[1504316697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"350.176434ms","start":"2026-04-27T12:01:42.736221Z","end":"2026-04-27T12:01:43.086397Z","steps":["trace[1504316697] 'agreement among raft nodes before linearized reading' (duration: 350.057471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.086451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:42.73619Z","time spent":"350.254707ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T12:01:43.424371Z","caller":"traceutil/trace.go:171","msg":"trace[2138307124] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"334.701319ms","start":"2026-04-27T12:01:43.089361Z","end":"2026-04-27T12:01:43.424062Z","steps":["trace[2138307124] 'process raft request' (duration: 334.553924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.425006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:43.089332Z","time spent":"335.11984ms","remote":"127.0.0.1:47948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1026,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:01:43.513421Z","caller":"traceutil/trace.go:171","msg":"trace[418654376] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"422.369728ms","start":"2026-04-27T12:01:43.091028Z","end":"2026-04-27T12:01:43.513398Z","steps":["trace[418654376] 'process raft request' (duration: 421.991729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.513554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:43.091019Z","time spent":"422.467531ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:01:43.776325Z","caller":"traceutil/trace.go:171","msg":"trace[1212676930] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"252.509833ms","start":"2026-04-27T12:01:43.523783Z","end":"2026-04-27T12:01:43.776292Z","steps":["trace[1212676930] 'read index received' (duration: 252.38328ms)","trace[1212676930] 'applied index is now lower than readState.Index' (duration: 125.443µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:43.776371Z","caller":"traceutil/trace.go:171","msg":"trace[1229872469] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"253.477959ms","start":"2026-04-27T12:01:43.522871Z","end":"2026-04-27T12:01:43.776349Z","steps":["trace[1229872469] 'process raft request' (duration: 253.260793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.776632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.742149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:43.776736Z","caller":"traceutil/trace.go:171","msg":"trace[1325756888] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:1252; }","duration":"252.937044ms","start":"2026-04-27T12:01:43.523777Z","end":"2026-04-27T12:01:43.776714Z","steps":["trace[1325756888] 'agreement among raft nodes before linearized reading' (duration: 252.642077ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:44.006962Z","caller":"traceutil/trace.go:171","msg":"trace[146022142] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"230.408017ms","start":"2026-04-27T12:01:43.776524Z","end":"2026-04-27T12:01:44.006932Z","steps":["trace[146022142] 'read index received' (duration: 133.037908ms)","trace[146022142] 'applied index is now lower than readState.Index' (duration: 97.368849ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:44.006993Z","caller":"traceutil/trace.go:171","msg":"trace[1293274030] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"336.816166ms","start":"2026-04-27T12:01:43.670141Z","end":"2026-04-27T12:01:44.006957Z","steps":["trace[1293274030] 'process raft request' (duration: 239.411406ms)","trace[1293274030] 'compare' (duration: 97.09538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:01:44.007137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:43.670116Z","time spent":"336.95385ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:01:44.00725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.557424ms","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-04-27T12:01:44.007285Z","caller":"traceutil/trace.go:171","msg":"trace[1538014212] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1253; }","duration":"264.700648ms","start":"2026-04-27T12:01:43.742573Z","end":"2026-04-27T12:01:44.007274Z","steps":["trace[1538014212] 'agreement among raft nodes before linearized reading' (duration: 264.502573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:44.007258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.474976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:44.007353Z","caller":"traceutil/trace.go:171","msg":"trace[517865432] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1253; }","duration":"374.65237ms","start":"2026-04-27T12:01:43.632681Z","end":"2026-04-27T12:01:44.007334Z","steps":["trace[517865432] 'agreement among raft nodes before linearized reading' (duration: 374.454675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:44.00741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:43.63266Z","time spent":"374.739613ms","remote":"127.0.0.1:42010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:01:44.007712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.625004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:44.007763Z","caller":"traceutil/trace.go:171","msg":"trace[1922788068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"279.709306ms","start":"2026-04-27T12:01:43.728039Z","end":"2026-04-27T12:01:44.007748Z","steps":["trace[1922788068] 'agreement among raft nodes before linearized reading' (duration: 279.617534ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:44.01037Z","caller":"traceutil/trace.go:171","msg":"trace[593753783] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"231.567267ms","start":"2026-04-27T12:01:43.778764Z","end":"2026-04-27T12:01:44.010332Z","steps":["trace[593753783] 'process raft request' (duration: 231.360142ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:44.673396Z","caller":"traceutil/trace.go:171","msg":"trace[2036396494] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"118.577795ms","start":"2026-04-27T12:01:44.554771Z","end":"2026-04-27T12:01:44.673349Z","steps":["trace[2036396494] 'process raft request' (duration: 54.598343ms)","trace[2036396494] 'compare' (duration: 63.859259ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:44.842471Z","caller":"traceutil/trace.go:171","msg":"trace[1321016973] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"115.644278ms","start":"2026-04-27T12:01:44.726779Z","end":"2026-04-27T12:01:44.842424Z","steps":["trace[1321016973] 'read index received' (duration: 114.469646ms)","trace[1321016973] 'applied index is now lower than readState.Index' (duration: 1.173672ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:44.842506Z","caller":"traceutil/trace.go:171","msg":"trace[989541949] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"129.68033ms","start":"2026-04-27T12:01:44.712788Z","end":"2026-04-27T12:01:44.842469Z","steps":["trace[989541949] 'process raft request' (duration: 128.537819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:44.842737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.919835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:44.842863Z","caller":"traceutil/trace.go:171","msg":"trace[1900242351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"116.104419ms","start":"2026-04-27T12:01:44.726729Z","end":"2026-04-27T12:01:44.842833Z","steps":["trace[1900242351] 'agreement among raft nodes before linearized reading' (duration: 115.841602ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:45.65128Z","caller":"traceutil/trace.go:171","msg":"trace[361930554] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"131.42386ms","start":"2026-04-27T12:01:45.519825Z","end":"2026-04-27T12:01:45.651249Z","steps":["trace[361930554] 'process raft request' (duration: 131.240624ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:45.671133Z","caller":"traceutil/trace.go:171","msg":"trace[2031129475] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"148.737161ms","start":"2026-04-27T12:01:45.522373Z","end":"2026-04-27T12:01:45.671111Z","steps":["trace[2031129475] 'process raft request' (duration: 148.601347ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:47.839594Z","caller":"traceutil/trace.go:171","msg":"trace[1188466334] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"139.856419ms","start":"2026-04-27T12:01:47.699706Z","end":"2026-04-27T12:01:47.839563Z","steps":["trace[1188466334] 'process raft request' (duration: 72.682799ms)","trace[1188466334] 'compare' (duration: 66.955575ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:47.83955Z","caller":"traceutil/trace.go:171","msg":"trace[321386631] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"112.458049ms","start":"2026-04-27T12:01:47.727046Z","end":"2026-04-27T12:01:47.839504Z","steps":["trace[321386631] 'read index received' (duration: 45.261947ms)","trace[321386631] 'applied index is now lower than readState.Index' (duration: 67.194582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:01:47.839826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.760187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:47.839884Z","caller":"traceutil/trace.go:171","msg":"trace[1149062341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"112.860939ms","start":"2026-04-27T12:01:47.727009Z","end":"2026-04-27T12:01:47.83987Z","steps":["trace[1149062341] 'agreement among raft nodes before linearized reading' (duration: 112.750357ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:49.35422Z","caller":"traceutil/trace.go:171","msg":"trace[376495762] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"167.366279ms","start":"2026-04-27T12:01:49.186831Z","end":"2026-04-27T12:01:49.354197Z","steps":["trace[376495762] 'process raft request' (duration: 167.129413ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:04.82251Z","caller":"traceutil/trace.go:171","msg":"trace[1037760731] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1397; }","duration":"170.807056ms","start":"2026-04-27T12:02:04.651679Z","end":"2026-04-27T12:02:04.822486Z","steps":["trace[1037760731] 'read index received' (duration: 170.672143ms)","trace[1037760731] 'applied index is now lower than readState.Index' (duration: 134.243µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:02:04.82258Z","caller":"traceutil/trace.go:171","msg":"trace[603954208] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"352.63776ms","start":"2026-04-27T12:02:04.469925Z","end":"2026-04-27T12:02:04.822563Z","steps":["trace[603954208] 'process raft request' (duration: 352.451976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:04.822696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.024123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T12:02:04.8227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:04.469889Z","time spent":"352.736633ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:02:04.82272Z","caller":"traceutil/trace.go:171","msg":"trace[1016873998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"171.089194ms","start":"2026-04-27T12:02:04.651624Z","end":"2026-04-27T12:02:04.822713Z","steps":["trace[1016873998] 'agreement among raft nodes before linearized reading' (duration: 170.990602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:10.085083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.897058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:02:10.085166Z","caller":"traceutil/trace.go:171","msg":"trace[587068693] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1369; }","duration":"149.035842ms","start":"2026-04-27T12:02:09.936114Z","end":"2026-04-27T12:02:10.08515Z","steps":["trace[587068693] 'count revisions from in-memory index tree' (duration: 148.800036ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:11.658803Z","caller":"traceutil/trace.go:171","msg":"trace[1300133204] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"760.68079ms","start":"2026-04-27T12:02:10.898099Z","end":"2026-04-27T12:02:11.658779Z","steps":["trace[1300133204] 'process raft request' (duration: 760.556717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:11.658934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:10.898072Z","time spent":"760.800134ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:02:11.730233Z","caller":"traceutil/trace.go:171","msg":"trace[1527476606] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1427; }","duration":"315.717357ms","start":"2026-04-27T12:02:11.414485Z","end":"2026-04-27T12:02:11.730202Z","steps":["trace[1527476606] 'read index received' (duration: 244.714875ms)","trace[1527476606] 'applied index is now lower than readState.Index' (duration: 71.001372ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:11.73042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.923482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:02:11.73049Z","caller":"traceutil/trace.go:171","msg":"trace[909676651] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1373; }","duration":"316.051175ms","start":"2026-04-27T12:02:11.414423Z","end":"2026-04-27T12:02:11.730474Z","steps":["trace[909676651] 'agreement among raft nodes before linearized reading' (duration: 315.909521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:11.730525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:11.4144Z","time spent":"316.117217ms","remote":"127.0.0.1:47912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":6,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-27T12:02:11.73064Z","caller":"traceutil/trace.go:171","msg":"trace[827033449] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"830.740098ms","start":"2026-04-27T12:02:10.899595Z","end":"2026-04-27T12:02:11.730335Z","steps":["trace[827033449] 'process raft request' (duration: 817.019606ms)","trace[827033449] 'compare' (duration: 13.457845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:11.730779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.481704ms","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-04-27T12:02:11.731044Z","caller":"traceutil/trace.go:171","msg":"trace[1942038930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1373; }","duration":"235.856854ms","start":"2026-04-27T12:02:11.495166Z","end":"2026-04-27T12:02:11.731022Z","steps":["trace[1942038930] 'agreement among raft nodes before linearized reading' (duration: 235.297709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:11.730882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:10.899575Z","time spent":"831.146158ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:02:15.268261Z","caller":"traceutil/trace.go:171","msg":"trace[515219550] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1443; }","duration":"173.27101ms","start":"2026-04-27T12:02:15.094961Z","end":"2026-04-27T12:02:15.268232Z","steps":["trace[515219550] 'read index received' (duration: 161.686856ms)","trace[515219550] 'applied index is now lower than readState.Index' (duration: 11.582564ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:02:15.268435Z","caller":"traceutil/trace.go:171","msg":"trace[1410489491] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"173.806494ms","start":"2026-04-27T12:02:15.094601Z","end":"2026-04-27T12:02:15.268407Z","steps":["trace[1410489491] 'process raft request' (duration: 173.494785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:15.269611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.616916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-04-27T12:02:15.269921Z","caller":"traceutil/trace.go:171","msg":"trace[222013653] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1388; }","duration":"174.76156ms","start":"2026-04-27T12:02:15.094926Z","end":"2026-04-27T12:02:15.269688Z","steps":["trace[222013653] 'agreement among raft nodes before linearized reading' (duration: 173.531987ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:21.979512Z","caller":"traceutil/trace.go:171","msg":"trace[723566547] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"111.612465ms","start":"2026-04-27T12:02:21.867847Z","end":"2026-04-27T12:02:21.979459Z","steps":["trace[723566547] 'process raft request' (duration: 111.458011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:24.089292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.461212ms","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-04-27T12:02:24.089405Z","caller":"traceutil/trace.go:171","msg":"trace[1226515779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1416; }","duration":"105.604055ms","start":"2026-04-27T12:02:23.983771Z","end":"2026-04-27T12:02:24.089375Z","steps":["trace[1226515779] 'range keys from in-memory index tree' (duration: 105.260886ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:24.090105Z","caller":"traceutil/trace.go:171","msg":"trace[984467308] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"105.337168ms","start":"2026-04-27T12:02:23.984746Z","end":"2026-04-27T12:02:24.090083Z","steps":["trace[984467308] 'process raft request' (duration: 99.15024ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:26.273188Z","caller":"traceutil/trace.go:171","msg":"trace[505406768] linearizableReadLoop","detail":"{readStateIndex:1483; appliedIndex:1482; }","duration":"116.467403ms","start":"2026-04-27T12:02:26.156692Z","end":"2026-04-27T12:02:26.273159Z","steps":["trace[505406768] 'read index received' (duration: 115.678042ms)","trace[505406768] 'applied index is now lower than readState.Index' (duration: 788.291µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:02:26.273262Z","caller":"traceutil/trace.go:171","msg":"trace[555408563] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"178.71686ms","start":"2026-04-27T12:02:26.094522Z","end":"2026-04-27T12:02:26.273239Z","steps":["trace[555408563] 'process raft request' (duration: 177.771615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:26.273552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.828153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-04-27T12:02:26.27371Z","caller":"traceutil/trace.go:171","msg":"trace[1291297409] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1424; }","duration":"117.043138ms","start":"2026-04-27T12:02:26.156646Z","end":"2026-04-27T12:02:26.273689Z","steps":["trace[1291297409] 'agreement among raft nodes before linearized reading' (duration: 116.73416ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:26.316702Z","caller":"traceutil/trace.go:171","msg":"trace[259278369] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"139.511802ms","start":"2026-04-27T12:02:26.177157Z","end":"2026-04-27T12:02:26.316669Z","steps":["trace[259278369] 'process raft request' (duration: 139.310607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:26.31699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.802725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" ","response":"range_response_count:1 size:1486"} {"level":"info","ts":"2026-04-27T12:02:26.317094Z","caller":"traceutil/trace.go:171","msg":"trace[2079239887] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:1; response_revision:1426; }","duration":"148.966119ms","start":"2026-04-27T12:02:26.168105Z","end":"2026-04-27T12:02:26.317071Z","steps":["trace[2079239887] 'agreement among raft nodes before linearized reading' (duration: 148.796535ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:26.317271Z","caller":"traceutil/trace.go:171","msg":"trace[1996811162] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"109.575116ms","start":"2026-04-27T12:02:26.207657Z","end":"2026-04-27T12:02:26.317232Z","steps":["trace[1996811162] 'process raft request' (duration: 108.954909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:26.317515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.815259ms","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-04-27T12:02:26.317609Z","caller":"traceutil/trace.go:171","msg":"trace[2013249589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1426; }","duration":"147.951392ms","start":"2026-04-27T12:02:26.169645Z","end":"2026-04-27T12:02:26.317596Z","steps":["trace[2013249589] 'agreement among raft nodes before linearized reading' (duration: 147.563712ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:35.925814Z","caller":"traceutil/trace.go:171","msg":"trace[1004913388] linearizableReadLoop","detail":"{readStateIndex:1516; appliedIndex:1515; }","duration":"249.060051ms","start":"2026-04-27T12:02:35.676735Z","end":"2026-04-27T12:02:35.925795Z","steps":["trace[1004913388] 'read index received' (duration: 248.930807ms)","trace[1004913388] 'applied index is now lower than readState.Index' (duration: 128.424µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:35.926002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.256476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/kube-root-ca.crt\" ","response":"range_response_count:1 size:1760"} {"level":"warn","ts":"2026-04-27T12:02:35.926015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.273565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"warn","ts":"2026-04-27T12:02:35.926011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.279732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:02:35.926035Z","caller":"traceutil/trace.go:171","msg":"trace[1348540000] range","detail":"{range_begin:/registry/configmaps/local-path-storage/kube-root-ca.crt; range_end:; response_count:1; response_revision:1455; }","duration":"249.335558ms","start":"2026-04-27T12:02:35.676688Z","end":"2026-04-27T12:02:35.926023Z","steps":["trace[1348540000] 'agreement among raft nodes before linearized reading' (duration: 249.203615ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:35.926049Z","caller":"traceutil/trace.go:171","msg":"trace[707578397] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:1455; }","duration":"246.328636ms","start":"2026-04-27T12:02:35.679711Z","end":"2026-04-27T12:02:35.92604Z","steps":["trace[707578397] 'agreement among raft nodes before linearized reading' (duration: 246.239693ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:35.926069Z","caller":"traceutil/trace.go:171","msg":"trace[741003290] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1455; }","duration":"194.376945ms","start":"2026-04-27T12:02:35.73168Z","end":"2026-04-27T12:02:35.926057Z","steps":["trace[741003290] 'agreement among raft nodes before linearized reading' (duration: 194.280752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:35.926102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.505376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:02:35.926159Z","caller":"traceutil/trace.go:171","msg":"trace[96476627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1455; }","duration":"199.610988ms","start":"2026-04-27T12:02:35.726533Z","end":"2026-04-27T12:02:35.926144Z","steps":["trace[96476627] 'agreement among raft nodes before linearized reading' (duration: 199.503405ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:35.925924Z","caller":"traceutil/trace.go:171","msg":"trace[571635853] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"260.76275ms","start":"2026-04-27T12:02:35.665141Z","end":"2026-04-27T12:02:35.925904Z","steps":["trace[571635853] 'process raft request' (duration: 260.531874ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:36.590434Z","caller":"traceutil/trace.go:171","msg":"trace[283864890] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"110.81074ms","start":"2026-04-27T12:02:36.479604Z","end":"2026-04-27T12:02:36.590415Z","steps":["trace[283864890] 'read index received' (duration: 72.718058ms)","trace[283864890] 'applied index is now lower than readState.Index' (duration: 38.091662ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:02:36.590496Z","caller":"traceutil/trace.go:171","msg":"trace[35109009] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"112.242269ms","start":"2026-04-27T12:02:36.478237Z","end":"2026-04-27T12:02:36.59048Z","steps":["trace[35109009] 'process raft request' (duration: 74.075625ms)","trace[35109009] 'compare' (duration: 37.970089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:36.590603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.970587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T12:02:36.590626Z","caller":"traceutil/trace.go:171","msg":"trace[1784727922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1459; }","duration":"110.007408ms","start":"2026-04-27T12:02:36.480613Z","end":"2026-04-27T12:02:36.59062Z","steps":["trace[1784727922] 'agreement among raft nodes before linearized reading' (duration: 109.894775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:36.590585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.960555ms","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-04-27T12:02:36.590673Z","caller":"traceutil/trace.go:171","msg":"trace[496380124] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1459; }","duration":"111.061977ms","start":"2026-04-27T12:02:36.479588Z","end":"2026-04-27T12:02:36.59065Z","steps":["trace[496380124] 'agreement among raft nodes before linearized reading' (duration: 110.906133ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:36.789554Z","caller":"traceutil/trace.go:171","msg":"trace[1606996369] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"195.395354ms","start":"2026-04-27T12:02:36.59412Z","end":"2026-04-27T12:02:36.789515Z","steps":["trace[1606996369] 'process raft request' (duration: 141.10736ms)","trace[1606996369] 'compare' (duration: 53.912304ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:02:36.789642Z","caller":"traceutil/trace.go:171","msg":"trace[835331017] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"195.395804ms","start":"2026-04-27T12:02:36.59422Z","end":"2026-04-27T12:02:36.789616Z","steps":["trace[835331017] 'process raft request' (duration: 195.272241ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:36.789924Z","caller":"traceutil/trace.go:171","msg":"trace[659596206] linearizableReadLoop","detail":"{readStateIndex:1522; appliedIndex:1520; }","duration":"192.767303ms","start":"2026-04-27T12:02:36.59683Z","end":"2026-04-27T12:02:36.789597Z","steps":["trace[659596206] 'read index received' (duration: 138.396766ms)","trace[659596206] 'applied index is now lower than readState.Index' (duration: 54.369657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:36.790071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.436345ms","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-04-27T12:02:36.790102Z","caller":"traceutil/trace.go:171","msg":"trace[145054827] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1462; }","duration":"127.516307ms","start":"2026-04-27T12:02:36.662578Z","end":"2026-04-27T12:02:36.790094Z","steps":["trace[145054827] 'agreement among raft nodes before linearized reading' (duration: 127.437125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:36.790085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.239415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rscw4\" ","response":"range_response_count:1 size:4905"} {"level":"info","ts":"2026-04-27T12:02:36.790154Z","caller":"traceutil/trace.go:171","msg":"trace[1063218598] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rscw4; range_end:; response_count:1; response_revision:1462; }","duration":"193.357458ms","start":"2026-04-27T12:02:36.59678Z","end":"2026-04-27T12:02:36.790137Z","steps":["trace[1063218598] 'agreement among raft nodes before linearized reading' (duration: 193.123652ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:38.244504Z","caller":"traceutil/trace.go:171","msg":"trace[956947238] linearizableReadLoop","detail":"{readStateIndex:1524; appliedIndex:1523; }","duration":"226.158361ms","start":"2026-04-27T12:02:38.018325Z","end":"2026-04-27T12:02:38.244483Z","steps":["trace[956947238] 'read index received' (duration: 225.984245ms)","trace[956947238] 'applied index is now lower than readState.Index' (duration: 172.946µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:02:38.244564Z","caller":"traceutil/trace.go:171","msg":"trace[1681952362] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"239.498626ms","start":"2026-04-27T12:02:38.005023Z","end":"2026-04-27T12:02:38.244522Z","steps":["trace[1681952362] 'process raft request' (duration: 239.274079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:38.244673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.481533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:token-cleaner\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-04-27T12:02:38.244712Z","caller":"traceutil/trace.go:171","msg":"trace[289089695] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:token-cleaner; range_end:; response_count:1; response_revision:1463; }","duration":"198.572465ms","start":"2026-04-27T12:02:38.046127Z","end":"2026-04-27T12:02:38.2447Z","steps":["trace[289089695] 'agreement among raft nodes before linearized reading' (duration: 198.486213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:38.244751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.368486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"info","ts":"2026-04-27T12:02:38.244848Z","caller":"traceutil/trace.go:171","msg":"trace[301991732] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1463; }","duration":"226.530131ms","start":"2026-04-27T12:02:38.018295Z","end":"2026-04-27T12:02:38.244825Z","steps":["trace[301991732] 'agreement among raft nodes before linearized reading' (duration: 226.318055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:42.56007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.613332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-27T12:02:42.56017Z","caller":"traceutil/trace.go:171","msg":"trace[247021289] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:1; response_revision:1477; }","duration":"133.761846ms","start":"2026-04-27T12:02:42.426388Z","end":"2026-04-27T12:02:42.56015Z","steps":["trace[247021289] 'range keys from in-memory index tree' (duration: 133.486539ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:42.560715Z","caller":"traceutil/trace.go:171","msg":"trace[706707626] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"117.727546ms","start":"2026-04-27T12:02:42.442973Z","end":"2026-04-27T12:02:42.560701Z","steps":["trace[706707626] 'process raft request' (duration: 82.348887ms)","trace[706707626] 'compare' (duration: 34.740542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:42.823621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.955959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-04-27T12:02:42.823695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.488807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-27T12:02:42.823723Z","caller":"traceutil/trace.go:171","msg":"trace[770732762] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:1; response_revision:1479; }","duration":"154.105764ms","start":"2026-04-27T12:02:42.669597Z","end":"2026-04-27T12:02:42.823703Z","steps":["trace[770732762] 'range keys from in-memory index tree' (duration: 153.829195ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:42.82375Z","caller":"traceutil/trace.go:171","msg":"trace[939526375] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:1479; }","duration":"118.57729ms","start":"2026-04-27T12:02:42.705158Z","end":"2026-04-27T12:02:42.823735Z","steps":["trace[939526375] 'range keys from in-memory index tree' (duration: 118.422955ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:42.991893Z","caller":"traceutil/trace.go:171","msg":"trace[929030629] linearizableReadLoop","detail":"{readStateIndex:1542; appliedIndex:1541; }","duration":"139.492062ms","start":"2026-04-27T12:02:42.852378Z","end":"2026-04-27T12:02:42.99187Z","steps":["trace[929030629] 'read index received' (duration: 139.287077ms)","trace[929030629] 'applied index is now lower than readState.Index' (duration: 203.525µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:02:42.992021Z","caller":"traceutil/trace.go:171","msg":"trace[1064025016] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"140.614533ms","start":"2026-04-27T12:02:42.851398Z","end":"2026-04-27T12:02:42.992012Z","steps":["trace[1064025016] 'process raft request' (duration: 140.335956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:42.992224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.830622ms","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-04-27T12:02:42.992255Z","caller":"traceutil/trace.go:171","msg":"trace[618422846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1480; }","duration":"139.894323ms","start":"2026-04-27T12:02:42.852353Z","end":"2026-04-27T12:02:42.992247Z","steps":["trace[618422846] 'agreement among raft nodes before linearized reading' (duration: 139.78179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:42.992881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.868837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51929"} {"level":"info","ts":"2026-04-27T12:02:42.992961Z","caller":"traceutil/trace.go:171","msg":"trace[870797110] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1480; }","duration":"134.983639ms","start":"2026-04-27T12:02:42.857958Z","end":"2026-04-27T12:02:42.992941Z","steps":["trace[870797110] 'agreement among raft nodes before linearized reading' (duration: 134.398152ms)"],"step_count":1}