{"level":"warn","ts":"2026-04-12T20:44:40.891589Z","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-12T20:44:40.891739Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-12T20:44:40.891821Z","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-12T20:44:40.891839Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-12T20:44:40.891911Z","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-12T20:44:40.892848Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-04-12T20:44:40.892946Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-12T20:44:40.901427Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.203222ms"} {"level":"info","ts":"2026-04-12T20:44:40.910931Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-04-12T20:44:40.911047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:44:40.911083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-04-12T20:44:40.9111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:44:40.911137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-04-12T20:44:40.917312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-04-12T20:44:40.928368Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:44:40.929678Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:44:40.930776Z","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-12T20:44:40.932874Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:44:40.933087Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:44:40.933298Z","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-12T20:44:40.933422Z","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-12T20:44:40.933439Z","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-12T20:44:40.94026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-04-12T20:44:40.940541Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-12T20:44:40.943569Z","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-12T20:44:40.943856Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-12T20:44:40.943941Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-12T20:44:40.945266Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:44:40.945339Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:44:41.518094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:44:41.518176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:44:41.518237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-04-12T20:44:41.51827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:44:41.518284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-12T20:44:41.518336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-04-12T20:44:41.518355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-12T20:44:41.520161Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:44:41.5202Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:44:41.520187Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:44:41.520408Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:44:41.520662Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:44:41.520725Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:44:41.522348Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:44:41.522581Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:44:41.52262Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:44:41.52414Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:44:41.531583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-04-12T20:44:43.049928Z","caller":"traceutil/trace.go:171","msg":"trace[1189271415] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"117.560527ms","start":"2026-04-12T20:44:42.932343Z","end":"2026-04-12T20:44:43.049904Z","steps":["trace[1189271415] 'process raft request' (duration: 108.533702ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:43.050019Z","caller":"traceutil/trace.go:171","msg":"trace[452937795] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"117.474186ms","start":"2026-04-12T20:44:42.932519Z","end":"2026-04-12T20:44:43.049994Z","steps":["trace[452937795] 'process raft request' (duration: 117.342606ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:43.049954Z","caller":"traceutil/trace.go:171","msg":"trace[1732349062] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:10; }","duration":"117.008333ms","start":"2026-04-12T20:44:42.932919Z","end":"2026-04-12T20:44:43.049928Z","steps":["trace[1732349062] 'read index received' (duration: 107.931578ms)","trace[1732349062] 'applied index is now lower than readState.Index' (duration: 9.075425ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:43.049962Z","caller":"traceutil/trace.go:171","msg":"trace[506409912] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"117.436726ms","start":"2026-04-12T20:44:42.932496Z","end":"2026-04-12T20:44:43.049933Z","steps":["trace[506409912] 'process raft request' (duration: 117.324045ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:43.050231Z","caller":"traceutil/trace.go:171","msg":"trace[1864318099] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"117.584935ms","start":"2026-04-12T20:44:42.932604Z","end":"2026-04-12T20:44:43.050189Z","steps":["trace[1864318099] 'process raft request' (duration: 117.289064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:43.050237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.218984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:44:43.050465Z","caller":"traceutil/trace.go:171","msg":"trace[982315341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"117.553537ms","start":"2026-04-12T20:44:42.932895Z","end":"2026-04-12T20:44:43.050449Z","steps":["trace[982315341] 'agreement among raft nodes before linearized reading' (duration: 117.115093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:43.052367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.356152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:44:43.052435Z","caller":"traceutil/trace.go:171","msg":"trace[952610368] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:14; }","duration":"118.453503ms","start":"2026-04-12T20:44:42.933962Z","end":"2026-04-12T20:44:43.052416Z","steps":["trace[952610368] 'agreement among raft nodes before linearized reading' (duration: 118.249902ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:43.052548Z","caller":"traceutil/trace.go:171","msg":"trace[1598541489] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"116.846681ms","start":"2026-04-12T20:44:42.93568Z","end":"2026-04-12T20:44:43.052526Z","steps":["trace[1598541489] 'process raft request' (duration: 116.353009ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:43.05279Z","caller":"traceutil/trace.go:171","msg":"trace[171944960] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"119.67196ms","start":"2026-04-12T20:44:42.933083Z","end":"2026-04-12T20:44:43.052755Z","steps":["trace[171944960] 'process raft request' (duration: 118.826015ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:43.05585Z","caller":"traceutil/trace.go:171","msg":"trace[551044869] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"106.520267ms","start":"2026-04-12T20:44:42.94843Z","end":"2026-04-12T20:44:43.054951Z","steps":["trace[551044869] 'process raft request' (duration: 103.65326ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:44.232536Z","caller":"traceutil/trace.go:171","msg":"trace[80178036] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"162.878328ms","start":"2026-04-12T20:44:44.069631Z","end":"2026-04-12T20:44:44.23251Z","steps":["trace[80178036] 'read index received' (duration: 90.570365ms)","trace[80178036] 'applied index is now lower than readState.Index' (duration: 72.306853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:44.232805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.117219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:44:44.232706Z","caller":"traceutil/trace.go:171","msg":"trace[1693248889] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"167.075384ms","start":"2026-04-12T20:44:44.065558Z","end":"2026-04-12T20:44:44.232634Z","steps":["trace[1693248889] 'process raft request' (duration: 94.63163ms)","trace[1693248889] 'compare' (duration: 72.194042ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:44.23286Z","caller":"traceutil/trace.go:171","msg":"trace[2020354564] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:0; response_revision:77; }","duration":"163.24053ms","start":"2026-04-12T20:44:44.069605Z","end":"2026-04-12T20:44:44.232846Z","steps":["trace[2020354564] 'agreement among raft nodes before linearized reading' (duration: 162.998128ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:45.023318Z","caller":"traceutil/trace.go:171","msg":"trace[1201364358] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"105.267624ms","start":"2026-04-12T20:44:44.918013Z","end":"2026-04-12T20:44:45.023281Z","steps":["trace[1201364358] 'process raft request' (duration: 17.085564ms)","trace[1201364358] 'compare' (duration: 87.996389ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:45.238243Z","caller":"traceutil/trace.go:171","msg":"trace[199180337] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"177.33797ms","start":"2026-04-12T20:44:45.060868Z","end":"2026-04-12T20:44:45.238206Z","steps":["trace[199180337] 'process raft request' (duration: 105.311092ms)","trace[199180337] 'compare' (duration: 71.876957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:45.452929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.457906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-12T20:44:45.453057Z","caller":"traceutil/trace.go:171","msg":"trace[2081365829] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:120; }","duration":"160.299618ms","start":"2026-04-12T20:44:45.29274Z","end":"2026-04-12T20:44:45.45304Z","steps":["trace[2081365829] 'read index received' (duration: 47.019737ms)","trace[2081365829] 'applied index is now lower than readState.Index' (duration: 113.278571ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:45.45315Z","caller":"traceutil/trace.go:171","msg":"trace[498473024] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"180.218668ms","start":"2026-04-12T20:44:45.272897Z","end":"2026-04-12T20:44:45.453116Z","steps":["trace[498473024] 'process raft request' (duration: 66.920127ms)","trace[498473024] 'compare' (duration: 112.267865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:45.45319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.451519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:44:45.453222Z","caller":"traceutil/trace.go:171","msg":"trace[1590337504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:117; }","duration":"160.530419ms","start":"2026-04-12T20:44:45.292682Z","end":"2026-04-12T20:44:45.453212Z","steps":["trace[1590337504] 'agreement among raft nodes before linearized reading' (duration: 160.410758ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:45.636537Z","caller":"traceutil/trace.go:171","msg":"trace[1424386296] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"106.329508ms","start":"2026-04-12T20:44:45.530187Z","end":"2026-04-12T20:44:45.636516Z","steps":["trace[1424386296] 'process raft request' (duration: 106.197607ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:45.889017Z","caller":"traceutil/trace.go:171","msg":"trace[607270824] linearizableReadLoop","detail":"{readStateIndex:132; appliedIndex:131; }","duration":"186.189385ms","start":"2026-04-12T20:44:45.702803Z","end":"2026-04-12T20:44:45.888992Z","steps":["trace[607270824] 'read index received' (duration: 106.72925ms)","trace[607270824] 'applied index is now lower than readState.Index' (duration: 79.458815ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:45.889104Z","caller":"traceutil/trace.go:171","msg":"trace[298207466] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"188.491238ms","start":"2026-04-12T20:44:45.700592Z","end":"2026-04-12T20:44:45.889083Z","steps":["trace[298207466] 'process raft request' (duration: 108.899053ms)","trace[298207466] 'compare' (duration: 79.363804ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:45.889181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.358135ms","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-12T20:44:45.889268Z","caller":"traceutil/trace.go:171","msg":"trace[57848415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:128; }","duration":"186.558916ms","start":"2026-04-12T20:44:45.702652Z","end":"2026-04-12T20:44:45.889211Z","steps":["trace[57848415] 'agreement among raft nodes before linearized reading' (duration: 186.432505ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:46.106532Z","caller":"traceutil/trace.go:171","msg":"trace[1905766321] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"130.055459ms","start":"2026-04-12T20:44:45.976421Z","end":"2026-04-12T20:44:46.106477Z","steps":["trace[1905766321] 'process raft request' (duration: 42.78102ms)","trace[1905766321] 'compare' (duration: 87.112178ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:46.301214Z","caller":"traceutil/trace.go:171","msg":"trace[355351135] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"140.523501ms","start":"2026-04-12T20:44:46.160652Z","end":"2026-04-12T20:44:46.301176Z","steps":["trace[355351135] 'process raft request' (duration: 88.827149ms)","trace[355351135] 'compare' (duration: 51.581232ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:47.536018Z","caller":"traceutil/trace.go:171","msg":"trace[875965845] linearizableReadLoop","detail":"{readStateIndex:219; appliedIndex:218; }","duration":"111.041639ms","start":"2026-04-12T20:44:47.424946Z","end":"2026-04-12T20:44:47.535988Z","steps":["trace[875965845] 'read index received' (duration: 43.00951ms)","trace[875965845] 'applied index is now lower than readState.Index' (duration: 68.030819ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:47.536109Z","caller":"traceutil/trace.go:171","msg":"trace[1562527428] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"149.021028ms","start":"2026-04-12T20:44:47.387041Z","end":"2026-04-12T20:44:47.536062Z","steps":["trace[1562527428] 'process raft request' (duration: 80.959558ms)","trace[1562527428] 'compare' (duration: 67.836648ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:47.536307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.283291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:47.536391Z","caller":"traceutil/trace.go:171","msg":"trace[775764817] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:0; response_revision:214; }","duration":"111.467012ms","start":"2026-04-12T20:44:47.424908Z","end":"2026-04-12T20:44:47.536375Z","steps":["trace[775764817] 'agreement among raft nodes before linearized reading' (duration: 111.24299ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:47.865742Z","caller":"traceutil/trace.go:171","msg":"trace[1937123429] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"128.904237ms","start":"2026-04-12T20:44:47.736813Z","end":"2026-04-12T20:44:47.865717Z","steps":["trace[1937123429] 'process raft request' (duration: 128.855306ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:47.865794Z","caller":"traceutil/trace.go:171","msg":"trace[735008690] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"130.599487ms","start":"2026-04-12T20:44:47.735167Z","end":"2026-04-12T20:44:47.865766Z","steps":["trace[735008690] 'process raft request' (duration: 63.263001ms)","trace[735008690] 'compare' (duration: 67.087885ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:48.024206Z","caller":"traceutil/trace.go:171","msg":"trace[1407811167] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"102.766188ms","start":"2026-04-12T20:44:47.921404Z","end":"2026-04-12T20:44:48.02417Z","steps":["trace[1407811167] 'process raft request' (duration: 44.88127ms)","trace[1407811167] 'compare' (duration: 57.740437ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:48.239687Z","caller":"traceutil/trace.go:171","msg":"trace[2065768375] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"128.615611ms","start":"2026-04-12T20:44:48.111028Z","end":"2026-04-12T20:44:48.239644Z","steps":["trace[2065768375] 'process raft request' (duration: 49.272135ms)","trace[2065768375] 'compare' (duration: 79.098545ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:48.402838Z","caller":"traceutil/trace.go:171","msg":"trace[1048557796] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"155.855984ms","start":"2026-04-12T20:44:48.246946Z","end":"2026-04-12T20:44:48.402802Z","steps":["trace[1048557796] 'process raft request' (duration: 95.248711ms)","trace[1048557796] 'compare' (duration: 60.476243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:48.674344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.689919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-04-12T20:44:48.674424Z","caller":"traceutil/trace.go:171","msg":"trace[1410198479] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:243; }","duration":"116.81695ms","start":"2026-04-12T20:44:48.557586Z","end":"2026-04-12T20:44:48.674403Z","steps":["trace[1410198479] 'range keys from in-memory index tree' (duration: 116.554468ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:48.889989Z","caller":"traceutil/trace.go:171","msg":"trace[122800763] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"210.17872ms","start":"2026-04-12T20:44:48.679784Z","end":"2026-04-12T20:44:48.889963Z","steps":["trace[122800763] 'process raft request' (duration: 209.974609ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:48.92463Z","caller":"traceutil/trace.go:171","msg":"trace[466440227] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"176.422178ms","start":"2026-04-12T20:44:48.748191Z","end":"2026-04-12T20:44:48.924613Z","steps":["trace[466440227] 'read index received' (duration: 141.591419ms)","trace[466440227] 'applied index is now lower than readState.Index' (duration: 34.829689ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:48.924712Z","caller":"traceutil/trace.go:171","msg":"trace[293326365] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"228.093897ms","start":"2026-04-12T20:44:48.696609Z","end":"2026-04-12T20:44:48.924703Z","steps":["trace[293326365] 'process raft request' (duration: 227.923446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:48.924955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.758427ms","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-12T20:44:48.925033Z","caller":"traceutil/trace.go:171","msg":"trace[2085752321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:248; }","duration":"144.869248ms","start":"2026-04-12T20:44:48.780143Z","end":"2026-04-12T20:44:48.925013Z","steps":["trace[2085752321] 'agreement among raft nodes before linearized reading' (duration: 144.686677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:48.92513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.87025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:48.925294Z","caller":"traceutil/trace.go:171","msg":"trace[186460626] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:248; }","duration":"177.056882ms","start":"2026-04-12T20:44:48.748163Z","end":"2026-04-12T20:44:48.92522Z","steps":["trace[186460626] 'agreement among raft nodes before linearized reading' (duration: 176.686799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:49.410335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.070563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:49.410502Z","caller":"traceutil/trace.go:171","msg":"trace[330755073] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"480.75835ms","start":"2026-04-12T20:44:48.929727Z","end":"2026-04-12T20:44:49.410485Z","steps":["trace[330755073] 'process raft request' (duration: 480.701289ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:49.410531Z","caller":"traceutil/trace.go:171","msg":"trace[353772022] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"481.316844ms","start":"2026-04-12T20:44:48.929182Z","end":"2026-04-12T20:44:49.410499Z","steps":["trace[353772022] 'process raft request' (duration: 114.846078ms)","trace[353772022] 'compare' (duration: 365.931283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:49.410762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:48.929163Z","time spent":"481.486834ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:49.410567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:48.929713Z","time spent":"480.82931ms","remote":"127.0.0.1:40214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:49.483966Z","caller":"traceutil/trace.go:171","msg":"trace[1577207448] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"513.362213ms","start":"2026-04-12T20:44:48.970579Z","end":"2026-04-12T20:44:49.483941Z","steps":["trace[1577207448] 'process raft request' (duration: 513.282243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:49.4842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:48.970557Z","time spent":"513.574794ms","remote":"127.0.0.1:40326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:44:49.484016Z","caller":"traceutil/trace.go:171","msg":"trace[1695466299] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"516.318981ms","start":"2026-04-12T20:44:48.967659Z","end":"2026-04-12T20:44:49.483978Z","steps":["trace[1695466299] 'process raft request' (duration: 516.06634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:49.48474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:48.967639Z","time spent":"516.780974ms","remote":"127.0.0.1:40158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:44:49.674416Z","caller":"traceutil/trace.go:171","msg":"trace[780822966] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:258; }","duration":"186.390441ms","start":"2026-04-12T20:44:49.488Z","end":"2026-04-12T20:44:49.67439Z","steps":["trace[780822966] 'read index received' (duration: 186.107889ms)","trace[780822966] 'applied index is now lower than readState.Index' (duration: 281.472µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:49.674438Z","caller":"traceutil/trace.go:171","msg":"trace[1668091356] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"595.421842ms","start":"2026-04-12T20:44:49.078976Z","end":"2026-04-12T20:44:49.674398Z","steps":["trace[1668091356] 'process raft request' (duration: 595.213811ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:49.674691Z","caller":"traceutil/trace.go:171","msg":"trace[1407351897] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"260.440604ms","start":"2026-04-12T20:44:49.414238Z","end":"2026-04-12T20:44:49.674679Z","steps":["trace[1407351897] 'process raft request' (duration: 260.091212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:49.674694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:49.078951Z","time spent":"595.569303ms","remote":"127.0.0.1:40304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:44:49.67492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.712754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:49.675014Z","caller":"traceutil/trace.go:171","msg":"trace[1769236865] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:254; }","duration":"186.867534ms","start":"2026-04-12T20:44:49.488122Z","end":"2026-04-12T20:44:49.674989Z","steps":["trace[1769236865] 'agreement among raft nodes before linearized reading' (duration: 186.619513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:49.675013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.973504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:49.675095Z","caller":"traceutil/trace.go:171","msg":"trace[1425920258] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:0; response_revision:254; }","duration":"187.115556ms","start":"2026-04-12T20:44:49.487958Z","end":"2026-04-12T20:44:49.675073Z","steps":["trace[1425920258] 'agreement among raft nodes before linearized reading' (duration: 186.952405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:50.007351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.973987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:50.007506Z","caller":"traceutil/trace.go:171","msg":"trace[705832237] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"271.920472ms","start":"2026-04-12T20:44:49.735544Z","end":"2026-04-12T20:44:50.007465Z","steps":["trace[705832237] 'process raft request' (duration: 55.770543ms)","trace[705832237] 'compare' (duration: 215.824476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:50.216762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.666431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:50.216872Z","caller":"traceutil/trace.go:171","msg":"trace[1481365273] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:266; }","duration":"203.91815ms","start":"2026-04-12T20:44:50.012935Z","end":"2026-04-12T20:44:50.216853Z","steps":["trace[1481365273] 'read index received' (duration: 99.082728ms)","trace[1481365273] 'applied index is now lower than readState.Index' (duration: 104.834272ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:50.216958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.015701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:50.216937Z","caller":"traceutil/trace.go:171","msg":"trace[1336948065] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"204.599133ms","start":"2026-04-12T20:44:50.012302Z","end":"2026-04-12T20:44:50.216901Z","steps":["trace[1336948065] 'process raft request' (duration: 99.697341ms)","trace[1336948065] 'compare' (duration: 104.534521ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:50.216997Z","caller":"traceutil/trace.go:171","msg":"trace[2014690521] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:261; }","duration":"204.065471ms","start":"2026-04-12T20:44:50.012914Z","end":"2026-04-12T20:44:50.216979Z","steps":["trace[2014690521] 'agreement among raft nodes before linearized reading' (duration: 204.009411ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:50.217423Z","caller":"traceutil/trace.go:171","msg":"trace[1100534603] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"185.606672ms","start":"2026-04-12T20:44:50.031786Z","end":"2026-04-12T20:44:50.217393Z","steps":["trace[1100534603] 'process raft request' (duration: 185.46917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:50.217491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.685088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:50.217568Z","caller":"traceutil/trace.go:171","msg":"trace[1258052722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:262; }","duration":"186.793079ms","start":"2026-04-12T20:44:50.030753Z","end":"2026-04-12T20:44:50.217546Z","steps":["trace[1258052722] 'agreement among raft nodes before linearized reading' (duration: 186.620117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:50.217597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.683799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-12T20:44:50.217647Z","caller":"traceutil/trace.go:171","msg":"trace[1075756227] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:262; }","duration":"176.7894ms","start":"2026-04-12T20:44:50.040843Z","end":"2026-04-12T20:44:50.217633Z","steps":["trace[1075756227] 'agreement among raft nodes before linearized reading' (duration: 176.65533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:50.805873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.727276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:50.806254Z","caller":"traceutil/trace.go:171","msg":"trace[2083441315] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:269; }","duration":"494.577005ms","start":"2026-04-12T20:44:50.311659Z","end":"2026-04-12T20:44:50.806236Z","steps":["trace[2083441315] 'read index received' (duration: 303.728963ms)","trace[2083441315] 'applied index is now lower than readState.Index' (duration: 190.846872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:50.806362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.696106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:50.806399Z","caller":"traceutil/trace.go:171","msg":"trace[1962593779] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:265; }","duration":"494.773816ms","start":"2026-04-12T20:44:50.311613Z","end":"2026-04-12T20:44:50.806387Z","steps":["trace[1962593779] 'agreement among raft nodes before linearized reading' (duration: 494.675395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:50.806434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:50.311591Z","time spent":"494.834996ms","remote":"127.0.0.1:40326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" "} {"level":"info","ts":"2026-04-12T20:44:50.806588Z","caller":"traceutil/trace.go:171","msg":"trace[930300591] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"571.990703ms","start":"2026-04-12T20:44:50.23454Z","end":"2026-04-12T20:44:50.80653Z","steps":["trace[930300591] 'process raft request' (duration: 571.617412ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:50.806691Z","caller":"traceutil/trace.go:171","msg":"trace[1594910938] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"579.842561ms","start":"2026-04-12T20:44:50.226814Z","end":"2026-04-12T20:44:50.806657Z","steps":["trace[1594910938] 'process raft request' (duration: 388.674567ms)","trace[1594910938] 'compare' (duration: 189.564555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:50.80684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:50.226804Z","time spent":"579.958052ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:50.807027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:50.234525Z","time spent":"572.137474ms","remote":"127.0.0.1:40304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:51.504132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.846876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:51.504307Z","caller":"traceutil/trace.go:171","msg":"trace[1095112814] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:272; }","duration":"617.25265ms","start":"2026-04-12T20:44:50.887037Z","end":"2026-04-12T20:44:51.50429Z","steps":["trace[1095112814] 'read index received' (duration: 196.128651ms)","trace[1095112814] 'applied index is now lower than readState.Index' (duration: 421.123039ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:51.504338Z","caller":"traceutil/trace.go:171","msg":"trace[763299883] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"690.188633ms","start":"2026-04-12T20:44:50.814101Z","end":"2026-04-12T20:44:51.50429Z","steps":["trace[763299883] 'process raft request' (duration: 269.116495ms)","trace[763299883] 'compare' (duration: 420.713345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:51.504482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:50.814088Z","time spent":"690.328313ms","remote":"127.0.0.1:40212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:44:51.50449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.437151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:51.504462Z","caller":"traceutil/trace.go:171","msg":"trace[445020420] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"686.228959ms","start":"2026-04-12T20:44:50.818164Z","end":"2026-04-12T20:44:51.504393Z","steps":["trace[445020420] 'process raft request' (duration: 686.063758ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:51.50459Z","caller":"traceutil/trace.go:171","msg":"trace[323116677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:268; }","duration":"617.552291ms","start":"2026-04-12T20:44:50.887001Z","end":"2026-04-12T20:44:51.504553Z","steps":["trace[323116677] 'agreement among raft nodes before linearized reading' (duration: 617.36324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:51.504637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:50.886984Z","time spent":"617.641851ms","remote":"127.0.0.1:40326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "} {"level":"warn","ts":"2026-04-12T20:44:51.504417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.217638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:51.50487Z","caller":"traceutil/trace.go:171","msg":"trace[242065155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:268; }","duration":"421.702972ms","start":"2026-04-12T20:44:51.083154Z","end":"2026-04-12T20:44:51.504857Z","steps":["trace[242065155] 'agreement among raft nodes before linearized reading' (duration: 421.229079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:51.504907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:51.083133Z","time spent":"421.763072ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:44:51.504725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:50.818147Z","time spent":"686.43044ms","remote":"127.0.0.1:40304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:51.892266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.003326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:51.892353Z","caller":"traceutil/trace.go:171","msg":"trace[708718621] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"324.234166ms","start":"2026-04-12T20:44:51.568106Z","end":"2026-04-12T20:44:51.89234Z","steps":["trace[708718621] 'read index received' (duration: 86.133469ms)","trace[708718621] 'applied index is now lower than readState.Index' (duration: 238.099977ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:51.892389Z","caller":"traceutil/trace.go:171","msg":"trace[764982061] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"382.036727ms","start":"2026-04-12T20:44:51.510328Z","end":"2026-04-12T20:44:51.892364Z","steps":["trace[764982061] 'process raft request' (duration: 143.8808ms)","trace[764982061] 'compare' (duration: 237.880125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:51.892554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:51.510306Z","time spent":"382.176688ms","remote":"127.0.0.1:40326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:44:51.892606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.3346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-1gkrdp\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-12T20:44:51.892657Z","caller":"traceutil/trace.go:171","msg":"trace[1773431158] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-1gkrdp; range_end:; response_count:1; response_revision:270; }","duration":"384.403061ms","start":"2026-04-12T20:44:51.508245Z","end":"2026-04-12T20:44:51.892648Z","steps":["trace[1773431158] 'agreement among raft nodes before linearized reading' (duration: 384.298591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:51.892787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:51.50824Z","time spent":"384.537922ms","remote":"127.0.0.1:40212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":564,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-1gkrdp\" "} {"level":"info","ts":"2026-04-12T20:44:51.898976Z","caller":"traceutil/trace.go:171","msg":"trace[1991980200] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"323.762983ms","start":"2026-04-12T20:44:51.575189Z","end":"2026-04-12T20:44:51.898952Z","steps":["trace[1991980200] 'process raft request' (duration: 323.612681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:51.899125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:51.575166Z","time spent":"323.883314ms","remote":"127.0.0.1:40304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:44:51.899254Z","caller":"traceutil/trace.go:171","msg":"trace[251761407] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"295.486825ms","start":"2026-04-12T20:44:51.603745Z","end":"2026-04-12T20:44:51.899232Z","steps":["trace[251761407] 'process raft request' (duration: 295.174833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:51.899412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.276352ms","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-12T20:44:51.899525Z","caller":"traceutil/trace.go:171","msg":"trace[840681211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:272; }","duration":"188.426024ms","start":"2026-04-12T20:44:51.711079Z","end":"2026-04-12T20:44:51.899505Z","steps":["trace[840681211] 'agreement among raft nodes before linearized reading' (duration: 188.199483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:53.244012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.292456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:53.244141Z","caller":"traceutil/trace.go:171","msg":"trace[586310317] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"154.209802ms","start":"2026-04-12T20:44:53.089906Z","end":"2026-04-12T20:44:53.244116Z","steps":["trace[586310317] 'read index received' (duration: 31.808366ms)","trace[586310317] 'applied index is now lower than readState.Index' (duration: 122.399646ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:53.244286Z","caller":"traceutil/trace.go:171","msg":"trace[1671858041] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"207.120862ms","start":"2026-04-12T20:44:53.037133Z","end":"2026-04-12T20:44:53.244254Z","steps":["trace[1671858041] 'process raft request' (duration: 84.518536ms)","trace[1671858041] 'compare' (duration: 122.101084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:53.244299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.077182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-12T20:44:53.244427Z","caller":"traceutil/trace.go:171","msg":"trace[1429946294] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:310; }","duration":"207.216182ms","start":"2026-04-12T20:44:53.037193Z","end":"2026-04-12T20:44:53.244409Z","steps":["trace[1429946294] 'agreement among raft nodes before linearized reading' (duration: 206.991261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:53.244565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.540232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-12T20:44:53.244699Z","caller":"traceutil/trace.go:171","msg":"trace[940251119] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:310; }","duration":"198.740953ms","start":"2026-04-12T20:44:53.045929Z","end":"2026-04-12T20:44:53.24467Z","steps":["trace[940251119] 'agreement among raft nodes before linearized reading' (duration: 198.3492ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:53.246798Z","caller":"traceutil/trace.go:171","msg":"trace[927268739] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"149.740216ms","start":"2026-04-12T20:44:53.097037Z","end":"2026-04-12T20:44:53.246778Z","steps":["trace[927268739] 'process raft request' (duration: 149.669996ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:53.246864Z","caller":"traceutil/trace.go:171","msg":"trace[2046099072] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"152.927635ms","start":"2026-04-12T20:44:53.093903Z","end":"2026-04-12T20:44:53.246831Z","steps":["trace[2046099072] 'process raft request' (duration: 152.709684ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:53.296293Z","caller":"traceutil/trace.go:171","msg":"trace[821646969] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"139.601567ms","start":"2026-04-12T20:44:53.156657Z","end":"2026-04-12T20:44:53.296258Z","steps":["trace[821646969] 'process raft request' (duration: 139.382785ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:53.296708Z","caller":"traceutil/trace.go:171","msg":"trace[1383365529] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"139.486277ms","start":"2026-04-12T20:44:53.157202Z","end":"2026-04-12T20:44:53.296688Z","steps":["trace[1383365529] 'process raft request' (duration: 138.970753ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:53.296765Z","caller":"traceutil/trace.go:171","msg":"trace[481123785] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"138.178229ms","start":"2026-04-12T20:44:53.158561Z","end":"2026-04-12T20:44:53.296739Z","steps":["trace[481123785] 'process raft request' (duration: 137.760207ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:53.296981Z","caller":"traceutil/trace.go:171","msg":"trace[350588120] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"138.833802ms","start":"2026-04-12T20:44:53.158118Z","end":"2026-04-12T20:44:53.296952Z","steps":["trace[350588120] 'process raft request' (duration: 138.086927ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:53.297035Z","caller":"traceutil/trace.go:171","msg":"trace[1154865947] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"138.683971ms","start":"2026-04-12T20:44:53.158272Z","end":"2026-04-12T20:44:53.296956Z","steps":["trace[1154865947] 'process raft request' (duration: 137.969907ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:53.752142Z","caller":"traceutil/trace.go:171","msg":"trace[43647426] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"105.820138ms","start":"2026-04-12T20:44:53.646296Z","end":"2026-04-12T20:44:53.752116Z","steps":["trace[43647426] 'process raft request' (duration: 48.742954ms)","trace[43647426] 'compare' (duration: 56.900343ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:54.33382Z","caller":"traceutil/trace.go:171","msg":"trace[247970322] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:347; }","duration":"258.093274ms","start":"2026-04-12T20:44:54.07571Z","end":"2026-04-12T20:44:54.333803Z","steps":["trace[247970322] 'read index received' (duration: 228.595181ms)","trace[247970322] 'applied index is now lower than readState.Index' (duration: 29.497453ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:54.333895Z","caller":"traceutil/trace.go:171","msg":"trace[335022187] transaction","detail":"{read_only:false; number_of_response:0; response_revision:335; }","duration":"263.232063ms","start":"2026-04-12T20:44:54.070658Z","end":"2026-04-12T20:44:54.33389Z","steps":["trace[335022187] 'process raft request' (duration: 233.633789ms)","trace[335022187] 'compare' (duration: 29.302781ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:54.334024Z","caller":"traceutil/trace.go:171","msg":"trace[1355160498] transaction","detail":"{read_only:false; number_of_response:0; response_revision:335; }","duration":"263.218733ms","start":"2026-04-12T20:44:54.070763Z","end":"2026-04-12T20:44:54.333982Z","steps":["trace[1355160498] 'process raft request' (duration: 262.943981ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:54.334151Z","caller":"traceutil/trace.go:171","msg":"trace[794178287] transaction","detail":"{read_only:false; number_of_response:0; response_revision:335; }","duration":"262.935152ms","start":"2026-04-12T20:44:54.071211Z","end":"2026-04-12T20:44:54.334147Z","steps":["trace[794178287] 'process raft request' (duration: 262.57329ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:54.334151Z","caller":"traceutil/trace.go:171","msg":"trace[1209000652] transaction","detail":"{read_only:false; number_of_response:0; response_revision:335; }","duration":"263.333264ms","start":"2026-04-12T20:44:54.070799Z","end":"2026-04-12T20:44:54.334132Z","steps":["trace[1209000652] 'process raft request' (duration: 262.972632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.334215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.479736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-12T20:44:54.334314Z","caller":"traceutil/trace.go:171","msg":"trace[1255146042] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:335; }","duration":"258.623157ms","start":"2026-04-12T20:44:54.075668Z","end":"2026-04-12T20:44:54.334291Z","steps":["trace[1255146042] 'agreement among raft nodes before linearized reading' (duration: 258.409696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.411824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.499731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-12T20:44:54.412035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.574706ms","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-12T20:44:54.412076Z","caller":"traceutil/trace.go:171","msg":"trace[1554128115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:336; }","duration":"179.676466ms","start":"2026-04-12T20:44:54.232393Z","end":"2026-04-12T20:44:54.41207Z","steps":["trace[1554128115] 'agreement among raft nodes before linearized reading' (duration: 179.562936ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:54.411941Z","caller":"traceutil/trace.go:171","msg":"trace[25734027] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"336.146719ms","start":"2026-04-12T20:44:54.075761Z","end":"2026-04-12T20:44:54.411908Z","steps":["trace[25734027] 'process raft request' (duration: 335.629866ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:54.412152Z","caller":"traceutil/trace.go:171","msg":"trace[2091786792] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:336; }","duration":"314.795542ms","start":"2026-04-12T20:44:54.097258Z","end":"2026-04-12T20:44:54.412053Z","steps":["trace[2091786792] 'agreement among raft nodes before linearized reading' (duration: 314.493501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.412325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:54.097221Z","time spent":"315.089316ms","remote":"127.0.0.1:40226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-12T20:44:54.412315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:44:54.075751Z","time spent":"336.450221ms","remote":"127.0.0.1:40304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:44:54.549597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.573839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:44:54.549776Z","caller":"traceutil/trace.go:171","msg":"trace[1756393858] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:353; }","duration":"121.82885ms","start":"2026-04-12T20:44:54.427931Z","end":"2026-04-12T20:44:54.54976Z","steps":["trace[1756393858] 'read index received' (duration: 18.94642ms)","trace[1756393858] 'applied index is now lower than readState.Index' (duration: 102.88146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:54.54986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.92201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:44:54.549898Z","caller":"traceutil/trace.go:171","msg":"trace[1190091847] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:339; }","duration":"121.96292ms","start":"2026-04-12T20:44:54.427915Z","end":"2026-04-12T20:44:54.549877Z","steps":["trace[1190091847] 'agreement among raft nodes before linearized reading' (duration: 121.89611ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:54.549879Z","caller":"traceutil/trace.go:171","msg":"trace[882645694] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"132.27122ms","start":"2026-04-12T20:44:54.417576Z","end":"2026-04-12T20:44:54.549847Z","steps":["trace[882645694] 'process raft request' (duration: 29.35649ms)","trace[882645694] 'compare' (duration: 102.413208ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:54.549909Z","caller":"traceutil/trace.go:171","msg":"trace[500477536] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"128.886451ms","start":"2026-04-12T20:44:54.420999Z","end":"2026-04-12T20:44:54.549886Z","steps":["trace[500477536] 'process raft request' (duration: 128.68854ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:54.836512Z","caller":"traceutil/trace.go:171","msg":"trace[861891428] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"212.514678ms","start":"2026-04-12T20:44:54.623971Z","end":"2026-04-12T20:44:54.836485Z","steps":["trace[861891428] 'read index received' (duration: 157.640908ms)","trace[861891428] 'applied index is now lower than readState.Index' (duration: 54.87288ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:54.836575Z","caller":"traceutil/trace.go:171","msg":"trace[874188386] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"278.631073ms","start":"2026-04-12T20:44:54.557908Z","end":"2026-04-12T20:44:54.836539Z","steps":["trace[874188386] 'process raft request' (duration: 223.667142ms)","trace[874188386] 'compare' (duration: 54.80303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:54.836765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.76848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-12T20:44:54.836823Z","caller":"traceutil/trace.go:171","msg":"trace[2106417395] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:341; }","duration":"212.86497ms","start":"2026-04-12T20:44:54.623941Z","end":"2026-04-12T20:44:54.836806Z","steps":["trace[2106417395] 'agreement among raft nodes before linearized reading' (duration: 212.690649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:44:54.836904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.610866ms","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-12T20:44:54.836945Z","caller":"traceutil/trace.go:171","msg":"trace[265585762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:341; }","duration":"160.705417ms","start":"2026-04-12T20:44:54.676226Z","end":"2026-04-12T20:44:54.836931Z","steps":["trace[265585762] 'agreement among raft nodes before linearized reading' (duration: 160.602226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:02.504539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315158597097643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:45:03.005685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315158597097643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:45:03.506237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315158597097643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:45:03.593575Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.898027195s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:45:03.593972Z","caller":"traceutil/trace.go:171","msg":"trace[733782281] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.898470227s","start":"2026-04-12T20:45:01.695474Z","end":"2026-04-12T20:45:03.593944Z","steps":["trace[733782281] 'process raft request' (duration: 1.898282767s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:03.594134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:01.69546Z","time spent":"1.898574708s","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:45:03.712665Z","caller":"traceutil/trace.go:171","msg":"trace[98310797] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:437; }","duration":"1.70902069s","start":"2026-04-12T20:45:02.003616Z","end":"2026-04-12T20:45:03.712637Z","steps":["trace[98310797] 'read index received' (duration: 1.590258572s)","trace[98310797] 'applied index is now lower than readState.Index' (duration: 118.760848ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:03.713048Z","caller":"traceutil/trace.go:171","msg":"trace[1013366962] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"319.863838ms","start":"2026-04-12T20:45:03.393154Z","end":"2026-04-12T20:45:03.713018Z","steps":["trace[1013366962] 'process raft request' (duration: 258.732815ms)","trace[1013366962] 'compare' (duration: 60.443599ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:03.713185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:03.393132Z","time spent":"319.978619ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:45:03.713202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709574464s","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-12T20:45:03.713289Z","caller":"traceutil/trace.go:171","msg":"trace[953246368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"1.709709684s","start":"2026-04-12T20:45:02.003561Z","end":"2026-04-12T20:45:03.713271Z","steps":["trace[953246368] 'agreement among raft nodes before linearized reading' (duration: 1.709568503s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:03.713326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:02.003532Z","time spent":"1.709785255s","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T20:45:03.721543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123425455s","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-12T20:45:03.721606Z","caller":"traceutil/trace.go:171","msg":"trace[85961254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"1.123603197s","start":"2026-04-12T20:45:02.597987Z","end":"2026-04-12T20:45:03.72159Z","steps":["trace[85961254] 'agreement among raft nodes before linearized reading' (duration: 1.123491345s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:03.721601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.244515ms","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-12T20:45:03.721682Z","caller":"traceutil/trace.go:171","msg":"trace[1041626358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"813.351545ms","start":"2026-04-12T20:45:02.908299Z","end":"2026-04-12T20:45:03.721651Z","steps":["trace[1041626358] 'agreement among raft nodes before linearized reading' (duration: 813.224494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:03.721731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:02.908279Z","time spent":"813.440136ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:45:03.72175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.26637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-12T20:45:03.721791Z","caller":"traceutil/trace.go:171","msg":"trace[71715668] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:422; }","duration":"851.30945ms","start":"2026-04-12T20:45:02.870471Z","end":"2026-04-12T20:45:03.72178Z","steps":["trace[71715668] 'agreement among raft nodes before linearized reading' (duration: 851.225859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:03.721829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:02.870419Z","time spent":"851.40278ms","remote":"127.0.0.1:40304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-12T20:45:03.721908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:02.597967Z","time spent":"1.123930158s","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:45:06.048228Z","caller":"traceutil/trace.go:171","msg":"trace[496675084] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"401.649704ms","start":"2026-04-12T20:45:05.646548Z","end":"2026-04-12T20:45:06.048198Z","steps":["trace[496675084] 'read index received' (duration: 401.364563ms)","trace[496675084] 'applied index is now lower than readState.Index' (duration: 283.661µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:06.048392Z","caller":"traceutil/trace.go:171","msg":"trace[803673150] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"446.542442ms","start":"2026-04-12T20:45:05.601834Z","end":"2026-04-12T20:45:06.048377Z","steps":["trace[803673150] 'process raft request' (duration: 446.185889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:06.048615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.743799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-12T20:45:06.04871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.173766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:06.048709Z","caller":"traceutil/trace.go:171","msg":"trace[449716994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"319.889545ms","start":"2026-04-12T20:45:05.728797Z","end":"2026-04-12T20:45:06.048687Z","steps":["trace[449716994] 'agreement among raft nodes before linearized reading' (duration: 319.746259ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:06.048739Z","caller":"traceutil/trace.go:171","msg":"trace[1761665614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"402.224558ms","start":"2026-04-12T20:45:05.646505Z","end":"2026-04-12T20:45:06.04873Z","steps":["trace[1761665614] 'agreement among raft nodes before linearized reading' (duration: 402.177226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:06.048763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:05.646491Z","time spent":"402.267579ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:45:06.048768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:05.72877Z","time spent":"319.984589ms","remote":"127.0.0.1:40374","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-12T20:45:06.048874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:05.601807Z","time spent":"446.787412ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:45:06.049015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.53325ms","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-12T20:45:06.04906Z","caller":"traceutil/trace.go:171","msg":"trace[958033930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"303.614834ms","start":"2026-04-12T20:45:05.745432Z","end":"2026-04-12T20:45:06.049047Z","steps":["trace[958033930] 'agreement among raft nodes before linearized reading' (duration: 303.538921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:06.048654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.473598ms","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-12T20:45:06.049103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:05.745414Z","time spent":"303.679196ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:45:06.049109Z","caller":"traceutil/trace.go:171","msg":"trace[858911598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:431; }","duration":"272.973327ms","start":"2026-04-12T20:45:05.776128Z","end":"2026-04-12T20:45:06.049101Z","steps":["trace[858911598] 'agreement among raft nodes before linearized reading' (duration: 272.457287ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:08.430847Z","caller":"traceutil/trace.go:171","msg":"trace[261131416] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"124.55784ms","start":"2026-04-12T20:45:08.306266Z","end":"2026-04-12T20:45:08.430824Z","steps":["trace[261131416] 'process raft request' (duration: 124.388654ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:18.267677Z","caller":"traceutil/trace.go:171","msg":"trace[1055635949] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"136.324738ms","start":"2026-04-12T20:45:18.13133Z","end":"2026-04-12T20:45:18.267654Z","steps":["trace[1055635949] 'process raft request' (duration: 130.700829ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:18.269694Z","caller":"traceutil/trace.go:171","msg":"trace[1119054038] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:511; }","duration":"101.6626ms","start":"2026-04-12T20:45:18.168012Z","end":"2026-04-12T20:45:18.269674Z","steps":["trace[1119054038] 'read index received' (duration: 94.181918ms)","trace[1119054038] 'applied index is now lower than readState.Index' (duration: 7.479602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:18.269818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.791054ms","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-12T20:45:18.269778Z","caller":"traceutil/trace.go:171","msg":"trace[1057152594] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"137.873431ms","start":"2026-04-12T20:45:18.131867Z","end":"2026-04-12T20:45:18.26974Z","steps":["trace[1057152594] 'process raft request' (duration: 137.727826ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:18.269911Z","caller":"traceutil/trace.go:171","msg":"trace[114180158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:491; }","duration":"101.930998ms","start":"2026-04-12T20:45:18.167961Z","end":"2026-04-12T20:45:18.269892Z","steps":["trace[114180158] 'agreement among raft nodes before linearized reading' (duration: 101.795654ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:20.405078Z","caller":"traceutil/trace.go:171","msg":"trace[1240980136] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"116.4952ms","start":"2026-04-12T20:45:20.288566Z","end":"2026-04-12T20:45:20.405061Z","steps":["trace[1240980136] 'read index received' (duration: 91.989562ms)","trace[1240980136] 'applied index is now lower than readState.Index' (duration: 24.504998ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:20.405179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.598823ms","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-12T20:45:20.405201Z","caller":"traceutil/trace.go:171","msg":"trace[440574432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"116.658685ms","start":"2026-04-12T20:45:20.288536Z","end":"2026-04-12T20:45:20.405194Z","steps":["trace[440574432] 'agreement among raft nodes before linearized reading' (duration: 116.600263ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:20.405337Z","caller":"traceutil/trace.go:171","msg":"trace[155585224] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"129.653103ms","start":"2026-04-12T20:45:20.275661Z","end":"2026-04-12T20:45:20.405314Z","steps":["trace[155585224] 'process raft request' (duration: 104.97268ms)","trace[155585224] 'compare' (duration: 24.332272ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:23.59481Z","caller":"traceutil/trace.go:171","msg":"trace[296120378] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"427.540868ms","start":"2026-04-12T20:45:23.167242Z","end":"2026-04-12T20:45:23.594783Z","steps":["trace[296120378] 'process raft request' (duration: 427.358662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:23.595067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.167203Z","time spent":"427.778226ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:45:23.609748Z","caller":"traceutil/trace.go:171","msg":"trace[1836747227] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"437.993471ms","start":"2026-04-12T20:45:23.171706Z","end":"2026-04-12T20:45:23.609699Z","steps":["trace[1836747227] 'process raft request' (duration: 437.795695ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:23.609788Z","caller":"traceutil/trace.go:171","msg":"trace[1463101056] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"437.958701ms","start":"2026-04-12T20:45:23.171791Z","end":"2026-04-12T20:45:23.60975Z","steps":["trace[1463101056] 'process raft request' (duration: 437.796826ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:23.609817Z","caller":"traceutil/trace.go:171","msg":"trace[193945268] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"433.395475ms","start":"2026-04-12T20:45:23.176401Z","end":"2026-04-12T20:45:23.609797Z","steps":["trace[193945268] 'process raft request' (duration: 433.23083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:23.609898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.171682Z","time spent":"438.150656ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:45:23.60992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.176384Z","time spent":"433.481928ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:45:23.609913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.171785Z","time spent":"438.059654ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:45:23.609953Z","caller":"traceutil/trace.go:171","msg":"trace[803891242] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"430.018818ms","start":"2026-04-12T20:45:23.179891Z","end":"2026-04-12T20:45:23.60991Z","steps":["trace[803891242] 'process raft request' (duration: 429.77809ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:23.61004Z","caller":"traceutil/trace.go:171","msg":"trace[1430704571] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"422.356264ms","start":"2026-04-12T20:45:23.187659Z","end":"2026-04-12T20:45:23.610015Z","steps":["trace[1430704571] 'process raft request' (duration: 422.146817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:23.610118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.187653Z","time spent":"422.424716ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:45:23.610043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.179872Z","time spent":"430.11768ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:45:23.610283Z","caller":"traceutil/trace.go:171","msg":"trace[1104295303] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"418.629475ms","start":"2026-04-12T20:45:23.191638Z","end":"2026-04-12T20:45:23.610267Z","steps":["trace[1104295303] 'process raft request' (duration: 418.392217ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:23.610324Z","caller":"traceutil/trace.go:171","msg":"trace[1262627845] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"417.264781ms","start":"2026-04-12T20:45:23.193035Z","end":"2026-04-12T20:45:23.6103Z","steps":["trace[1262627845] 'process raft request' (duration: 417.097406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:23.610332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.19162Z","time spent":"418.691906ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:45:23.610413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.193018Z","time spent":"417.350474ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:45:23.610451Z","caller":"traceutil/trace.go:171","msg":"trace[1988460314] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"383.473435ms","start":"2026-04-12T20:45:23.226949Z","end":"2026-04-12T20:45:23.610423Z","steps":["trace[1988460314] 'process raft request' (duration: 383.306319ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:23.609982Z","caller":"traceutil/trace.go:171","msg":"trace[1174038501] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"426.202375ms","start":"2026-04-12T20:45:23.183761Z","end":"2026-04-12T20:45:23.609963Z","steps":["trace[1174038501] 'process raft request' (duration: 425.995899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:23.610555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.22693Z","time spent":"383.558798ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:45:23.610565Z","caller":"traceutil/trace.go:171","msg":"trace[1396310900] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"369.260391ms","start":"2026-04-12T20:45:23.241295Z","end":"2026-04-12T20:45:23.610555Z","steps":["trace[1396310900] 'process raft request' (duration: 369.085315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:23.610602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.241277Z","time spent":"369.307453ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:45:23.610723Z","caller":"traceutil/trace.go:171","msg":"trace[309912352] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"241.652368ms","start":"2026-04-12T20:45:23.369058Z","end":"2026-04-12T20:45:23.61071Z","steps":["trace[309912352] 'process raft request' (duration: 241.509482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:23.610782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:23.183755Z","time spent":"426.763923ms","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:45:26.580992Z","caller":"traceutil/trace.go:171","msg":"trace[71498355] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"424.294976ms","start":"2026-04-12T20:45:26.156674Z","end":"2026-04-12T20:45:26.580969Z","steps":["trace[71498355] 'read index received' (duration: 424.082889ms)","trace[71498355] 'applied index is now lower than readState.Index' (duration: 211.097µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:26.581144Z","caller":"traceutil/trace.go:171","msg":"trace[635388961] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"463.7495ms","start":"2026-04-12T20:45:26.117383Z","end":"2026-04-12T20:45:26.581132Z","steps":["trace[635388961] 'process raft request' (duration: 463.44114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.117371Z","time spent":"463.803442ms","remote":"127.0.0.1:40226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:45:26.581229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.537683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.581291Z","caller":"traceutil/trace.go:171","msg":"trace[1968039687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:618; }","duration":"424.649406ms","start":"2026-04-12T20:45:26.156629Z","end":"2026-04-12T20:45:26.581279Z","steps":["trace[1968039687] 'agreement among raft nodes before linearized reading' (duration: 424.537473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.156606Z","time spent":"424.716178ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T20:45:26.581498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.442217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.581525Z","caller":"traceutil/trace.go:171","msg":"trace[455848123] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:618; }","duration":"295.490599ms","start":"2026-04-12T20:45:26.286025Z","end":"2026-04-12T20:45:26.581516Z","steps":["trace[455848123] 'agreement among raft nodes before linearized reading' (duration: 295.450298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.227124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:45:26.58156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.765699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.581599Z","caller":"traceutil/trace.go:171","msg":"trace[1584955210] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:618; }","duration":"149.303327ms","start":"2026-04-12T20:45:26.432277Z","end":"2026-04-12T20:45:26.581581Z","steps":["trace[1584955210] 'agreement among raft nodes before linearized reading' (duration: 149.187654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.387829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.581681Z","caller":"traceutil/trace.go:171","msg":"trace[1513099762] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:618; }","duration":"149.42136ms","start":"2026-04-12T20:45:26.432252Z","end":"2026-04-12T20:45:26.581673Z","steps":["trace[1513099762] 'agreement among raft nodes before linearized reading' (duration: 149.372629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.458961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:45:26.581702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.099935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:45:26.581727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.739387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.581738Z","caller":"traceutil/trace.go:171","msg":"trace[398598785] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:618; }","duration":"348.585434ms","start":"2026-04-12T20:45:26.233136Z","end":"2026-04-12T20:45:26.581722Z","steps":["trace[398598785] 'agreement among raft nodes before linearized reading' (duration: 348.4331ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:26.581745Z","caller":"traceutil/trace.go:171","msg":"trace[1659514191] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:618; }","duration":"369.332492ms","start":"2026-04-12T20:45:26.2124Z","end":"2026-04-12T20:45:26.581733Z","steps":["trace[1659514191] 'agreement among raft nodes before linearized reading' (duration: 369.27085ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:26.581768Z","caller":"traceutil/trace.go:171","msg":"trace[1170616050] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:618; }","duration":"183.815329ms","start":"2026-04-12T20:45:26.397942Z","end":"2026-04-12T20:45:26.581757Z","steps":["trace[1170616050] 'agreement among raft nodes before linearized reading' (duration: 183.748537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.233119Z","time spent":"348.651766ms","remote":"127.0.0.1:41254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:45:26.581792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.59255ms","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-12T20:45:26.581817Z","caller":"traceutil/trace.go:171","msg":"trace[817218472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"268.661632ms","start":"2026-04-12T20:45:26.313148Z","end":"2026-04-12T20:45:26.58181Z","steps":["trace[817218472] 'agreement among raft nodes before linearized reading' (duration: 268.57754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.132128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.581859Z","caller":"traceutil/trace.go:171","msg":"trace[1866128514] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:618; }","duration":"370.18858ms","start":"2026-04-12T20:45:26.211662Z","end":"2026-04-12T20:45:26.581851Z","steps":["trace[1866128514] 'agreement among raft nodes before linearized reading' (duration: 370.145378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.211655Z","time spent":"370.233821ms","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:45:26.581945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.854431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.581969Z","caller":"traceutil/trace.go:171","msg":"trace[91040308] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:618; }","duration":"295.892653ms","start":"2026-04-12T20:45:26.286069Z","end":"2026-04-12T20:45:26.581962Z","steps":["trace[91040308] 'agreement among raft nodes before linearized reading' (duration: 295.857082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.072952ms","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-12T20:45:26.581997Z","caller":"traceutil/trace.go:171","msg":"trace[736821321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"150.139604ms","start":"2026-04-12T20:45:26.431848Z","end":"2026-04-12T20:45:26.581988Z","steps":["trace[736821321] 'agreement among raft nodes before linearized reading' (duration: 150.052302ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:26.581642Z","caller":"traceutil/trace.go:171","msg":"trace[1160267431] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:618; }","duration":"424.895703ms","start":"2026-04-12T20:45:26.156728Z","end":"2026-04-12T20:45:26.581624Z","steps":["trace[1160267431] 'agreement among raft nodes before linearized reading' (duration: 424.723748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.582049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.185981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:45:26.582081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.15672Z","time spent":"425.352048ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-12T20:45:26.582084Z","caller":"traceutil/trace.go:171","msg":"trace[839094806] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:618; }","duration":"296.255163ms","start":"2026-04-12T20:45:26.285819Z","end":"2026-04-12T20:45:26.582074Z","steps":["trace[839094806] 'agreement among raft nodes before linearized reading' (duration: 296.198111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.581786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.21238Z","time spent":"369.395884ms","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-12T20:45:26.582178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.543341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.582209Z","caller":"traceutil/trace.go:171","msg":"trace[567571114] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:618; }","duration":"370.680965ms","start":"2026-04-12T20:45:26.211519Z","end":"2026-04-12T20:45:26.5822Z","steps":["trace[567571114] 'agreement among raft nodes before linearized reading' (duration: 370.630703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.58224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.211511Z","time spent":"370.721426ms","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T20:45:26.582253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.265814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.582288Z","caller":"traceutil/trace.go:171","msg":"trace[1556148576] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:618; }","duration":"184.355317ms","start":"2026-04-12T20:45:26.39792Z","end":"2026-04-12T20:45:26.582276Z","steps":["trace[1556148576] 'agreement among raft nodes before linearized reading' (duration: 184.307596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.582297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.569563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.582334Z","caller":"traceutil/trace.go:171","msg":"trace[1426489708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:618; }","duration":"348.609025ms","start":"2026-04-12T20:45:26.233715Z","end":"2026-04-12T20:45:26.582324Z","steps":["trace[1426489708] 'agreement among raft nodes before linearized reading' (duration: 348.551723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.582367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.233667Z","time spent":"348.690448ms","remote":"127.0.0.1:41254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-12T20:45:26.582197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.184292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:45:26.582456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.541815ms","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-12T20:45:26.582486Z","caller":"traceutil/trace.go:171","msg":"trace[600743869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"389.596637ms","start":"2026-04-12T20:45:26.19288Z","end":"2026-04-12T20:45:26.582477Z","steps":["trace[600743869] 'agreement among raft nodes before linearized reading' (duration: 389.462623ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:26.582476Z","caller":"traceutil/trace.go:171","msg":"trace[1429366652] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:618; }","duration":"184.481881ms","start":"2026-04-12T20:45:26.397986Z","end":"2026-04-12T20:45:26.582468Z","steps":["trace[1429366652] 'agreement among raft nodes before linearized reading' (duration: 184.165711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.582515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.192866Z","time spent":"389.640898ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-12T20:45:26.582563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.340367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.582609Z","caller":"traceutil/trace.go:171","msg":"trace[1025296849] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:618; }","duration":"349.4114ms","start":"2026-04-12T20:45:26.233183Z","end":"2026-04-12T20:45:26.582595Z","steps":["trace[1025296849] 'agreement among raft nodes before linearized reading' (duration: 349.339928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.58265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:26.233169Z","time spent":"349.466032ms","remote":"127.0.0.1:41254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T20:45:26.582892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.51233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.582935Z","caller":"traceutil/trace.go:171","msg":"trace[241694438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:618; }","duration":"119.583462ms","start":"2026-04-12T20:45:26.463338Z","end":"2026-04-12T20:45:26.582922Z","steps":["trace[241694438] 'agreement among raft nodes before linearized reading' (duration: 119.520471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.583027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.968513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:26.583062Z","caller":"traceutil/trace.go:171","msg":"trace[420339040] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:618; }","duration":"119.025685ms","start":"2026-04-12T20:45:26.464027Z","end":"2026-04-12T20:45:26.583052Z","steps":["trace[420339040] 'agreement among raft nodes before linearized reading' (duration: 118.970844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:26.583119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.713596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:45:26.582927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.454258ms","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-12T20:45:26.583151Z","caller":"traceutil/trace.go:171","msg":"trace[3741713] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:618; }","duration":"119.905982ms","start":"2026-04-12T20:45:26.463235Z","end":"2026-04-12T20:45:26.583141Z","steps":["trace[3741713] 'agreement among raft nodes before linearized reading' (duration: 119.857261ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:26.583166Z","caller":"traceutil/trace.go:171","msg":"trace[1842019542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"144.700705ms","start":"2026-04-12T20:45:26.438452Z","end":"2026-04-12T20:45:26.583153Z","steps":["trace[1842019542] 'agreement among raft nodes before linearized reading' (duration: 144.428407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:30.363403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.330687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9gfcf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-12T20:45:30.363651Z","caller":"traceutil/trace.go:171","msg":"trace[565463878] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9gfcf; range_end:; response_count:1; response_revision:645; }","duration":"207.630636ms","start":"2026-04-12T20:45:30.156006Z","end":"2026-04-12T20:45:30.363637Z","steps":["trace[565463878] 'range keys from in-memory index tree' (duration: 207.188723ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:30.975136Z","caller":"traceutil/trace.go:171","msg":"trace[361838768] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"612.124551ms","start":"2026-04-12T20:45:30.362986Z","end":"2026-04-12T20:45:30.97511Z","steps":["trace[361838768] 'process raft request' (duration: 611.563435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:30.975309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:30.362864Z","time spent":"612.360048ms","remote":"127.0.0.1:40212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:45:31.510252Z","caller":"traceutil/trace.go:171","msg":"trace[1453648193] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"891.408105ms","start":"2026-04-12T20:45:30.618812Z","end":"2026-04-12T20:45:31.51022Z","steps":["trace[1453648193] 'read index received' (duration: 355.689268ms)","trace[1453648193] 'applied index is now lower than readState.Index' (duration: 535.717867ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:31.510343Z","caller":"traceutil/trace.go:171","msg":"trace[400885415] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.131770006s","start":"2026-04-12T20:45:30.378538Z","end":"2026-04-12T20:45:31.510308Z","steps":["trace[400885415] 'process raft request' (duration: 1.131534619s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.510577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:30.378513Z","time spent":"1.131983112s","remote":"127.0.0.1:40304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:45:31.510739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.91019ms","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-12T20:45:31.510791Z","caller":"traceutil/trace.go:171","msg":"trace[1531920561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"892.015603ms","start":"2026-04-12T20:45:30.618761Z","end":"2026-04-12T20:45:31.510776Z","steps":["trace[1531920561] 'agreement among raft nodes before linearized reading' (duration: 891.600241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.510924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:30.618739Z","time spent":"892.171818ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-12T20:45:31.650833Z","caller":"traceutil/trace.go:171","msg":"trace[543608030] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"140.469093ms","start":"2026-04-12T20:45:31.510335Z","end":"2026-04-12T20:45:31.650804Z","steps":["trace[543608030] 'read index received' (duration: 140.430052ms)","trace[543608030] 'applied index is now lower than readState.Index' (duration: 37.861µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:31.650912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005078108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:31.650979Z","caller":"traceutil/trace.go:171","msg":"trace[1394183642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.005177681s","start":"2026-04-12T20:45:30.645783Z","end":"2026-04-12T20:45:31.65096Z","steps":["trace[1394183642] 'agreement among raft nodes before linearized reading' (duration: 1.005031827s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.651024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:30.645773Z","time spent":"1.005240363s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:45:31.651038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015996129s","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-12T20:45:31.65107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016835504s","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-12T20:45:31.651115Z","caller":"traceutil/trace.go:171","msg":"trace[2070565735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"1.016104893s","start":"2026-04-12T20:45:30.63499Z","end":"2026-04-12T20:45:31.651095Z","steps":["trace[2070565735] 'agreement among raft nodes before linearized reading' (duration: 1.015878197s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:31.651119Z","caller":"traceutil/trace.go:171","msg":"trace[111915568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"1.016904536s","start":"2026-04-12T20:45:30.6342Z","end":"2026-04-12T20:45:31.651104Z","steps":["trace[111915568] 'agreement among raft nodes before linearized reading' (duration: 1.016739102s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.651157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:30.634982Z","time spent":"1.016166675s","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:45:31.651159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:30.634191Z","time spent":"1.016958488s","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:45:31.651329Z","caller":"traceutil/trace.go:171","msg":"trace[375790083] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"612.966798ms","start":"2026-04-12T20:45:31.038288Z","end":"2026-04-12T20:45:31.651255Z","steps":["trace[375790083] 'process raft request' (duration: 612.381491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.651366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016957257s","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-12T20:45:31.651418Z","caller":"traceutil/trace.go:171","msg":"trace[2145115284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"1.017068941s","start":"2026-04-12T20:45:30.634337Z","end":"2026-04-12T20:45:31.651406Z","steps":["trace[2145115284] 'agreement among raft nodes before linearized reading' (duration: 1.016584367s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.651569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:30.634315Z","time spent":"1.017199834s","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-12T20:45:31.651523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:31.038272Z","time spent":"613.139943ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:45:31.671382Z","caller":"traceutil/trace.go:171","msg":"trace[967044093] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"155.172399ms","start":"2026-04-12T20:45:31.516186Z","end":"2026-04-12T20:45:31.671358Z","steps":["trace[967044093] 'process raft request' (duration: 155.051406ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:31.996173Z","caller":"traceutil/trace.go:171","msg":"trace[515891141] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"340.023014ms","start":"2026-04-12T20:45:31.656131Z","end":"2026-04-12T20:45:31.996154Z","steps":["trace[515891141] 'process raft request' (duration: 339.388036ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:31.99624Z","caller":"traceutil/trace.go:171","msg":"trace[857398450] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:680; }","duration":"324.7472ms","start":"2026-04-12T20:45:31.671466Z","end":"2026-04-12T20:45:31.996213Z","steps":["trace[857398450] 'read index received' (duration: 324.124391ms)","trace[857398450] 'applied index is now lower than readState.Index' (duration: 621.469µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:31.996299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:31.656121Z","time spent":"340.099217ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:45:31.996605Z","caller":"traceutil/trace.go:171","msg":"trace[1010068368] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"340.365895ms","start":"2026-04-12T20:45:31.656218Z","end":"2026-04-12T20:45:31.996584Z","steps":["trace[1010068368] 'process raft request' (duration: 339.835589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.996663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.351803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-12T20:45:31.996716Z","caller":"traceutil/trace.go:171","msg":"trace[1255666797] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:652; }","duration":"341.405216ms","start":"2026-04-12T20:45:31.655302Z","end":"2026-04-12T20:45:31.996707Z","steps":["trace[1255666797] 'agreement among raft nodes before linearized reading' (duration: 341.326524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.996791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:31.655254Z","time spent":"341.502268ms","remote":"127.0.0.1:40326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-12T20:45:31.996757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:31.65621Z","time spent":"340.454118ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:45:31.996865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.970142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:31.996968Z","caller":"traceutil/trace.go:171","msg":"trace[42253281] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"341.078686ms","start":"2026-04-12T20:45:31.65588Z","end":"2026-04-12T20:45:31.996959Z","steps":["trace[42253281] 'process raft request' (duration: 340.282212ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:31.996951Z","caller":"traceutil/trace.go:171","msg":"trace[452488152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"318.107526ms","start":"2026-04-12T20:45:31.678825Z","end":"2026-04-12T20:45:31.996933Z","steps":["trace[452488152] 'agreement among raft nodes before linearized reading' (duration: 317.951922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:31.997038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:31.655864Z","time spent":"341.127928ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:45:31.99703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:31.678808Z","time spent":"318.20573ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:45:33.221088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.212307666s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-12T20:45:33.961665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.338968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:45:33.96173Z","caller":"traceutil/trace.go:171","msg":"trace[1463052863] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"316.237186ms","start":"2026-04-12T20:45:33.645482Z","end":"2026-04-12T20:45:33.961719Z","steps":["trace[1463052863] 'read index received' (duration: 49.741µs)","trace[1463052863] 'applied index is now lower than readState.Index' (duration: 316.186695ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:33.961776Z","caller":"traceutil/trace.go:171","msg":"trace[1270297044] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.953037765s","start":"2026-04-12T20:45:32.008707Z","end":"2026-04-12T20:45:33.961745Z","steps":["trace[1270297044] 'process raft request' (duration: 1.212577404s)","trace[1270297044] 'compare' (duration: 740.152253ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:33.962943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.862999ms","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-12T20:45:33.962991Z","caller":"traceutil/trace.go:171","msg":"trace[984569766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"286.941201ms","start":"2026-04-12T20:45:33.676026Z","end":"2026-04-12T20:45:33.962967Z","steps":["trace[984569766] 'agreement among raft nodes before linearized reading' (duration: 285.758327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:33.963252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:32.008689Z","time spent":"1.954457685s","remote":"127.0.0.1:40304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:45:33.964403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.899123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:33.964496Z","caller":"traceutil/trace.go:171","msg":"trace[1567072364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"319.017446ms","start":"2026-04-12T20:45:33.645445Z","end":"2026-04-12T20:45:33.964463Z","steps":["trace[1567072364] 'agreement among raft nodes before linearized reading' (duration: 316.311528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:33.964544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:33.645428Z","time spent":"319.106549ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:45:35.7399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.789087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:35.739988Z","caller":"traceutil/trace.go:171","msg":"trace[555991517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"416.927521ms","start":"2026-04-12T20:45:35.323045Z","end":"2026-04-12T20:45:35.739973Z","steps":["trace[555991517] 'range keys from in-memory index tree' (duration: 416.693535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:35.740044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:35.323025Z","time spent":"417.001483ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:45:35.740028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.770842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:35.740143Z","caller":"traceutil/trace.go:171","msg":"trace[382764765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:674; }","duration":"404.988188ms","start":"2026-04-12T20:45:35.335129Z","end":"2026-04-12T20:45:35.740117Z","steps":["trace[382764765] 'range keys from in-memory index tree' (duration: 404.702591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:35.740202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:35.335114Z","time spent":"405.07288ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-12T20:45:46.360642Z","caller":"traceutil/trace.go:171","msg":"trace[708972669] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"183.723819ms","start":"2026-04-12T20:45:46.176896Z","end":"2026-04-12T20:45:46.36062Z","steps":["trace[708972669] 'read index received' (duration: 183.586196ms)","trace[708972669] 'applied index is now lower than readState.Index' (duration: 136.563µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:46.360786Z","caller":"traceutil/trace.go:171","msg":"trace[647494985] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"216.606318ms","start":"2026-04-12T20:45:46.144166Z","end":"2026-04-12T20:45:46.360773Z","steps":["trace[647494985] 'process raft request' (duration: 216.307611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:46.361041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.125539ms","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-12T20:45:46.361083Z","caller":"traceutil/trace.go:171","msg":"trace[1767483790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"184.206651ms","start":"2026-04-12T20:45:46.176866Z","end":"2026-04-12T20:45:46.361072Z","steps":["trace[1767483790] 'agreement among raft nodes before linearized reading' (duration: 184.030577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:46.361254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.305339ms","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-12T20:45:46.361486Z","caller":"traceutil/trace.go:171","msg":"trace[1104619481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"177.609958ms","start":"2026-04-12T20:45:46.183858Z","end":"2026-04-12T20:45:46.361468Z","steps":["trace[1104619481] 'agreement among raft nodes before linearized reading' (duration: 177.227999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:46.361755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.053849ms","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-12T20:45:46.361859Z","caller":"traceutil/trace.go:171","msg":"trace[172240286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"174.434709ms","start":"2026-04-12T20:45:46.187407Z","end":"2026-04-12T20:45:46.361842Z","steps":["trace[172240286] 'agreement among raft nodes before linearized reading' (duration: 173.803913ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:48.447338Z","caller":"traceutil/trace.go:171","msg":"trace[1629131392] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"138.036766ms","start":"2026-04-12T20:45:48.309269Z","end":"2026-04-12T20:45:48.447306Z","steps":["trace[1629131392] 'process raft request' (duration: 137.866442ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:48.58932Z","caller":"traceutil/trace.go:171","msg":"trace[1650581194] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"137.357719ms","start":"2026-04-12T20:45:48.451939Z","end":"2026-04-12T20:45:48.589297Z","steps":["trace[1650581194] 'process raft request' (duration: 137.169305ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:48.594438Z","caller":"traceutil/trace.go:171","msg":"trace[85566990] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"142.410972ms","start":"2026-04-12T20:45:48.452016Z","end":"2026-04-12T20:45:48.594427Z","steps":["trace[85566990] 'process raft request' (duration: 142.30482ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:48.594557Z","caller":"traceutil/trace.go:171","msg":"trace[1688287423] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"142.433802ms","start":"2026-04-12T20:45:48.452117Z","end":"2026-04-12T20:45:48.594551Z","steps":["trace[1688287423] 'process raft request' (duration: 142.285239ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:48.594688Z","caller":"traceutil/trace.go:171","msg":"trace[1317903889] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"141.993581ms","start":"2026-04-12T20:45:48.452686Z","end":"2026-04-12T20:45:48.59468Z","steps":["trace[1317903889] 'process raft request' (duration: 141.841208ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:48.594803Z","caller":"traceutil/trace.go:171","msg":"trace[1630922948] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"141.744676ms","start":"2026-04-12T20:45:48.453051Z","end":"2026-04-12T20:45:48.594796Z","steps":["trace[1630922948] 'process raft request' (duration: 141.577792ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:50.136704Z","caller":"traceutil/trace.go:171","msg":"trace[296791303] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"490.261923ms","start":"2026-04-12T20:45:49.646417Z","end":"2026-04-12T20:45:50.136679Z","steps":["trace[296791303] 'read index received' (duration: 490.049108ms)","trace[296791303] 'applied index is now lower than readState.Index' (duration: 211.495µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:50.13684Z","caller":"traceutil/trace.go:171","msg":"trace[1183250910] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"679.448119ms","start":"2026-04-12T20:45:49.457382Z","end":"2026-04-12T20:45:50.13683Z","steps":["trace[1183250910] 'process raft request' (duration: 679.107901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:50.136948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:49.457353Z","time spent":"679.51466ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:45:50.137098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.694503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:50.137134Z","caller":"traceutil/trace.go:171","msg":"trace[1424177035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"490.786065ms","start":"2026-04-12T20:45:49.646338Z","end":"2026-04-12T20:45:50.137124Z","steps":["trace[1424177035] 'agreement among raft nodes before linearized reading' (duration: 490.733664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:50.137161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:49.646299Z","time spent":"490.855987ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:45:50.138024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.200886ms","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-12T20:45:50.138088Z","caller":"traceutil/trace.go:171","msg":"trace[784726394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"235.318929ms","start":"2026-04-12T20:45:49.902757Z","end":"2026-04-12T20:45:50.138076Z","steps":["trace[784726394] 'agreement among raft nodes before linearized reading' (duration: 235.159495ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:54.009953Z","caller":"traceutil/trace.go:171","msg":"trace[853277078] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"195.430204ms","start":"2026-04-12T20:45:53.814496Z","end":"2026-04-12T20:45:54.009926Z","steps":["trace[853277078] 'process raft request' (duration: 193.873889ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:54.032785Z","caller":"traceutil/trace.go:171","msg":"trace[521104666] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:895; }","duration":"192.538771ms","start":"2026-04-12T20:45:53.840225Z","end":"2026-04-12T20:45:54.032763Z","steps":["trace[521104666] 'read index received' (duration: 167.950916ms)","trace[521104666] 'applied index is now lower than readState.Index' (duration: 24.586845ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:54.032915Z","caller":"traceutil/trace.go:171","msg":"trace[1850045094] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"196.449882ms","start":"2026-04-12T20:45:53.836454Z","end":"2026-04-12T20:45:54.032904Z","steps":["trace[1850045094] 'process raft request' (duration: 196.209907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:54.033003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.723515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:45:54.033078Z","caller":"traceutil/trace.go:171","msg":"trace[1523795728] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:860; }","duration":"192.840108ms","start":"2026-04-12T20:45:53.840193Z","end":"2026-04-12T20:45:54.033033Z","steps":["trace[1523795728] 'agreement among raft nodes before linearized reading' (duration: 192.658754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:54.15194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.461075ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-12T20:45:54.152374Z","caller":"traceutil/trace.go:171","msg":"trace[320624083] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"107.373197ms","start":"2026-04-12T20:45:54.044956Z","end":"2026-04-12T20:45:54.152329Z","steps":["trace[320624083] 'process raft request' (duration: 107.121272ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:50.734853Z","caller":"traceutil/trace.go:171","msg":"trace[711051669] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"102.949996ms","start":"2026-04-12T20:46:50.631874Z","end":"2026-04-12T20:46:50.734824Z","steps":["trace[711051669] 'process raft request' (duration: 102.716183ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:56.893014Z","caller":"traceutil/trace.go:171","msg":"trace[800467293] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"121.86593ms","start":"2026-04-12T20:46:56.771129Z","end":"2026-04-12T20:46:56.892994Z","steps":["trace[800467293] 'process raft request' (duration: 121.749698ms)"],"step_count":1}