{"level":"warn","ts":"2026-05-07T23:21:17.373858Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:21:17.373952Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-05-07T23:21:17.374081Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:21:17.374096Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-07T23:21:17.374118Z","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-05-07T23:21:17.374754Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-05-07T23:21:17.374823Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-05-07T23:21:17.423738Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"48.652778ms"} {"level":"info","ts":"2026-05-07T23:21:17.462176Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-05-07T23:21:17.462295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T23:21:17.462336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-05-07T23:21:17.462355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T23:21:17.462445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-05-07T23:21:17.462704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-05-07T23:21:17.492455Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T23:21:17.501893Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T23:21:17.515801Z","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-05-07T23:21:17.532127Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T23:21:17.532433Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T23:21:17.532844Z","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-05-07T23:21:17.532921Z","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-05-07T23:21:17.532944Z","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-05-07T23:21:17.53753Z","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-05-07T23:21:17.537615Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-07T23:21:17.53764Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-07T23:21:17.537823Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T23:21:17.537887Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T23:21:17.553381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-05-07T23:21:17.553714Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-07T23:21:17.563507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T23:21:17.563599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T23:21:17.563737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-05-07T23:21:17.563887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-05-07T23:21:17.563912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-07T23:21:17.564073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-05-07T23:21:17.564099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-07T23:21:17.573744Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:21:17.573744Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T23:21:17.573762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:21:17.574196Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T23:21:17.574394Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T23:21:17.574122Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:21:17.577609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T23:21:17.578151Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-05-07T23:21:17.613835Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:21:17.613952Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:21:17.614103Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:21:19.366061Z","caller":"traceutil/trace.go:171","msg":"trace[451246452] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"214.454975ms","start":"2026-05-07T23:21:19.151573Z","end":"2026-05-07T23:21:19.366028Z","steps":["trace[451246452] 'process raft request' (duration: 132.873132ms)","trace[451246452] 'compare' (duration: 81.390752ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:19.366623Z","caller":"traceutil/trace.go:171","msg":"trace[573118445] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:13; }","duration":"154.193564ms","start":"2026-05-07T23:21:19.21187Z","end":"2026-05-07T23:21:19.366064Z","steps":["trace[573118445] 'read index received' (duration: 72.588292ms)","trace[573118445] 'applied index is now lower than readState.Index' (duration: 81.604292ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:19.366645Z","caller":"traceutil/trace.go:171","msg":"trace[1884529189] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"214.136852ms","start":"2026-05-07T23:21:19.151986Z","end":"2026-05-07T23:21:19.366123Z","steps":["trace[1884529189] 'process raft request' (duration: 214.025882ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.367479Z","caller":"traceutil/trace.go:171","msg":"trace[275587262] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"215.548791ms","start":"2026-05-07T23:21:19.151753Z","end":"2026-05-07T23:21:19.367302Z","steps":["trace[275587262] 'process raft request' (duration: 214.220192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:19.368406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.365907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T23:21:19.368457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.86757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:21:19.368521Z","caller":"traceutil/trace.go:171","msg":"trace[220798100] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"219.519298ms","start":"2026-05-07T23:21:19.148964Z","end":"2026-05-07T23:21:19.368483Z","steps":["trace[220798100] 'agreement among raft nodes before linearized reading' (duration: 217.782857ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.368585Z","caller":"traceutil/trace.go:171","msg":"trace[800584678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13; }","duration":"219.966031ms","start":"2026-05-07T23:21:19.148545Z","end":"2026-05-07T23:21:19.368511Z","steps":["trace[800584678] 'agreement among raft nodes before linearized reading' (duration: 218.31093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:19.368774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.784468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-07T23:21:19.368831Z","caller":"traceutil/trace.go:171","msg":"trace[513294111] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"178.878698ms","start":"2026-05-07T23:21:19.189938Z","end":"2026-05-07T23:21:19.368816Z","steps":["trace[513294111] 'agreement among raft nodes before linearized reading' (duration: 176.829545ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.369465Z","caller":"traceutil/trace.go:171","msg":"trace[229324969] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"154.290956ms","start":"2026-05-07T23:21:19.215152Z","end":"2026-05-07T23:21:19.369442Z","steps":["trace[229324969] 'process raft request' (duration: 153.771572ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.369684Z","caller":"traceutil/trace.go:171","msg":"trace[910017759] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"153.176458ms","start":"2026-05-07T23:21:19.216475Z","end":"2026-05-07T23:21:19.369651Z","steps":["trace[910017759] 'process raft request' (duration: 152.901076ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.370121Z","caller":"traceutil/trace.go:171","msg":"trace[2013385816] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"154.677248ms","start":"2026-05-07T23:21:19.215237Z","end":"2026-05-07T23:21:19.369914Z","steps":["trace[2013385816] 'process raft request' (duration: 153.974033ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.370331Z","caller":"traceutil/trace.go:171","msg":"trace[1791932379] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"154.964379ms","start":"2026-05-07T23:21:19.215333Z","end":"2026-05-07T23:21:19.370297Z","steps":["trace[1791932379] 'process raft request' (duration: 153.953113ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.370356Z","caller":"traceutil/trace.go:171","msg":"trace[187071768] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"156.065167ms","start":"2026-05-07T23:21:19.214275Z","end":"2026-05-07T23:21:19.37034Z","steps":["trace[187071768] 'process raft request' (duration: 153.816222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:19.370516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.362653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:21:19.370571Z","caller":"traceutil/trace.go:171","msg":"trace[1461153424] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:18; }","duration":"155.415843ms","start":"2026-05-07T23:21:19.215132Z","end":"2026-05-07T23:21:19.370547Z","steps":["trace[1461153424] 'agreement among raft nodes before linearized reading' (duration: 155.356823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:19.370654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.739435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:21:19.370937Z","caller":"traceutil/trace.go:171","msg":"trace[542863537] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:18; }","duration":"156.095467ms","start":"2026-05-07T23:21:19.21475Z","end":"2026-05-07T23:21:19.370846Z","steps":["trace[542863537] 'agreement among raft nodes before linearized reading' (duration: 154.703498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:19.615442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.734045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-07T23:21:19.6158Z","caller":"traceutil/trace.go:171","msg":"trace[174843426] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"236.690913ms","start":"2026-05-07T23:21:19.379073Z","end":"2026-05-07T23:21:19.615763Z","steps":["trace[174843426] 'process raft request' (duration: 124.78217ms)","trace[174843426] 'compare' (duration: 110.551104ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:19.615928Z","caller":"traceutil/trace.go:171","msg":"trace[1096476702] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"236.501701ms","start":"2026-05-07T23:21:19.379386Z","end":"2026-05-07T23:21:19.615888Z","steps":["trace[1096476702] 'process raft request' (duration: 236.35822ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.615952Z","caller":"traceutil/trace.go:171","msg":"trace[1740034708] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"235.863647ms","start":"2026-05-07T23:21:19.380064Z","end":"2026-05-07T23:21:19.615928Z","steps":["trace[1740034708] 'process raft request' (duration: 235.734826ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.616277Z","caller":"traceutil/trace.go:171","msg":"trace[674909003] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"235.745256ms","start":"2026-05-07T23:21:19.380516Z","end":"2026-05-07T23:21:19.616261Z","steps":["trace[674909003] 'process raft request' (duration: 235.643285ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.616281Z","caller":"traceutil/trace.go:171","msg":"trace[803143413] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"235.971268ms","start":"2026-05-07T23:21:19.380274Z","end":"2026-05-07T23:21:19.616245Z","steps":["trace[803143413] 'process raft request' (duration: 235.556795ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.61649Z","caller":"traceutil/trace.go:171","msg":"trace[572333469] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"231.118625ms","start":"2026-05-07T23:21:19.385273Z","end":"2026-05-07T23:21:19.616392Z","steps":["trace[572333469] 'process raft request' (duration: 230.985904ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.616685Z","caller":"traceutil/trace.go:171","msg":"trace[1916645715] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"231.251476ms","start":"2026-05-07T23:21:19.385416Z","end":"2026-05-07T23:21:19.616667Z","steps":["trace[1916645715] 'process raft request' (duration: 230.893023ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.617332Z","caller":"traceutil/trace.go:171","msg":"trace[1882830889] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"231.502898ms","start":"2026-05-07T23:21:19.385815Z","end":"2026-05-07T23:21:19.617318Z","steps":["trace[1882830889] 'process raft request' (duration: 231.272597ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.617361Z","caller":"traceutil/trace.go:171","msg":"trace[1736572270] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"231.84301ms","start":"2026-05-07T23:21:19.38551Z","end":"2026-05-07T23:21:19.617353Z","steps":["trace[1736572270] 'process raft request' (duration: 230.890044ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.61735Z","caller":"traceutil/trace.go:171","msg":"trace[1279687849] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:22; }","duration":"222.918811ms","start":"2026-05-07T23:21:19.394407Z","end":"2026-05-07T23:21:19.617326Z","steps":["trace[1279687849] 'read index received' (duration: 109.463838ms)","trace[1279687849] 'applied index is now lower than readState.Index' (duration: 113.450963ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:19.617525Z","caller":"traceutil/trace.go:171","msg":"trace[1012705966] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"220.464864ms","start":"2026-05-07T23:21:19.397055Z","end":"2026-05-07T23:21:19.61752Z","steps":["trace[1012705966] 'process raft request' (duration: 220.154682ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.618907Z","caller":"traceutil/trace.go:171","msg":"trace[117094140] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"221.040859ms","start":"2026-05-07T23:21:19.397853Z","end":"2026-05-07T23:21:19.618894Z","steps":["trace[117094140] 'process raft request' (duration: 219.597349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:19.618842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.599802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-xmhnh\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-07T23:21:19.619254Z","caller":"traceutil/trace.go:171","msg":"trace[1183027502] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-xmhnh; range_end:; response_count:1; response_revision:30; }","duration":"240.034095ms","start":"2026-05-07T23:21:19.379206Z","end":"2026-05-07T23:21:19.61924Z","steps":["trace[1183027502] 'agreement among raft nodes before linearized reading' (duration: 238.187203ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.619565Z","caller":"traceutil/trace.go:171","msg":"trace[2087744236] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"237.498028ms","start":"2026-05-07T23:21:19.382046Z","end":"2026-05-07T23:21:19.619544Z","steps":["trace[2087744236] 'process raft request' (duration: 234.166816ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.791109Z","caller":"traceutil/trace.go:171","msg":"trace[1133956688] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"169.068814ms","start":"2026-05-07T23:21:19.62201Z","end":"2026-05-07T23:21:19.791079Z","steps":["trace[1133956688] 'process raft request' (duration: 145.630728ms)","trace[1133956688] 'compare' (duration: 23.213154ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:19.791317Z","caller":"traceutil/trace.go:171","msg":"trace[1858835293] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"169.161874ms","start":"2026-05-07T23:21:19.622087Z","end":"2026-05-07T23:21:19.791248Z","steps":["trace[1858835293] 'process raft request' (duration: 169.011333ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.791337Z","caller":"traceutil/trace.go:171","msg":"trace[1056437594] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"169.249745ms","start":"2026-05-07T23:21:19.62203Z","end":"2026-05-07T23:21:19.79128Z","steps":["trace[1056437594] 'process raft request' (duration: 168.979063ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.791388Z","caller":"traceutil/trace.go:171","msg":"trace[242537668] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"169.053554ms","start":"2026-05-07T23:21:19.622325Z","end":"2026-05-07T23:21:19.791378Z","steps":["trace[242537668] 'process raft request' (duration: 168.803632ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.791203Z","caller":"traceutil/trace.go:171","msg":"trace[1637083696] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"168.51877ms","start":"2026-05-07T23:21:19.622668Z","end":"2026-05-07T23:21:19.791187Z","steps":["trace[1637083696] 'process raft request' (duration: 168.489129ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:19.79154Z","caller":"traceutil/trace.go:171","msg":"trace[1722031108] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"169.451866ms","start":"2026-05-07T23:21:19.62208Z","end":"2026-05-07T23:21:19.791532Z","steps":["trace[1722031108] 'process raft request' (duration: 168.985863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:20.234219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.158176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-07T23:21:20.234375Z","caller":"traceutil/trace.go:171","msg":"trace[1208514511] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:42; }","duration":"205.408285ms","start":"2026-05-07T23:21:20.028952Z","end":"2026-05-07T23:21:20.23436Z","steps":["trace[1208514511] 'read index received' (duration: 39.81µs)","trace[1208514511] 'applied index is now lower than readState.Index' (duration: 205.367485ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:20.234548Z","caller":"traceutil/trace.go:171","msg":"trace[749625723] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"369.695575ms","start":"2026-05-07T23:21:19.864838Z","end":"2026-05-07T23:21:20.234534Z","steps":["trace[749625723] 'process raft request' (duration: 369.466954ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:20.234534Z","caller":"traceutil/trace.go:171","msg":"trace[346664324] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"371.348176ms","start":"2026-05-07T23:21:19.863151Z","end":"2026-05-07T23:21:20.234499Z","steps":["trace[346664324] 'process raft request' (duration: 32.854438ms)","trace[346664324] 'compare' (duration: 338.049535ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:20.234609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:19.864818Z","time spent":"369.762006ms","remote":"127.0.0.1:36328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":715,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:21:20.234648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:19.863126Z","time spent":"371.471418ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":326,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:21:20.234834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.876428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:21:20.234869Z","caller":"traceutil/trace.go:171","msg":"trace[746499257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:41; }","duration":"205.95017ms","start":"2026-05-07T23:21:20.028908Z","end":"2026-05-07T23:21:20.234858Z","steps":["trace[746499257] 'agreement among raft nodes before linearized reading' (duration: 205.849499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:20.235161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.084215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:21:20.235219Z","caller":"traceutil/trace.go:171","msg":"trace[498583345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41; }","duration":"172.165116ms","start":"2026-05-07T23:21:20.063042Z","end":"2026-05-07T23:21:20.235207Z","steps":["trace[498583345] 'agreement among raft nodes before linearized reading' (duration: 172.077785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:20.235175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.867898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T23:21:20.235414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.030783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:21:20.235441Z","caller":"traceutil/trace.go:171","msg":"trace[24355838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:41; }","duration":"206.134821ms","start":"2026-05-07T23:21:20.029227Z","end":"2026-05-07T23:21:20.235361Z","steps":["trace[24355838] 'agreement among raft nodes before linearized reading' (duration: 205.829999ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:20.235476Z","caller":"traceutil/trace.go:171","msg":"trace[103525660] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:41; }","duration":"199.116655ms","start":"2026-05-07T23:21:20.036343Z","end":"2026-05-07T23:21:20.23546Z","steps":["trace[103525660] 'agreement among raft nodes before linearized reading' (duration: 198.896513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:20.235256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.316359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:21:20.235855Z","caller":"traceutil/trace.go:171","msg":"trace[1457403949] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:41; }","duration":"195.911263ms","start":"2026-05-07T23:21:20.03991Z","end":"2026-05-07T23:21:20.235821Z","steps":["trace[1457403949] 'agreement among raft nodes before linearized reading' (duration: 195.320179ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:21.612838Z","caller":"traceutil/trace.go:171","msg":"trace[1172763954] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"290.960785ms","start":"2026-05-07T23:21:21.32185Z","end":"2026-05-07T23:21:21.612811Z","steps":["trace[1172763954] 'process raft request' (duration: 193.060164ms)","trace[1172763954] 'compare' (duration: 97.79971ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:22.072732Z","caller":"traceutil/trace.go:171","msg":"trace[946868349] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"269.849605ms","start":"2026-05-07T23:21:21.802842Z","end":"2026-05-07T23:21:22.072691Z","steps":["trace[946868349] 'process raft request' (duration: 183.960745ms)","trace[946868349] 'compare' (duration: 85.738679ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:24.609547Z","caller":"traceutil/trace.go:171","msg":"trace[70632689] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"216.676252ms","start":"2026-05-07T23:21:24.392842Z","end":"2026-05-07T23:21:24.609518Z","steps":["trace[70632689] 'process raft request' (duration: 216.526591ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:24.686159Z","caller":"traceutil/trace.go:171","msg":"trace[480825148] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"292.960472ms","start":"2026-05-07T23:21:24.393176Z","end":"2026-05-07T23:21:24.686136Z","steps":["trace[480825148] 'process raft request' (duration: 264.69771ms)","trace[480825148] 'compare' (duration: 27.91247ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:26.485851Z","caller":"traceutil/trace.go:171","msg":"trace[1520730676] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"100.281797ms","start":"2026-05-07T23:21:26.385542Z","end":"2026-05-07T23:21:26.485824Z","steps":["trace[1520730676] 'process raft request' (duration: 100.052355ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:29.074618Z","caller":"traceutil/trace.go:171","msg":"trace[250479623] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"126.962645ms","start":"2026-05-07T23:21:28.947628Z","end":"2026-05-07T23:21:29.074591Z","steps":["trace[250479623] 'read index received' (duration: 126.826264ms)","trace[250479623] 'applied index is now lower than readState.Index' (duration: 135.631µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:29.074785Z","caller":"traceutil/trace.go:171","msg":"trace[1740245143] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"143.988402ms","start":"2026-05-07T23:21:28.930766Z","end":"2026-05-07T23:21:29.074755Z","steps":["trace[1740245143] 'process raft request' (duration: 143.66777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:29.07489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.166676ms","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-05-07T23:21:29.07493Z","caller":"traceutil/trace.go:171","msg":"trace[1782623883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:345; }","duration":"127.327187ms","start":"2026-05-07T23:21:28.94759Z","end":"2026-05-07T23:21:29.074917Z","steps":["trace[1782623883] 'agreement among raft nodes before linearized reading' (duration: 127.098715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:29.287811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.233284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-07T23:21:29.287897Z","caller":"traceutil/trace.go:171","msg":"trace[1419105001] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:346; }","duration":"108.373126ms","start":"2026-05-07T23:21:29.179505Z","end":"2026-05-07T23:21:29.287879Z","steps":["trace[1419105001] 'range keys from in-memory index tree' (duration: 108.128504ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:29.675588Z","caller":"traceutil/trace.go:171","msg":"trace[1892145781] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"295.672715ms","start":"2026-05-07T23:21:29.379888Z","end":"2026-05-07T23:21:29.675561Z","steps":["trace[1892145781] 'read index received' (duration: 258.867336ms)","trace[1892145781] 'applied index is now lower than readState.Index' (duration: 36.804099ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:29.675674Z","caller":"traceutil/trace.go:171","msg":"trace[489288035] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"341.526381ms","start":"2026-05-07T23:21:29.334114Z","end":"2026-05-07T23:21:29.67564Z","steps":["trace[489288035] 'process raft request' (duration: 304.695611ms)","trace[489288035] 'compare' (duration: 36.646888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:29.675741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.832786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-07T23:21:29.675888Z","caller":"traceutil/trace.go:171","msg":"trace[487218185] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:351; }","duration":"295.970427ms","start":"2026-05-07T23:21:29.379839Z","end":"2026-05-07T23:21:29.675809Z","steps":["trace[487218185] 'agreement among raft nodes before linearized reading' (duration: 295.824676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:29.675931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.537354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-07T23:21:29.675993Z","caller":"traceutil/trace.go:171","msg":"trace[1248616238] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:351; }","duration":"146.654075ms","start":"2026-05-07T23:21:29.529322Z","end":"2026-05-07T23:21:29.675976Z","steps":["trace[1248616238] 'agreement among raft nodes before linearized reading' (duration: 146.511424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:29.675769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.574518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-07T23:21:29.676052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.388976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-07T23:21:29.676106Z","caller":"traceutil/trace.go:171","msg":"trace[967768352] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:351; }","duration":"246.956351ms","start":"2026-05-07T23:21:29.429136Z","end":"2026-05-07T23:21:29.676092Z","steps":["trace[967768352] 'agreement among raft nodes before linearized reading' (duration: 246.577279ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:29.676123Z","caller":"traceutil/trace.go:171","msg":"trace[1786351875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:351; }","duration":"196.553126ms","start":"2026-05-07T23:21:29.479549Z","end":"2026-05-07T23:21:29.676102Z","steps":["trace[1786351875] 'agreement among raft nodes before linearized reading' (duration: 196.331415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:29.676418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:29.334104Z","time spent":"341.628681ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:29.836386Z","caller":"traceutil/trace.go:171","msg":"trace[1334640071] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"151.893647ms","start":"2026-05-07T23:21:29.684466Z","end":"2026-05-07T23:21:29.83636Z","steps":["trace[1334640071] 'process raft request' (duration: 119.566286ms)","trace[1334640071] 'compare' (duration: 32.14347ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:29.836569Z","caller":"traceutil/trace.go:171","msg":"trace[2057181522] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:360; }","duration":"106.390165ms","start":"2026-05-07T23:21:29.73014Z","end":"2026-05-07T23:21:29.83653Z","steps":["trace[2057181522] 'read index received' (duration: 73.875612ms)","trace[2057181522] 'applied index is now lower than readState.Index' (duration: 32.512992ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:29.836673Z","caller":"traceutil/trace.go:171","msg":"trace[1107067371] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"149.155061ms","start":"2026-05-07T23:21:29.687428Z","end":"2026-05-07T23:21:29.836583Z","steps":["trace[1107067371] 'process raft request' (duration: 148.880309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:29.836825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.640106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-07T23:21:29.836911Z","caller":"traceutil/trace.go:171","msg":"trace[1615581542] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:353; }","duration":"106.790997ms","start":"2026-05-07T23:21:29.730105Z","end":"2026-05-07T23:21:29.836896Z","steps":["trace[1615581542] 'agreement among raft nodes before linearized reading' (duration: 106.588856ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:29.990322Z","caller":"traceutil/trace.go:171","msg":"trace[433081097] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"103.731487ms","start":"2026-05-07T23:21:29.886563Z","end":"2026-05-07T23:21:29.990295Z","steps":["trace[433081097] 'process raft request' (duration: 64.043729ms)","trace[433081097] 'compare' (duration: 39.508017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:30.218348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.218648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:30.218544Z","caller":"traceutil/trace.go:171","msg":"trace[868215768] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"220.086076ms","start":"2026-05-07T23:21:29.998436Z","end":"2026-05-07T23:21:30.218522Z","steps":["trace[868215768] 'process raft request' (duration: 220.019606ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.218766Z","caller":"traceutil/trace.go:171","msg":"trace[467982935] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"220.275747ms","start":"2026-05-07T23:21:29.998318Z","end":"2026-05-07T23:21:30.218593Z","steps":["trace[467982935] 'process raft request' (duration: 97.715357ms)","trace[467982935] 'compare' (duration: 122.082597ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:30.221872Z","caller":"traceutil/trace.go:171","msg":"trace[1383187004] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"219.623654ms","start":"2026-05-07T23:21:30.002227Z","end":"2026-05-07T23:21:30.221851Z","steps":["trace[1383187004] 'process raft request' (duration: 219.368402ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.222014Z","caller":"traceutil/trace.go:171","msg":"trace[769952435] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"214.172369ms","start":"2026-05-07T23:21:30.007808Z","end":"2026-05-07T23:21:30.22198Z","steps":["trace[769952435] 'process raft request' (duration: 213.954508ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.222013Z","caller":"traceutil/trace.go:171","msg":"trace[1394973680] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:368; }","duration":"192.448074ms","start":"2026-05-07T23:21:30.029529Z","end":"2026-05-07T23:21:30.221977Z","steps":["trace[1394973680] 'read index received' (duration: 66.611583ms)","trace[1394973680] 'applied index is now lower than readState.Index' (duration: 125.834201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:30.222233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.683076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-07T23:21:30.222302Z","caller":"traceutil/trace.go:171","msg":"trace[1596577714] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:363; }","duration":"192.784296ms","start":"2026-05-07T23:21:30.029499Z","end":"2026-05-07T23:21:30.222283Z","steps":["trace[1596577714] 'agreement among raft nodes before linearized reading' (duration: 192.580555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:30.222532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.095212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-07T23:21:30.222861Z","caller":"traceutil/trace.go:171","msg":"trace[855899217] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:363; }","duration":"142.192232ms","start":"2026-05-07T23:21:30.080388Z","end":"2026-05-07T23:21:30.222581Z","steps":["trace[855899217] 'agreement among raft nodes before linearized reading' (duration: 142.084051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:30.223429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.637426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T23:21:30.223489Z","caller":"traceutil/trace.go:171","msg":"trace[30637598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:363; }","duration":"126.715277ms","start":"2026-05-07T23:21:30.09676Z","end":"2026-05-07T23:21:30.223475Z","steps":["trace[30637598] 'agreement among raft nodes before linearized reading' (duration: 126.612126ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.44373Z","caller":"traceutil/trace.go:171","msg":"trace[1228648725] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"219.485403ms","start":"2026-05-07T23:21:30.224203Z","end":"2026-05-07T23:21:30.443688Z","steps":["trace[1228648725] 'process raft request' (duration: 137.814255ms)","trace[1228648725] 'compare' (duration: 81.413546ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:30.443752Z","caller":"traceutil/trace.go:171","msg":"trace[902977075] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"217.40372ms","start":"2026-05-07T23:21:30.226313Z","end":"2026-05-07T23:21:30.443716Z","steps":["trace[902977075] 'process raft request' (duration: 217.292009ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.443953Z","caller":"traceutil/trace.go:171","msg":"trace[188382632] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"214.24262ms","start":"2026-05-07T23:21:30.229676Z","end":"2026-05-07T23:21:30.443918Z","steps":["trace[188382632] 'process raft request' (duration: 214.057029ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.443979Z","caller":"traceutil/trace.go:171","msg":"trace[1179930994] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"216.735535ms","start":"2026-05-07T23:21:30.227189Z","end":"2026-05-07T23:21:30.443925Z","steps":["trace[1179930994] 'process raft request' (duration: 216.457833ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.444158Z","caller":"traceutil/trace.go:171","msg":"trace[1289999710] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"216.045071ms","start":"2026-05-07T23:21:30.228098Z","end":"2026-05-07T23:21:30.444143Z","steps":["trace[1289999710] 'process raft request' (duration: 215.587779ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.444287Z","caller":"traceutil/trace.go:171","msg":"trace[1475116865] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"214.167489ms","start":"2026-05-07T23:21:30.230107Z","end":"2026-05-07T23:21:30.444274Z","steps":["trace[1475116865] 'process raft request' (duration: 213.676416ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.444718Z","caller":"traceutil/trace.go:171","msg":"trace[365873894] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"214.25944ms","start":"2026-05-07T23:21:30.230446Z","end":"2026-05-07T23:21:30.444705Z","steps":["trace[365873894] 'process raft request' (duration: 213.367424ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.444735Z","caller":"traceutil/trace.go:171","msg":"trace[1959463302] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:372; }","duration":"110.428876ms","start":"2026-05-07T23:21:30.334278Z","end":"2026-05-07T23:21:30.444707Z","steps":["trace[1959463302] 'read index received' (duration: 27.787783ms)","trace[1959463302] 'applied index is now lower than readState.Index' (duration: 82.639903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:30.445142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.826498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-07T23:21:30.445192Z","caller":"traceutil/trace.go:171","msg":"trace[2011715264] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:370; }","duration":"110.947209ms","start":"2026-05-07T23:21:30.334233Z","end":"2026-05-07T23:21:30.44518Z","steps":["trace[2011715264] 'agreement among raft nodes before linearized reading' (duration: 110.562417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:30.808269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.026355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:30.808368Z","caller":"traceutil/trace.go:171","msg":"trace[9042237] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"358.680066ms","start":"2026-05-07T23:21:30.449672Z","end":"2026-05-07T23:21:30.808352Z","steps":["trace[9042237] 'read index received' (duration: 230.517871ms)","trace[9042237] 'applied index is now lower than readState.Index' (duration: 128.160995ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:30.808432Z","caller":"traceutil/trace.go:171","msg":"trace[312534669] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"360.073074ms","start":"2026-05-07T23:21:30.448347Z","end":"2026-05-07T23:21:30.80842Z","steps":["trace[312534669] 'process raft request' (duration: 231.815848ms)","trace[312534669] 'compare' (duration: 127.874434ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:30.808552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.448314Z","time spent":"360.148216ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:30.808629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.926798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-07T23:21:30.808721Z","caller":"traceutil/trace.go:171","msg":"trace[309259467] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:371; }","duration":"359.065519ms","start":"2026-05-07T23:21:30.449632Z","end":"2026-05-07T23:21:30.808697Z","steps":["trace[309259467] 'agreement among raft nodes before linearized reading' (duration: 358.815188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:30.808778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.449614Z","time spent":"359.15225ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-07T23:21:30.810265Z","caller":"traceutil/trace.go:171","msg":"trace[288449717] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"360.471558ms","start":"2026-05-07T23:21:30.44976Z","end":"2026-05-07T23:21:30.810232Z","steps":["trace[288449717] 'process raft request' (duration: 360.129416ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.810396Z","caller":"traceutil/trace.go:171","msg":"trace[1718801898] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"360.365207ms","start":"2026-05-07T23:21:30.449999Z","end":"2026-05-07T23:21:30.810364Z","steps":["trace[1718801898] 'process raft request' (duration: 360.154185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:30.810444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.449746Z","time spent":"360.611128ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:21:30.810547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.449991Z","time spent":"360.489797ms","remote":"127.0.0.1:35980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:21:30.81084Z","caller":"traceutil/trace.go:171","msg":"trace[946733780] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"360.768019ms","start":"2026-05-07T23:21:30.45006Z","end":"2026-05-07T23:21:30.810829Z","steps":["trace[946733780] 'process raft request' (duration: 360.204316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:30.810889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.450054Z","time spent":"360.8116ms","remote":"127.0.0.1:35980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:21:30.810942Z","caller":"traceutil/trace.go:171","msg":"trace[1737199077] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"358.796167ms","start":"2026-05-07T23:21:30.452119Z","end":"2026-05-07T23:21:30.810916Z","steps":["trace[1737199077] 'process raft request' (duration: 358.434705ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.810947Z","caller":"traceutil/trace.go:171","msg":"trace[165694251] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"359.786173ms","start":"2026-05-07T23:21:30.451051Z","end":"2026-05-07T23:21:30.810838Z","steps":["trace[165694251] 'process raft request' (duration: 359.321071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:30.811094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.452113Z","time spent":"358.894888ms","remote":"127.0.0.1:36358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:30.811188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.801583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T23:21:30.811233Z","caller":"traceutil/trace.go:171","msg":"trace[1235749796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"114.888713ms","start":"2026-05-07T23:21:30.696334Z","end":"2026-05-07T23:21:30.811223Z","steps":["trace[1235749796] 'agreement among raft nodes before linearized reading' (duration: 114.790552ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:30.811424Z","caller":"traceutil/trace.go:171","msg":"trace[961639149] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"357.097587ms","start":"2026-05-07T23:21:30.454308Z","end":"2026-05-07T23:21:30.811406Z","steps":["trace[961639149] 'process raft request' (duration: 356.416363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:30.811527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.454299Z","time spent":"357.176517ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:30.811786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.451034Z","time spent":"360.587318ms","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:31.844434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.646816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:31.844641Z","caller":"traceutil/trace.go:171","msg":"trace[987311699] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.026560613s","start":"2026-05-07T23:21:30.818051Z","end":"2026-05-07T23:21:31.844612Z","steps":["trace[987311699] 'process raft request' (duration: 589.680596ms)","trace[987311699] 'compare' (duration: 436.459775ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:31.844708Z","caller":"traceutil/trace.go:171","msg":"trace[1755196901] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"1.026422373s","start":"2026-05-07T23:21:30.818248Z","end":"2026-05-07T23:21:31.84467Z","steps":["trace[1755196901] 'process raft request' (duration: 1.026278452s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:31.844776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.818031Z","time spent":"1.026676844s","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:21:31.84483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.81824Z","time spent":"1.026535843s","remote":"127.0.0.1:35980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:21:31.844812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015210732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-07T23:21:31.844726Z","caller":"traceutil/trace.go:171","msg":"trace[1305663129] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:386; }","duration":"1.015117852s","start":"2026-05-07T23:21:30.829592Z","end":"2026-05-07T23:21:31.84471Z","steps":["trace[1305663129] 'read index received' (duration: 67.308947ms)","trace[1305663129] 'applied index is now lower than readState.Index' (duration: 947.807995ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:31.844972Z","caller":"traceutil/trace.go:171","msg":"trace[1490339641] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:383; }","duration":"1.015344793s","start":"2026-05-07T23:21:30.829569Z","end":"2026-05-07T23:21:31.844914Z","steps":["trace[1490339641] 'agreement among raft nodes before linearized reading' (duration: 1.015186112s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:31.84506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.829563Z","time spent":"1.015466904s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3730,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-05-07T23:21:31.845165Z","caller":"traceutil/trace.go:171","msg":"trace[779796009] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"1.020901178s","start":"2026-05-07T23:21:30.824252Z","end":"2026-05-07T23:21:31.845153Z","steps":["trace[779796009] 'process raft request' (duration: 1.020391514s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:31.845212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.190587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-05-07T23:21:31.845249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.824227Z","time spent":"1.020975959s","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:31.845265Z","caller":"traceutil/trace.go:171","msg":"trace[194178295] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"1.021874564s","start":"2026-05-07T23:21:30.823381Z","end":"2026-05-07T23:21:31.845255Z","steps":["trace[194178295] 'process raft request' (duration: 1.02119525s)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:31.845276Z","caller":"traceutil/trace.go:171","msg":"trace[1456609661] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:383; }","duration":"869.302017ms","start":"2026-05-07T23:21:30.975956Z","end":"2026-05-07T23:21:31.845258Z","steps":["trace[1456609661] 'agreement among raft nodes before linearized reading' (duration: 869.129086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:31.845326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.823361Z","time spent":"1.021927704s","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:31.845342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.975937Z","time spent":"869.378448ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-05-07T23:21:31.845403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.68269ms","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-05-07T23:21:31.845436Z","caller":"traceutil/trace.go:171","msg":"trace[1948872588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:383; }","duration":"733.7404ms","start":"2026-05-07T23:21:31.111683Z","end":"2026-05-07T23:21:31.845424Z","steps":["trace[1948872588] 'agreement among raft nodes before linearized reading' (duration: 733.67067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:31.845454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.111667Z","time spent":"733.78331ms","remote":"127.0.0.1:36154","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-05-07T23:21:31.845441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.993055ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:21:31.845537Z","caller":"traceutil/trace.go:171","msg":"trace[856948787] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:383; }","duration":"869.088626ms","start":"2026-05-07T23:21:30.976438Z","end":"2026-05-07T23:21:31.845526Z","steps":["trace[856948787] 'agreement among raft nodes before linearized reading' (duration: 868.974725ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:31.84535Z","caller":"traceutil/trace.go:171","msg":"trace[1075595573] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"1.015892067s","start":"2026-05-07T23:21:30.829446Z","end":"2026-05-07T23:21:31.845338Z","steps":["trace[1075595573] 'process raft request' (duration: 1.015234183s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:31.845812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:30.829424Z","time spent":"1.016354789s","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:21:33.216333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009156011s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:33.21645Z","caller":"traceutil/trace.go:171","msg":"trace[1783801274] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"1.365735906s","start":"2026-05-07T23:21:31.850694Z","end":"2026-05-07T23:21:33.21643Z","steps":["trace[1783801274] 'process raft request' (duration: 356.414224ms)","trace[1783801274] 'compare' (duration: 1.00896748s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:33.216635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.850682Z","time spent":"1.365921927s","remote":"127.0.0.1:35980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:21:33.83428Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.31302118s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T23:21:33.874858Z","caller":"traceutil/trace.go:171","msg":"trace[906352753] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"2.024077628s","start":"2026-05-07T23:21:31.850751Z","end":"2026-05-07T23:21:33.874829Z","steps":["trace[906352753] 'process raft request' (duration: 1.983718291s)","trace[906352753] 'compare' (duration: 40.221246ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:33.87484Z","caller":"traceutil/trace.go:171","msg":"trace[932783153] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"2.023973028s","start":"2026-05-07T23:21:31.850848Z","end":"2026-05-07T23:21:33.874821Z","steps":["trace[932783153] 'process raft request' (duration: 2.023922947s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.874991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.850745Z","time spent":"2.024178168s","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:33.87566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.850825Z","time spent":"2.024108448s","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:21:33.875875Z","caller":"traceutil/trace.go:171","msg":"trace[1514995565] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"2.023474874s","start":"2026-05-07T23:21:31.852377Z","end":"2026-05-07T23:21:33.875852Z","steps":["trace[1514995565] 'process raft request' (duration: 2.022426298s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.87599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.852367Z","time spent":"2.023578985s","remote":"127.0.0.1:35980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:21:33.876071Z","caller":"traceutil/trace.go:171","msg":"trace[1780969466] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"2.022371417s","start":"2026-05-07T23:21:31.853686Z","end":"2026-05-07T23:21:33.876057Z","steps":["trace[1780969466] 'process raft request' (duration: 2.022010285s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.876134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.853678Z","time spent":"2.022432348s","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:21:33.876415Z","caller":"traceutil/trace.go:171","msg":"trace[1773642530] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"2.022490678s","start":"2026-05-07T23:21:31.853916Z","end":"2026-05-07T23:21:33.876406Z","steps":["trace[1773642530] 'process raft request' (duration: 2.021938675s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.876477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.853899Z","time spent":"2.022544869s","remote":"127.0.0.1:36340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:33.877508Z","caller":"traceutil/trace.go:171","msg":"trace[801506104] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:392; }","duration":"2.026673584s","start":"2026-05-07T23:21:31.850818Z","end":"2026-05-07T23:21:33.877491Z","steps":["trace[801506104] 'read index received' (duration: 356.257213ms)","trace[801506104] 'applied index is now lower than readState.Index' (duration: 1.670415481s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:33.877695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.026868565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-bsc5l\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-07T23:21:33.877762Z","caller":"traceutil/trace.go:171","msg":"trace[1789201945] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-bsc5l; range_end:; response_count:1; response_revision:390; }","duration":"2.026944946s","start":"2026-05-07T23:21:31.850807Z","end":"2026-05-07T23:21:33.877752Z","steps":["trace[1789201945] 'agreement among raft nodes before linearized reading' (duration: 2.026837895s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.877801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.850803Z","time spent":"2.026991046s","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3463,"request content":"key:\"/registry/pods/kube-system/kube-proxy-bsc5l\" "} {"level":"warn","ts":"2026-05-07T23:21:33.877841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42890388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-07T23:21:33.877858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.67596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T23:21:33.877893Z","caller":"traceutil/trace.go:171","msg":"trace[267959853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"1.42899479s","start":"2026-05-07T23:21:32.448885Z","end":"2026-05-07T23:21:33.87788Z","steps":["trace[267959853] 'agreement among raft nodes before linearized reading' (duration: 1.42890169s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.877904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.514279ms","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-05-07T23:21:33.877936Z","caller":"traceutil/trace.go:171","msg":"trace[207216611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"976.589051ms","start":"2026-05-07T23:21:32.901338Z","end":"2026-05-07T23:21:33.877927Z","steps":["trace[207216611] 'agreement among raft nodes before linearized reading' (duration: 976.52296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.877973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:32.901317Z","time spent":"976.650861ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T23:21:33.877978Z","caller":"traceutil/trace.go:171","msg":"trace[1339849384] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"2.019046617s","start":"2026-05-07T23:21:31.858904Z","end":"2026-05-07T23:21:33.877951Z","steps":["trace[1339849384] 'process raft request' (duration: 2.017007824s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.877935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:32.448866Z","time spent":"1.429059972s","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T23:21:33.877898Z","caller":"traceutil/trace.go:171","msg":"trace[149955813] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:390; }","duration":"793.75169ms","start":"2026-05-07T23:21:33.084135Z","end":"2026-05-07T23:21:33.877887Z","steps":["trace[149955813] 'agreement among raft nodes before linearized reading' (duration: 793.68111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:33.878082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:33.084117Z","time spent":"793.959672ms","remote":"127.0.0.1:35940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-05-07T23:21:33.878116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:31.858896Z","time spent":"2.019155777s","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:34.270278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.019838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:34.270385Z","caller":"traceutil/trace.go:171","msg":"trace[1213723629] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"340.133881ms","start":"2026-05-07T23:21:33.930232Z","end":"2026-05-07T23:21:34.270366Z","steps":["trace[1213723629] 'read index received' (duration: 55.858171ms)","trace[1213723629] 'applied index is now lower than readState.Index' (duration: 284.27399ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:34.270476Z","caller":"traceutil/trace.go:171","msg":"trace[1906982040] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"380.543199ms","start":"2026-05-07T23:21:33.889921Z","end":"2026-05-07T23:21:34.270464Z","steps":["trace[1906982040] 'process raft request' (duration: 96.246239ms)","trace[1906982040] 'compare' (duration: 283.721746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:34.27054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:33.889914Z","time spent":"380.595489ms","remote":"127.0.0.1:35940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:34.270782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.555785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-05-07T23:21:34.270813Z","caller":"traceutil/trace.go:171","msg":"trace[173552214] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:399; }","duration":"340.627335ms","start":"2026-05-07T23:21:33.930176Z","end":"2026-05-07T23:21:34.270803Z","steps":["trace[173552214] 'agreement among raft nodes before linearized reading' (duration: 340.532404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:34.270843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:33.930107Z","time spent":"340.729665ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5360,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-07T23:21:34.270874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.234234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-07T23:21:34.270948Z","caller":"traceutil/trace.go:171","msg":"trace[1060143539] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:399; }","duration":"337.351795ms","start":"2026-05-07T23:21:33.933578Z","end":"2026-05-07T23:21:34.27093Z","steps":["trace[1060143539] 'agreement among raft nodes before linearized reading' (duration: 337.180504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:34.270993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:33.933569Z","time spent":"337.415155ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3798,"request content":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" "} {"level":"info","ts":"2026-05-07T23:21:34.540921Z","caller":"traceutil/trace.go:171","msg":"trace[2006873267] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"261.878903ms","start":"2026-05-07T23:21:34.279011Z","end":"2026-05-07T23:21:34.540889Z","steps":["trace[2006873267] 'process raft request' (duration: 207.042529ms)","trace[2006873267] 'compare' (duration: 54.687643ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:34.719672Z","caller":"traceutil/trace.go:171","msg":"trace[1414867639] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"439.954676ms","start":"2026-05-07T23:21:34.279687Z","end":"2026-05-07T23:21:34.719641Z","steps":["trace[1414867639] 'process raft request' (duration: 383.995635ms)","trace[1414867639] 'compare' (duration: 55.82311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:34.719958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:34.279679Z","time spent":"440.067797ms","remote":"127.0.0.1:36372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:35.046964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.841165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:35.047088Z","caller":"traceutil/trace.go:171","msg":"trace[513432867] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"210.816781ms","start":"2026-05-07T23:21:34.836251Z","end":"2026-05-07T23:21:35.047068Z","steps":["trace[513432867] 'process raft request' (duration: 74.805955ms)","trace[513432867] 'compare' (duration: 135.699584ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:35.475134Z","caller":"traceutil/trace.go:171","msg":"trace[1751491697] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"109.805775ms","start":"2026-05-07T23:21:35.365302Z","end":"2026-05-07T23:21:35.475108Z","steps":["trace[1751491697] 'process raft request' (duration: 109.657734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:38.495142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471726117279734144,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:21:38.528169Z","caller":"traceutil/trace.go:171","msg":"trace[1952002283] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"533.412777ms","start":"2026-05-07T23:21:37.994734Z","end":"2026-05-07T23:21:38.528147Z","steps":["trace[1952002283] 'read index received' (duration: 533.294986ms)","trace[1952002283] 'applied index is now lower than readState.Index' (duration: 116.841µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:38.52833Z","caller":"traceutil/trace.go:171","msg":"trace[2129025891] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"624.898296ms","start":"2026-05-07T23:21:37.90342Z","end":"2026-05-07T23:21:38.528318Z","steps":["trace[2129025891] 'process raft request' (duration: 624.585004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:38.528426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:37.903403Z","time spent":"624.957716ms","remote":"127.0.0.1:36154","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-05-07T23:21:38.528904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.625238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T23:21:38.528943Z","caller":"traceutil/trace.go:171","msg":"trace[278825426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"534.234991ms","start":"2026-05-07T23:21:37.994698Z","end":"2026-05-07T23:21:38.528932Z","steps":["trace[278825426] 'agreement among raft nodes before linearized reading' (duration: 533.619328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:38.528966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:37.994681Z","time spent":"534.279872ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:21:38.969315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.863526ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-07T23:21:38.969661Z","caller":"traceutil/trace.go:171","msg":"trace[1356436416] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"441.348257ms","start":"2026-05-07T23:21:38.528275Z","end":"2026-05-07T23:21:38.969623Z","steps":["trace[1356436416] 'read index received' (duration: 259.168339ms)","trace[1356436416] 'applied index is now lower than readState.Index' (duration: 182.176548ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:38.969733Z","caller":"traceutil/trace.go:171","msg":"trace[246830579] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"437.04573ms","start":"2026-05-07T23:21:38.532649Z","end":"2026-05-07T23:21:38.969695Z","steps":["trace[246830579] 'process raft request' (duration: 436.833449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:38.96984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.392591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T23:21:38.969889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:38.532631Z","time spent":"437.161991ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:38.969904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.069755ms","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-05-07T23:21:38.96993Z","caller":"traceutil/trace.go:171","msg":"trace[122686358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"974.518082ms","start":"2026-05-07T23:21:37.995392Z","end":"2026-05-07T23:21:38.969911Z","steps":["trace[122686358] 'agreement among raft nodes before linearized reading' (duration: 974.24967ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:38.969966Z","caller":"traceutil/trace.go:171","msg":"trace[1001858752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"975.173296ms","start":"2026-05-07T23:21:37.994774Z","end":"2026-05-07T23:21:38.969947Z","steps":["trace[1001858752] 'agreement among raft nodes before linearized reading' (duration: 975.040515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:38.969981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:37.995384Z","time spent":"974.588192ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:21:38.970025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:37.994748Z","time spent":"975.263636ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:21:38.970145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.709752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:21:38.970177Z","caller":"traceutil/trace.go:171","msg":"trace[227885099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"393.768952ms","start":"2026-05-07T23:21:38.5764Z","end":"2026-05-07T23:21:38.970169Z","steps":["trace[227885099] 'agreement among raft nodes before linearized reading' (duration: 393.720992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:38.970202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:38.576387Z","time spent":"393.809852ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:21:43.253561Z","caller":"traceutil/trace.go:171","msg":"trace[629230473] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"160.165779ms","start":"2026-05-07T23:21:43.093372Z","end":"2026-05-07T23:21:43.253538Z","steps":["trace[629230473] 'process raft request' (duration: 160.124857ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:43.253675Z","caller":"traceutil/trace.go:171","msg":"trace[617561678] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"160.389181ms","start":"2026-05-07T23:21:43.093227Z","end":"2026-05-07T23:21:43.253616Z","steps":["trace[617561678] 'process raft request' (duration: 160.176381ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:48.947387Z","caller":"traceutil/trace.go:171","msg":"trace[424771206] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"172.670306ms","start":"2026-05-07T23:21:48.774685Z","end":"2026-05-07T23:21:48.947355Z","steps":["trace[424771206] 'process raft request' (duration: 172.460956ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:58.690887Z","caller":"traceutil/trace.go:171","msg":"trace[253262393] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"113.914588ms","start":"2026-05-07T23:21:58.57694Z","end":"2026-05-07T23:21:58.690855Z","steps":["trace[253262393] 'read index received' (duration: 110.448951ms)","trace[253262393] 'applied index is now lower than readState.Index' (duration: 3.463877ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:58.690989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.035083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:21:58.691045Z","caller":"traceutil/trace.go:171","msg":"trace[473883125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"114.142968ms","start":"2026-05-07T23:21:58.576888Z","end":"2026-05-07T23:21:58.691031Z","steps":["trace[473883125] 'agreement among raft nodes before linearized reading' (duration: 114.040174ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:00.709816Z","caller":"traceutil/trace.go:171","msg":"trace[92298643] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"148.906951ms","start":"2026-05-07T23:22:00.560888Z","end":"2026-05-07T23:22:00.709795Z","steps":["trace[92298643] 'process raft request' (duration: 148.848339ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:00.710033Z","caller":"traceutil/trace.go:171","msg":"trace[1261903956] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"187.403161ms","start":"2026-05-07T23:22:00.522618Z","end":"2026-05-07T23:22:00.710021Z","steps":["trace[1261903956] 'read index received' (duration: 118.118269ms)","trace[1261903956] 'applied index is now lower than readState.Index' (duration: 69.283622ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:00.710039Z","caller":"traceutil/trace.go:171","msg":"trace[662428838] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"191.752071ms","start":"2026-05-07T23:22:00.51826Z","end":"2026-05-07T23:22:00.710012Z","steps":["trace[662428838] 'process raft request' (duration: 122.465409ms)","trace[662428838] 'compare' (duration: 68.725419ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:22:00.710363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.723835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-05-07T23:22:00.71041Z","caller":"traceutil/trace.go:171","msg":"trace[2136474184] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"187.797148ms","start":"2026-05-07T23:22:00.5226Z","end":"2026-05-07T23:22:00.710397Z","steps":["trace[2136474184] 'agreement among raft nodes before linearized reading' (duration: 187.636471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:00.710573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.008383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:00.710628Z","caller":"traceutil/trace.go:171","msg":"trace[1682569517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"132.100087ms","start":"2026-05-07T23:22:00.578512Z","end":"2026-05-07T23:22:00.710612Z","steps":["trace[1682569517] 'agreement among raft nodes before linearized reading' (duration: 132.015423ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:01.44341Z","caller":"traceutil/trace.go:171","msg":"trace[608290958] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"100.473132ms","start":"2026-05-07T23:22:01.342908Z","end":"2026-05-07T23:22:01.443381Z","steps":["trace[608290958] 'read index received' (duration: 100.301355ms)","trace[608290958] 'applied index is now lower than readState.Index' (duration: 170.757µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:01.443455Z","caller":"traceutil/trace.go:171","msg":"trace[1790398414] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"188.201763ms","start":"2026-05-07T23:22:01.255224Z","end":"2026-05-07T23:22:01.443426Z","steps":["trace[1790398414] 'process raft request' (duration: 187.990135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:01.443799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.864357ms","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-05-07T23:22:01.443864Z","caller":"traceutil/trace.go:171","msg":"trace[1016503212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"100.975652ms","start":"2026-05-07T23:22:01.342873Z","end":"2026-05-07T23:22:01.443849Z","steps":["trace[1016503212] 'agreement among raft nodes before linearized reading' (duration: 100.632728ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:01.562043Z","caller":"traceutil/trace.go:171","msg":"trace[1568046391] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"114.211543ms","start":"2026-05-07T23:22:01.447809Z","end":"2026-05-07T23:22:01.56202Z","steps":["trace[1568046391] 'process raft request' (duration: 112.367018ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:01.562369Z","caller":"traceutil/trace.go:171","msg":"trace[1289151221] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"114.455762ms","start":"2026-05-07T23:22:01.447896Z","end":"2026-05-07T23:22:01.562352Z","steps":["trace[1289151221] 'process raft request' (duration: 114.367279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:05.406176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.275343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:22:05.406284Z","caller":"traceutil/trace.go:171","msg":"trace[759255839] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"241.691006ms","start":"2026-05-07T23:22:05.164574Z","end":"2026-05-07T23:22:05.406265Z","steps":["trace[759255839] 'process raft request' (duration: 89.257477ms)","trace[759255839] 'compare' (duration: 152.026343ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:05.654806Z","caller":"traceutil/trace.go:171","msg":"trace[168292073] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"170.991572ms","start":"2026-05-07T23:22:05.483782Z","end":"2026-05-07T23:22:05.654774Z","steps":["trace[168292073] 'read index received' (duration: 152.090986ms)","trace[168292073] 'applied index is now lower than readState.Index' (duration: 18.899006ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:05.654937Z","caller":"traceutil/trace.go:171","msg":"trace[1740695466] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"194.204107ms","start":"2026-05-07T23:22:05.460715Z","end":"2026-05-07T23:22:05.654919Z","steps":["trace[1740695466] 'process raft request' (duration: 175.127533ms)","trace[1740695466] 'compare' (duration: 18.617986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:22:05.655184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.385567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fz8fl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T23:22:05.655224Z","caller":"traceutil/trace.go:171","msg":"trace[2011936419] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fz8fl; range_end:; response_count:1; response_revision:634; }","duration":"171.482921ms","start":"2026-05-07T23:22:05.48373Z","end":"2026-05-07T23:22:05.655213Z","steps":["trace[2011936419] 'agreement among raft nodes before linearized reading' (duration: 171.333125ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:06.028468Z","caller":"traceutil/trace.go:171","msg":"trace[403840823] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"373.559144ms","start":"2026-05-07T23:22:05.654881Z","end":"2026-05-07T23:22:06.02844Z","steps":["trace[403840823] 'read index received' (duration: 372.894888ms)","trace[403840823] 'applied index is now lower than readState.Index' (duration: 663.476µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:06.028522Z","caller":"traceutil/trace.go:171","msg":"trace[2093117888] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"544.435802ms","start":"2026-05-07T23:22:05.484043Z","end":"2026-05-07T23:22:06.028478Z","steps":["trace[2093117888] 'process raft request' (duration: 543.86586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:06.028597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.652069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:06.028633Z","caller":"traceutil/trace.go:171","msg":"trace[313390360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"449.744312ms","start":"2026-05-07T23:22:05.578878Z","end":"2026-05-07T23:22:06.028622Z","steps":["trace[313390360] 'agreement among raft nodes before linearized reading' (duration: 449.654859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:06.028666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:05.578648Z","time spent":"450.010952ms","remote":"127.0.0.1:35912","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-05-07T23:22:06.02871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:05.484017Z","time spent":"544.593678ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:22:06.028727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.754841ms","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-05-07T23:22:06.028805Z","caller":"traceutil/trace.go:171","msg":"trace[924435927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"441.908186ms","start":"2026-05-07T23:22:05.586874Z","end":"2026-05-07T23:22:06.028783Z","steps":["trace[924435927] 'agreement among raft nodes before linearized reading' (duration: 441.662977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:06.02885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:05.586849Z","time spent":"441.992609ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:22:06.029201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.942778ms","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-05-07T23:22:06.029262Z","caller":"traceutil/trace.go:171","msg":"trace[641638409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"442.043232ms","start":"2026-05-07T23:22:05.587201Z","end":"2026-05-07T23:22:06.029244Z","steps":["trace[641638409] 'agreement among raft nodes before linearized reading' (duration: 441.794852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:06.029318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:05.587189Z","time spent":"442.117864ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T23:22:06.080007Z","caller":"traceutil/trace.go:171","msg":"trace[1039522912] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"411.638824ms","start":"2026-05-07T23:22:05.668352Z","end":"2026-05-07T23:22:06.079991Z","steps":["trace[1039522912] 'process raft request' (duration: 411.356854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:06.080107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:05.668326Z","time spent":"411.726168ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:22:07.03667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.67272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:22:07.036907Z","caller":"traceutil/trace.go:171","msg":"trace[1016437082] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.003190122s","start":"2026-05-07T23:22:06.033688Z","end":"2026-05-07T23:22:07.036878Z","steps":["trace[1016437082] 'process raft request' (duration: 113.243482ms)","trace[1016437082] 'compare' (duration: 889.560595ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:07.036977Z","caller":"traceutil/trace.go:171","msg":"trace[350973906] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"997.331196ms","start":"2026-05-07T23:22:06.039625Z","end":"2026-05-07T23:22:07.036956Z","steps":["trace[350973906] 'process raft request' (duration: 997.139969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:07.037039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:06.0396Z","time spent":"997.407749ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:22:07.037038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:06.033666Z","time spent":"1.003306527s","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:22:07.037231Z","caller":"traceutil/trace.go:171","msg":"trace[126006286] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:656; }","duration":"457.791267ms","start":"2026-05-07T23:22:06.579422Z","end":"2026-05-07T23:22:07.037213Z","steps":["trace[126006286] 'read index received' (duration: 108.682996ms)","trace[126006286] 'applied index is now lower than readState.Index' (duration: 349.107381ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:07.037272Z","caller":"traceutil/trace.go:171","msg":"trace[1408803334] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"818.511359ms","start":"2026-05-07T23:22:06.218744Z","end":"2026-05-07T23:22:07.037255Z","steps":["trace[1408803334] 'process raft request' (duration: 818.406385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:07.037324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.89081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:07.037336Z","caller":"traceutil/trace.go:171","msg":"trace[326707012] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"937.542163ms","start":"2026-05-07T23:22:06.099783Z","end":"2026-05-07T23:22:07.037326Z","steps":["trace[326707012] 'process raft request' (duration: 937.123127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:07.037353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:06.218721Z","time spent":"818.590703ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:22:07.037362Z","caller":"traceutil/trace.go:171","msg":"trace[46668218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"457.969264ms","start":"2026-05-07T23:22:06.57938Z","end":"2026-05-07T23:22:07.037349Z","steps":["trace[46668218] 'agreement among raft nodes before linearized reading' (duration: 457.903962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:07.03739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:06.099725Z","time spent":"937.639397ms","remote":"127.0.0.1:36392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:22:07.037396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:06.579359Z","time spent":"458.029866ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:22:07.615496Z","caller":"traceutil/trace.go:171","msg":"trace[1677022989] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"195.164741ms","start":"2026-05-07T23:22:07.420286Z","end":"2026-05-07T23:22:07.615451Z","steps":["trace[1677022989] 'process raft request' (duration: 194.562458ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:07.658276Z","caller":"traceutil/trace.go:171","msg":"trace[915995775] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"189.886149ms","start":"2026-05-07T23:22:07.468369Z","end":"2026-05-07T23:22:07.658255Z","steps":["trace[915995775] 'process raft request' (duration: 189.36903ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:07.658339Z","caller":"traceutil/trace.go:171","msg":"trace[1339129895] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"165.369216ms","start":"2026-05-07T23:22:07.492945Z","end":"2026-05-07T23:22:07.658315Z","steps":["trace[1339129895] 'process raft request' (duration: 165.23004ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:07.658264Z","caller":"traceutil/trace.go:171","msg":"trace[1824538988] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"166.693486ms","start":"2026-05-07T23:22:07.491523Z","end":"2026-05-07T23:22:07.658217Z","steps":["trace[1824538988] 'read index received' (duration: 123.356016ms)","trace[1824538988] 'applied index is now lower than readState.Index' (duration: 43.33688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:22:07.658642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.080711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fz8fl\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-05-07T23:22:07.658723Z","caller":"traceutil/trace.go:171","msg":"trace[1973195939] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fz8fl; range_end:; response_count:1; response_revision:643; }","duration":"167.226497ms","start":"2026-05-07T23:22:07.491483Z","end":"2026-05-07T23:22:07.65871Z","steps":["trace[1973195939] 'agreement among raft nodes before linearized reading' (duration: 166.824321ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:09.239765Z","caller":"traceutil/trace.go:171","msg":"trace[399118818] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"199.113869ms","start":"2026-05-07T23:22:09.040629Z","end":"2026-05-07T23:22:09.239743Z","steps":["trace[399118818] 'read index received' (duration: 192.821385ms)","trace[399118818] 'applied index is now lower than readState.Index' (duration: 6.291474ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:09.239869Z","caller":"traceutil/trace.go:171","msg":"trace[184712374] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"598.076731ms","start":"2026-05-07T23:22:08.641764Z","end":"2026-05-07T23:22:09.23984Z","steps":["trace[184712374] 'process raft request' (duration: 591.805088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:09.239969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.324757ms","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-05-07T23:22:09.24001Z","caller":"traceutil/trace.go:171","msg":"trace[48379274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"199.377229ms","start":"2026-05-07T23:22:09.040621Z","end":"2026-05-07T23:22:09.239998Z","steps":["trace[48379274] 'agreement among raft nodes before linearized reading' (duration: 199.226343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:09.240004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:08.641734Z","time spent":"598.199705ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:22:09.240051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.011398ms","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-05-07T23:22:09.240087Z","caller":"traceutil/trace.go:171","msg":"trace[295979455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"198.081581ms","start":"2026-05-07T23:22:09.041993Z","end":"2026-05-07T23:22:09.240075Z","steps":["trace[295979455] 'agreement among raft nodes before linearized reading' (duration: 197.954576ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:13.450926Z","caller":"traceutil/trace.go:171","msg":"trace[1803848434] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"107.039418ms","start":"2026-05-07T23:22:13.343862Z","end":"2026-05-07T23:22:13.450901Z","steps":["trace[1803848434] 'process raft request' (duration: 106.728777ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:13.596415Z","caller":"traceutil/trace.go:171","msg":"trace[1376535998] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"119.440378ms","start":"2026-05-07T23:22:13.476955Z","end":"2026-05-07T23:22:13.596395Z","steps":["trace[1376535998] 'process raft request' (duration: 119.327314ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:13.662592Z","caller":"traceutil/trace.go:171","msg":"trace[1552374620] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"185.456626ms","start":"2026-05-07T23:22:13.477101Z","end":"2026-05-07T23:22:13.662558Z","steps":["trace[1552374620] 'process raft request' (duration: 184.946588ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:13.662614Z","caller":"traceutil/trace.go:171","msg":"trace[171656794] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"138.57863ms","start":"2026-05-07T23:22:13.524015Z","end":"2026-05-07T23:22:13.662594Z","steps":["trace[171656794] 'process raft request' (duration: 138.392543ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:13.662883Z","caller":"traceutil/trace.go:171","msg":"trace[642865554] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:756; }","duration":"113.713455ms","start":"2026-05-07T23:22:13.549147Z","end":"2026-05-07T23:22:13.662861Z","steps":["trace[642865554] 'read index received' (duration: 47.565382ms)","trace[642865554] 'applied index is now lower than readState.Index' (duration: 66.146543ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:13.662993Z","caller":"traceutil/trace.go:171","msg":"trace[1261659966] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"113.142124ms","start":"2026-05-07T23:22:13.549835Z","end":"2026-05-07T23:22:13.662977Z","steps":["trace[1261659966] 'process raft request' (duration: 112.718009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:13.663157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.985105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fz8fl\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-05-07T23:22:13.663218Z","caller":"traceutil/trace.go:171","msg":"trace[534728529] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fz8fl; range_end:; response_count:1; response_revision:737; }","duration":"114.116099ms","start":"2026-05-07T23:22:13.549086Z","end":"2026-05-07T23:22:13.663202Z","steps":["trace[534728529] 'agreement among raft nodes before linearized reading' (duration: 113.902672ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:13.790086Z","caller":"traceutil/trace.go:171","msg":"trace[1989197541] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"127.09535ms","start":"2026-05-07T23:22:13.662964Z","end":"2026-05-07T23:22:13.790059Z","steps":["trace[1989197541] 'read index received' (duration: 117.073744ms)","trace[1989197541] 'applied index is now lower than readState.Index' (duration: 10.019656ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:13.790107Z","caller":"traceutil/trace.go:171","msg":"trace[1238944036] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"187.574082ms","start":"2026-05-07T23:22:13.602504Z","end":"2026-05-07T23:22:13.790078Z","steps":["trace[1238944036] 'process raft request' (duration: 177.589507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:13.790206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.52781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:13.790274Z","caller":"traceutil/trace.go:171","msg":"trace[1422549627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"212.639474ms","start":"2026-05-07T23:22:13.577618Z","end":"2026-05-07T23:22:13.790257Z","steps":["trace[1422549627] 'agreement among raft nodes before linearized reading' (duration: 212.53156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:13.790326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.934584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-05-07T23:22:13.79039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.850609ms","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-05-07T23:22:13.790425Z","caller":"traceutil/trace.go:171","msg":"trace[1092648411] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"143.066289ms","start":"2026-05-07T23:22:13.647337Z","end":"2026-05-07T23:22:13.790403Z","steps":["trace[1092648411] 'agreement among raft nodes before linearized reading' (duration: 142.755488ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:13.790437Z","caller":"traceutil/trace.go:171","msg":"trace[570292086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"147.951233ms","start":"2026-05-07T23:22:13.642474Z","end":"2026-05-07T23:22:13.790425Z","steps":["trace[570292086] 'agreement among raft nodes before linearized reading' (duration: 147.643942ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:13.790731Z","caller":"traceutil/trace.go:171","msg":"trace[331359532] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"110.807841ms","start":"2026-05-07T23:22:13.679908Z","end":"2026-05-07T23:22:13.790715Z","steps":["trace[331359532] 'process raft request' (duration: 110.651255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:23.16076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.672427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:23.160855Z","caller":"traceutil/trace.go:171","msg":"trace[1753682489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"582.815702ms","start":"2026-05-07T23:22:22.578019Z","end":"2026-05-07T23:22:23.160835Z","steps":["trace[1753682489] 'range keys from in-memory index tree' (duration: 582.586465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:23.160894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:22.577998Z","time spent":"582.888504ms","remote":"127.0.0.1:35926","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-05-07T23:22:23.16139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.246602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vzxrw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-07T23:22:23.161477Z","caller":"traceutil/trace.go:171","msg":"trace[2132866534] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vzxrw; range_end:; response_count:1; response_revision:805; }","duration":"577.384736ms","start":"2026-05-07T23:22:22.584072Z","end":"2026-05-07T23:22:23.161457Z","steps":["trace[2132866534] 'range keys from in-memory index tree' (duration: 577.070966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:23.161473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.954286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T23:22:23.161519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:22.584051Z","time spent":"577.459039ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vzxrw\" "} {"level":"info","ts":"2026-05-07T23:22:23.161545Z","caller":"traceutil/trace.go:171","msg":"trace[1511847767] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:805; }","duration":"229.092701ms","start":"2026-05-07T23:22:22.932433Z","end":"2026-05-07T23:22:23.161526Z","steps":["trace[1511847767] 'count revisions from in-memory index tree' (duration: 228.866083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:23.283233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.803672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:23.283281Z","caller":"traceutil/trace.go:171","msg":"trace[2026531065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"120.888895ms","start":"2026-05-07T23:22:23.162385Z","end":"2026-05-07T23:22:23.283274Z","steps":["trace[2026531065] 'agreement among raft nodes before linearized reading' (duration: 120.798732ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:23.283156Z","caller":"traceutil/trace.go:171","msg":"trace[393899995] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:830; }","duration":"120.715069ms","start":"2026-05-07T23:22:23.162418Z","end":"2026-05-07T23:22:23.283134Z","steps":["trace[393899995] 'read index received' (duration: 120.702209ms)","trace[393899995] 'applied index is now lower than readState.Index' (duration: 11.57µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:23.295034Z","caller":"traceutil/trace.go:171","msg":"trace[53519739] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"124.650896ms","start":"2026-05-07T23:22:23.170369Z","end":"2026-05-07T23:22:23.29502Z","steps":["trace[53519739] 'process raft request' (duration: 124.514801ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:23.855486Z","caller":"traceutil/trace.go:171","msg":"trace[1856220190] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"260.046283ms","start":"2026-05-07T23:22:23.595408Z","end":"2026-05-07T23:22:23.855455Z","steps":["trace[1856220190] 'process raft request' (duration: 259.731833ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:23.98213Z","caller":"traceutil/trace.go:171","msg":"trace[2096075286] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"122.718444ms","start":"2026-05-07T23:22:23.859388Z","end":"2026-05-07T23:22:23.982106Z","steps":["trace[2096075286] 'process raft request' (duration: 122.578359ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:24.078477Z","caller":"traceutil/trace.go:171","msg":"trace[1973132376] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"218.500213ms","start":"2026-05-07T23:22:23.859948Z","end":"2026-05-07T23:22:24.078448Z","steps":["trace[1973132376] 'process raft request' (duration: 218.340378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:48.21809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.522542ms","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-05-07T23:22:48.218175Z","caller":"traceutil/trace.go:171","msg":"trace[1318325660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"115.662646ms","start":"2026-05-07T23:22:48.102493Z","end":"2026-05-07T23:22:48.218156Z","steps":["trace[1318325660] 'range keys from in-memory index tree' (duration: 115.305538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:48.271352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.084508ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-07T23:22:48.35061Z","caller":"traceutil/trace.go:171","msg":"trace[86204230] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"129.003888ms","start":"2026-05-07T23:22:48.221561Z","end":"2026-05-07T23:22:48.350565Z","steps":["trace[86204230] 'process raft request' (duration: 128.436964ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:06.658251Z","caller":"traceutil/trace.go:171","msg":"trace[119032287] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"138.490906ms","start":"2026-05-07T23:23:06.519716Z","end":"2026-05-07T23:23:06.658207Z","steps":["trace[119032287] 'process raft request' (duration: 137.70653ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:06.658296Z","caller":"traceutil/trace.go:171","msg":"trace[1813528774] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"136.825841ms","start":"2026-05-07T23:23:06.52145Z","end":"2026-05-07T23:23:06.658276Z","steps":["trace[1813528774] 'process raft request' (duration: 136.677318ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:15.810297Z","caller":"traceutil/trace.go:171","msg":"trace[504969950] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"140.764311ms","start":"2026-05-07T23:23:15.669513Z","end":"2026-05-07T23:23:15.810277Z","steps":["trace[504969950] 'read index received' (duration: 140.503926ms)","trace[504969950] 'applied index is now lower than readState.Index' (duration: 258.955µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:23:15.810347Z","caller":"traceutil/trace.go:171","msg":"trace[96630379] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"159.673796ms","start":"2026-05-07T23:23:15.650644Z","end":"2026-05-07T23:23:15.810318Z","steps":["trace[96630379] 'process raft request' (duration: 159.469212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:15.810484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.833563ms","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-05-07T23:23:15.810515Z","caller":"traceutil/trace.go:171","msg":"trace[551817272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"140.923074ms","start":"2026-05-07T23:23:15.669584Z","end":"2026-05-07T23:23:15.810507Z","steps":["trace[551817272] 'agreement among raft nodes before linearized reading' (duration: 140.805061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:15.810576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.034326ms","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-05-07T23:23:15.810657Z","caller":"traceutil/trace.go:171","msg":"trace[916762933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"141.161398ms","start":"2026-05-07T23:23:15.669478Z","end":"2026-05-07T23:23:15.810639Z","steps":["trace[916762933] 'agreement among raft nodes before linearized reading' (duration: 140.875332ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:32.748866Z","caller":"traceutil/trace.go:171","msg":"trace[1071011878] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"132.704175ms","start":"2026-05-07T23:23:32.616141Z","end":"2026-05-07T23:23:32.748845Z","steps":["trace[1071011878] 'process raft request' (duration: 132.586113ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:33.012749Z","caller":"traceutil/trace.go:171","msg":"trace[828672117] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"177.707445ms","start":"2026-05-07T23:23:32.835009Z","end":"2026-05-07T23:23:33.012716Z","steps":["trace[828672117] 'process raft request' (duration: 177.498482ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:33.061907Z","caller":"traceutil/trace.go:171","msg":"trace[818089287] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"225.277846ms","start":"2026-05-07T23:23:32.836597Z","end":"2026-05-07T23:23:33.061875Z","steps":["trace[818089287] 'process raft request' (duration: 189.655834ms)","trace[818089287] 'compare' (duration: 35.49521ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:24:01.195808Z","caller":"traceutil/trace.go:171","msg":"trace[1219978282] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"112.412618ms","start":"2026-05-07T23:24:01.083368Z","end":"2026-05-07T23:24:01.19578Z","steps":["trace[1219978282] 'process raft request' (duration: 112.058073ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:09.019519Z","caller":"traceutil/trace.go:171","msg":"trace[1470369356] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"117.615747ms","start":"2026-05-07T23:24:08.901872Z","end":"2026-05-07T23:24:09.019487Z","steps":["trace[1470369356] 'process raft request' (duration: 117.405954ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:17.24192Z","caller":"traceutil/trace.go:171","msg":"trace[1167351533] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"192.395733ms","start":"2026-05-07T23:24:17.049492Z","end":"2026-05-07T23:24:17.241887Z","steps":["trace[1167351533] 'process raft request' (duration: 191.47189ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:46.904834Z","caller":"traceutil/trace.go:171","msg":"trace[294849461] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1509; }","duration":"123.256401ms","start":"2026-05-07T23:24:46.781547Z","end":"2026-05-07T23:24:46.904804Z","steps":["trace[294849461] 'read index received' (duration: 103.753255ms)","trace[294849461] 'applied index is now lower than readState.Index' (duration: 19.501726ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:24:46.905013Z","caller":"traceutil/trace.go:171","msg":"trace[1175965656] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"129.882844ms","start":"2026-05-07T23:24:46.775096Z","end":"2026-05-07T23:24:46.904979Z","steps":["trace[1175965656] 'process raft request' (duration: 110.168176ms)","trace[1175965656] 'compare' (duration: 19.433615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:46.905147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.529154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:1 size:199"} {"level":"info","ts":"2026-05-07T23:24:46.905275Z","caller":"traceutil/trace.go:171","msg":"trace[1403128986] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:1; response_revision:1452; }","duration":"123.720626ms","start":"2026-05-07T23:24:46.78153Z","end":"2026-05-07T23:24:46.905251Z","steps":["trace[1403128986] 'agreement among raft nodes before linearized reading' (duration: 123.441233ms)"],"step_count":1}