{"level":"warn","ts":"2026-03-31T20:14:38.447223Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T20:14:38.447336Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141: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-03-31T20:14:38.44742Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T20:14:38.44743Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-03-31T20:14:38.447449Z","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-03-31T20:14:38.44839Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-03-31T20:14:38.448475Z","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.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141: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-03-31T20:14:38.453671Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.864572ms"} {"level":"info","ts":"2026-03-31T20:14:38.463912Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-03-31T20:14:38.464047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T20:14:38.464087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-03-31T20:14:38.464098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T20:14:38.464115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-03-31T20:14:38.464155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-03-31T20:14:38.473824Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T20:14:38.477309Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T20:14:38.479824Z","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-03-31T20:14:38.48621Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T20:14:38.486324Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T20:14:38.486461Z","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-03-31T20:14:38.486516Z","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-03-31T20:14:38.486526Z","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-03-31T20:14:38.488883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-03-31T20:14:38.489205Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-03-31T20:14:38.491498Z","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-03-31T20:14:38.491726Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T20:14:38.491756Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T20:14:38.491871Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-03-31T20:14:38.49189Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-03-31T20:14:38.564995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T20:14:38.565085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T20:14:38.565127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-03-31T20:14:38.565156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-03-31T20:14:38.565174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-03-31T20:14:38.565191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-03-31T20:14:38.565213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-03-31T20:14:38.571839Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T20:14:38.57188Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T20:14:38.572004Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:14:38.573316Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:14:38.573464Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:14:38.573491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T20:14:38.57351Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T20:14:38.573515Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:14:38.575174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T20:14:38.575791Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T20:14:38.579709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-03-31T20:14:40.978896Z","caller":"traceutil/trace.go:171","msg":"trace[936215578] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"124.830718ms","start":"2026-03-31T20:14:40.854052Z","end":"2026-03-31T20:14:40.978882Z","steps":["trace[936215578] 'process raft request' (duration: 36.352723ms)","trace[936215578] 'compare' (duration: 88.185423ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:40.978819Z","caller":"traceutil/trace.go:171","msg":"trace[315197775] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"122.503859ms","start":"2026-03-31T20:14:40.856275Z","end":"2026-03-31T20:14:40.978779Z","steps":["trace[315197775] 'process raft request' (duration: 122.440578ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:41.759131Z","caller":"traceutil/trace.go:171","msg":"trace[718022368] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"113.329377ms","start":"2026-03-31T20:14:41.645738Z","end":"2026-03-31T20:14:41.759067Z","steps":["trace[718022368] 'process raft request' (duration: 113.013176ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:42.804855Z","caller":"traceutil/trace.go:171","msg":"trace[559346060] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"113.573298ms","start":"2026-03-31T20:14:42.691216Z","end":"2026-03-31T20:14:42.804789Z","steps":["trace[559346060] 'process raft request' (duration: 49.948036ms)","trace[559346060] 'compare' (duration: 63.426002ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:43.258805Z","caller":"traceutil/trace.go:171","msg":"trace[2047002280] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"111.695797ms","start":"2026-03-31T20:14:43.14707Z","end":"2026-03-31T20:14:43.258766Z","steps":["trace[2047002280] 'process raft request' (duration: 77.427557ms)","trace[2047002280] 'compare' (duration: 34.044289ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:43.888992Z","caller":"traceutil/trace.go:171","msg":"trace[1455907468] linearizableReadLoop","detail":"{readStateIndex:169; appliedIndex:168; }","duration":"121.548962ms","start":"2026-03-31T20:14:43.767402Z","end":"2026-03-31T20:14:43.888951Z","steps":["trace[1455907468] 'read index received' (duration: 25.538201ms)","trace[1455907468] 'applied index is now lower than readState.Index' (duration: 96.009211ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:43.889394Z","caller":"traceutil/trace.go:171","msg":"trace[1765702995] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"123.00382ms","start":"2026-03-31T20:14:43.766305Z","end":"2026-03-31T20:14:43.889309Z","steps":["trace[1765702995] 'process raft request' (duration: 26.594427ms)","trace[1765702995] 'compare' (duration: 95.881931ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:43.889654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.194897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a2064094a5b31a\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-03-31T20:14:43.889796Z","caller":"traceutil/trace.go:171","msg":"trace[1047581519] range","detail":"{range_begin:/registry/events/default/instance.18a2064094a5b31a; range_end:; response_count:1; response_revision:164; }","duration":"122.392967ms","start":"2026-03-31T20:14:43.767336Z","end":"2026-03-31T20:14:43.889729Z","steps":["trace[1047581519] 'agreement among raft nodes before linearized reading' (duration: 121.768463ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:44.336147Z","caller":"traceutil/trace.go:171","msg":"trace[1928372277] linearizableReadLoop","detail":"{readStateIndex:190; appliedIndex:188; }","duration":"147.494604ms","start":"2026-03-31T20:14:44.188625Z","end":"2026-03-31T20:14:44.33612Z","steps":["trace[1928372277] 'read index received' (duration: 25.806168ms)","trace[1928372277] 'applied index is now lower than readState.Index' (duration: 121.686826ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:44.336199Z","caller":"traceutil/trace.go:171","msg":"trace[769738570] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"156.995453ms","start":"2026-03-31T20:14:44.179157Z","end":"2026-03-31T20:14:44.336152Z","steps":["trace[769738570] 'process raft request' (duration: 148.307639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:44.336474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.231188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:14:44.336521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.859757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:14:44.336611Z","caller":"traceutil/trace.go:171","msg":"trace[379410819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:185; }","duration":"147.984097ms","start":"2026-03-31T20:14:44.188604Z","end":"2026-03-31T20:14:44.336588Z","steps":["trace[379410819] 'agreement among raft nodes before linearized reading' (duration: 147.638755ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:44.336545Z","caller":"traceutil/trace.go:171","msg":"trace[682680607] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:0; response_revision:185; }","duration":"120.422679ms","start":"2026-03-31T20:14:44.216102Z","end":"2026-03-31T20:14:44.336524Z","steps":["trace[682680607] 'agreement among raft nodes before linearized reading' (duration: 120.157917ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:44.931997Z","caller":"traceutil/trace.go:171","msg":"trace[779012492] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"111.284253ms","start":"2026-03-31T20:14:44.820685Z","end":"2026-03-31T20:14:44.93197Z","steps":["trace[779012492] 'read index received' (duration: 66.553808ms)","trace[779012492] 'applied index is now lower than readState.Index' (duration: 44.729325ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:44.932096Z","caller":"traceutil/trace.go:171","msg":"trace[1733231712] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"141.656369ms","start":"2026-03-31T20:14:44.790393Z","end":"2026-03-31T20:14:44.932049Z","steps":["trace[1733231712] 'process raft request' (duration: 96.846664ms)","trace[1733231712] 'compare' (duration: 44.610604ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:44.932202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.499594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:14:44.932302Z","caller":"traceutil/trace.go:171","msg":"trace[944177092] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:213; }","duration":"111.632984ms","start":"2026-03-31T20:14:44.820633Z","end":"2026-03-31T20:14:44.932266Z","steps":["trace[944177092] 'agreement among raft nodes before linearized reading' (duration: 111.446953ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:45.642651Z","caller":"traceutil/trace.go:171","msg":"trace[161331284] linearizableReadLoop","detail":"{readStateIndex:244; appliedIndex:243; }","duration":"104.895683ms","start":"2026-03-31T20:14:45.537726Z","end":"2026-03-31T20:14:45.642622Z","steps":["trace[161331284] 'read index received' (duration: 104.673302ms)","trace[161331284] 'applied index is now lower than readState.Index' (duration: 221.351µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:45.642776Z","caller":"traceutil/trace.go:171","msg":"trace[1820402476] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"106.182412ms","start":"2026-03-31T20:14:45.536555Z","end":"2026-03-31T20:14:45.642738Z","steps":["trace[1820402476] 'process raft request' (duration: 105.92365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:45.64285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.092014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:14:45.642924Z","caller":"traceutil/trace.go:171","msg":"trace[1989390406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:239; }","duration":"105.213315ms","start":"2026-03-31T20:14:45.537697Z","end":"2026-03-31T20:14:45.64291Z","steps":["trace[1989390406] 'agreement among raft nodes before linearized reading' (duration: 105.071564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:47.690568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.128648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:14:47.690686Z","caller":"traceutil/trace.go:171","msg":"trace[1385781939] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"386.522241ms","start":"2026-03-31T20:14:47.304147Z","end":"2026-03-31T20:14:47.690669Z","steps":["trace[1385781939] 'read index received' (duration: 145.806548ms)","trace[1385781939] 'applied index is now lower than readState.Index' (duration: 240.714243ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:47.690731Z","caller":"traceutil/trace.go:171","msg":"trace[873279866] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"390.959245ms","start":"2026-03-31T20:14:47.299729Z","end":"2026-03-31T20:14:47.690688Z","steps":["trace[873279866] 'process raft request' (duration: 150.215212ms)","trace[873279866] 'compare' (duration: 239.984887ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:47.69081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.667113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-31T20:14:47.690841Z","caller":"traceutil/trace.go:171","msg":"trace[284006500] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:281; }","duration":"386.738474ms","start":"2026-03-31T20:14:47.304092Z","end":"2026-03-31T20:14:47.69083Z","steps":["trace[284006500] 'agreement among raft nodes before linearized reading' (duration: 386.646742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:47.690845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:47.2997Z","time spent":"391.088427ms","remote":"127.0.0.1:54034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:14:47.690887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:47.30407Z","time spent":"386.807654ms","remote":"127.0.0.1:53878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-03-31T20:14:47.724915Z","caller":"traceutil/trace.go:171","msg":"trace[1045655048] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"291.902177ms","start":"2026-03-31T20:14:47.432997Z","end":"2026-03-31T20:14:47.724899Z","steps":["trace[1045655048] 'process raft request' (duration: 291.805766ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:47.893858Z","caller":"traceutil/trace.go:171","msg":"trace[1693835562] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"145.751197ms","start":"2026-03-31T20:14:47.74808Z","end":"2026-03-31T20:14:47.893831Z","steps":["trace[1693835562] 'process raft request' (duration: 76.29978ms)","trace[1693835562] 'compare' (duration: 69.229975ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:47.894195Z","caller":"traceutil/trace.go:171","msg":"trace[1139452333] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:290; }","duration":"116.519776ms","start":"2026-03-31T20:14:47.777649Z","end":"2026-03-31T20:14:47.894168Z","steps":["trace[1139452333] 'read index received' (duration: 46.714547ms)","trace[1139452333] 'applied index is now lower than readState.Index' (duration: 69.803769ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:47.894312Z","caller":"traceutil/trace.go:171","msg":"trace[8608319] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"142.822464ms","start":"2026-03-31T20:14:47.75146Z","end":"2026-03-31T20:14:47.894283Z","steps":["trace[8608319] 'process raft request' (duration: 142.29294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:47.894475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.733438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:14:47.894409Z","caller":"traceutil/trace.go:171","msg":"trace[827687361] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"126.879138ms","start":"2026-03-31T20:14:47.76746Z","end":"2026-03-31T20:14:47.894339Z","steps":["trace[827687361] 'process raft request' (duration: 126.558226ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:47.894534Z","caller":"traceutil/trace.go:171","msg":"trace[1437666054] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:287; }","duration":"116.90598ms","start":"2026-03-31T20:14:47.777607Z","end":"2026-03-31T20:14:47.894513Z","steps":["trace[1437666054] 'agreement among raft nodes before linearized reading' (duration: 116.658378ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:49.456346Z","caller":"traceutil/trace.go:171","msg":"trace[334157920] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"112.799564ms","start":"2026-03-31T20:14:49.343512Z","end":"2026-03-31T20:14:49.456311Z","steps":["trace[334157920] 'process raft request' (duration: 112.606112ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:49.462954Z","caller":"traceutil/trace.go:171","msg":"trace[766515078] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"108.662226ms","start":"2026-03-31T20:14:49.354258Z","end":"2026-03-31T20:14:49.46292Z","steps":["trace[766515078] 'process raft request' (duration: 108.405374ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:49.656178Z","caller":"traceutil/trace.go:171","msg":"trace[2032747746] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"101.888165ms","start":"2026-03-31T20:14:49.554258Z","end":"2026-03-31T20:14:49.656146Z","steps":["trace[2032747746] 'read index received' (duration: 46.87359ms)","trace[2032747746] 'applied index is now lower than readState.Index' (duration: 55.013295ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:49.656198Z","caller":"traceutil/trace.go:171","msg":"trace[1403781386] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"144.68807ms","start":"2026-03-31T20:14:49.511479Z","end":"2026-03-31T20:14:49.656168Z","steps":["trace[1403781386] 'process raft request' (duration: 89.711056ms)","trace[1403781386] 'compare' (duration: 54.827413ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:49.656378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.095296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:14:49.656477Z","caller":"traceutil/trace.go:171","msg":"trace[878167902] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:330; }","duration":"102.249758ms","start":"2026-03-31T20:14:49.554202Z","end":"2026-03-31T20:14:49.656452Z","steps":["trace[878167902] 'agreement among raft nodes before linearized reading' (duration: 102.040396ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:49.822969Z","caller":"traceutil/trace.go:171","msg":"trace[1571420467] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"105.78901ms","start":"2026-03-31T20:14:49.717153Z","end":"2026-03-31T20:14:49.822942Z","steps":["trace[1571420467] 'read index received' (duration: 62.283809ms)","trace[1571420467] 'applied index is now lower than readState.Index' (duration: 43.504151ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:49.823012Z","caller":"traceutil/trace.go:171","msg":"trace[579283418] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"112.716724ms","start":"2026-03-31T20:14:49.710259Z","end":"2026-03-31T20:14:49.822976Z","steps":["trace[579283418] 'process raft request' (duration: 69.171172ms)","trace[579283418] 'compare' (duration: 43.30403ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:49.823081Z","caller":"traceutil/trace.go:171","msg":"trace[145795966] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"104.792891ms","start":"2026-03-31T20:14:49.718269Z","end":"2026-03-31T20:14:49.823062Z","steps":["trace[145795966] 'process raft request' (duration: 104.62875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:49.823315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.149293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:14:49.823382Z","caller":"traceutil/trace.go:171","msg":"trace[1399799563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:335; }","duration":"106.263674ms","start":"2026-03-31T20:14:49.717091Z","end":"2026-03-31T20:14:49.823355Z","steps":["trace[1399799563] 'agreement among raft nodes before linearized reading' (duration: 105.996052ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:50.028797Z","caller":"traceutil/trace.go:171","msg":"trace[1118714928] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"199.227793ms","start":"2026-03-31T20:14:49.829516Z","end":"2026-03-31T20:14:50.028744Z","steps":["trace[1118714928] 'process raft request' (duration: 133.979474ms)","trace[1118714928] 'compare' (duration: 64.896976ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:50.028849Z","caller":"traceutil/trace.go:171","msg":"trace[1455179013] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:346; }","duration":"160.035241ms","start":"2026-03-31T20:14:49.868796Z","end":"2026-03-31T20:14:50.028832Z","steps":["trace[1455179013] 'read index received' (duration: 94.63152ms)","trace[1455179013] 'applied index is now lower than readState.Index' (duration: 65.402961ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:50.028943Z","caller":"traceutil/trace.go:171","msg":"trace[1069358442] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"195.028995ms","start":"2026-03-31T20:14:49.833884Z","end":"2026-03-31T20:14:50.028913Z","steps":["trace[1069358442] 'process raft request' (duration: 194.622161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:50.029021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.871153ms","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-03-31T20:14:50.029051Z","caller":"traceutil/trace.go:171","msg":"trace[25393333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:338; }","duration":"126.938314ms","start":"2026-03-31T20:14:49.902105Z","end":"2026-03-31T20:14:50.029044Z","steps":["trace[25393333] 'agreement among raft nodes before linearized reading' (duration: 126.900184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:50.028983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.172042ms","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-03-31T20:14:50.029182Z","caller":"traceutil/trace.go:171","msg":"trace[264899097] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:338; }","duration":"160.398214ms","start":"2026-03-31T20:14:49.86877Z","end":"2026-03-31T20:14:50.029168Z","steps":["trace[264899097] 'agreement among raft nodes before linearized reading' (duration: 160.156452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:50.029322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.572182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-31T20:14:50.029374Z","caller":"traceutil/trace.go:171","msg":"trace[1839452473] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:338; }","duration":"124.658793ms","start":"2026-03-31T20:14:49.904699Z","end":"2026-03-31T20:14:50.029358Z","steps":["trace[1839452473] 'agreement among raft nodes before linearized reading' (duration: 124.545552ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:50.255072Z","caller":"traceutil/trace.go:171","msg":"trace[1913744301] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"120.398398ms","start":"2026-03-31T20:14:50.134535Z","end":"2026-03-31T20:14:50.254934Z","steps":["trace[1913744301] 'process raft request' (duration: 120.036174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:50.255065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.062904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-31T20:14:50.255514Z","caller":"traceutil/trace.go:171","msg":"trace[1034277366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:341; }","duration":"100.541509ms","start":"2026-03-31T20:14:50.154939Z","end":"2026-03-31T20:14:50.255481Z","steps":["trace[1034277366] 'agreement among raft nodes before linearized reading' (duration: 99.881603ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:50.967208Z","caller":"traceutil/trace.go:171","msg":"trace[1194875976] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"112.678013ms","start":"2026-03-31T20:14:50.854507Z","end":"2026-03-31T20:14:50.967185Z","steps":["trace[1194875976] 'read index received' (duration: 30.886784ms)","trace[1194875976] 'applied index is now lower than readState.Index' (duration: 81.789889ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:50.967385Z","caller":"traceutil/trace.go:171","msg":"trace[460618696] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"127.859858ms","start":"2026-03-31T20:14:50.839512Z","end":"2026-03-31T20:14:50.967371Z","steps":["trace[460618696] 'process raft request' (duration: 75.374978ms)","trace[460618696] 'compare' (duration: 51.890204ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:50.967903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.37214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-31T20:14:50.967974Z","caller":"traceutil/trace.go:171","msg":"trace[891938429] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:377; }","duration":"113.46485ms","start":"2026-03-31T20:14:50.854491Z","end":"2026-03-31T20:14:50.967956Z","steps":["trace[891938429] 'agreement among raft nodes before linearized reading' (duration: 113.349279ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:51.442294Z","caller":"traceutil/trace.go:171","msg":"trace[1126873635] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"107.318351ms","start":"2026-03-31T20:14:51.33493Z","end":"2026-03-31T20:14:51.442249Z","steps":["trace[1126873635] 'process raft request' (duration: 87.880135ms)","trace[1126873635] 'compare' (duration: 19.242174ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:52.151233Z","caller":"traceutil/trace.go:171","msg":"trace[1996924871] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"110.804344ms","start":"2026-03-31T20:14:52.040393Z","end":"2026-03-31T20:14:52.151198Z","steps":["trace[1996924871] 'process raft request' (duration: 34.385554ms)","trace[1996924871] 'compare' (duration: 76.151417ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:52.275176Z","caller":"traceutil/trace.go:171","msg":"trace[1298512653] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"118.895079ms","start":"2026-03-31T20:14:52.156249Z","end":"2026-03-31T20:14:52.275144Z","steps":["trace[1298512653] 'process raft request' (duration: 118.707207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:52.837607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.193283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-31T20:14:52.83767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.349229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-31T20:14:52.837743Z","caller":"traceutil/trace.go:171","msg":"trace[361876531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"273.376045ms","start":"2026-03-31T20:14:52.564347Z","end":"2026-03-31T20:14:52.837723Z","steps":["trace[361876531] 'range keys from in-memory index tree' (duration: 273.121143ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:52.837796Z","caller":"traceutil/trace.go:171","msg":"trace[306986844] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:397; }","duration":"230.531842ms","start":"2026-03-31T20:14:52.607239Z","end":"2026-03-31T20:14:52.837771Z","steps":["trace[306986844] 'range keys from in-memory index tree' (duration: 230.228828ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:52.988895Z","caller":"traceutil/trace.go:171","msg":"trace[73336531] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"146.873376ms","start":"2026-03-31T20:14:52.841986Z","end":"2026-03-31T20:14:52.98886Z","steps":["trace[73336531] 'process raft request' (duration: 146.678744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:53.200844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.815573ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-31T20:14:53.543139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.571458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:14:53.543312Z","caller":"traceutil/trace.go:171","msg":"trace[1524959362] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:409; }","duration":"205.702566ms","start":"2026-03-31T20:14:53.337593Z","end":"2026-03-31T20:14:53.543296Z","steps":["trace[1524959362] 'read index received' (duration: 31.967556ms)","trace[1524959362] 'applied index is now lower than readState.Index' (duration: 173.73418ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:53.543375Z","caller":"traceutil/trace.go:171","msg":"trace[1164402982] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"373.614732ms","start":"2026-03-31T20:14:53.169719Z","end":"2026-03-31T20:14:53.543334Z","steps":["trace[1164402982] 'process raft request' (duration: 199.783891ms)","trace[1164402982] 'compare' (duration: 173.441047ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:53.543499Z","caller":"traceutil/trace.go:171","msg":"trace[280340551] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"341.173841ms","start":"2026-03-31T20:14:53.202246Z","end":"2026-03-31T20:14:53.54342Z","steps":["trace[280340551] 'process raft request' (duration: 341.003939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:53.543579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.986959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-628ml\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-03-31T20:14:53.543621Z","caller":"traceutil/trace.go:171","msg":"trace[424372842] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-628ml; range_end:; response_count:1; response_revision:400; }","duration":"206.034909ms","start":"2026-03-31T20:14:53.337576Z","end":"2026-03-31T20:14:53.543611Z","steps":["trace[424372842] 'agreement among raft nodes before linearized reading' (duration: 205.772996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:53.543524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:53.169691Z","time spent":"373.762984ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:14:53.543821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:53.202224Z","time spent":"341.527255ms","remote":"127.0.0.1:54210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:14:54.19092Z","caller":"traceutil/trace.go:171","msg":"trace[327846518] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"637.954333ms","start":"2026-03-31T20:14:53.552936Z","end":"2026-03-31T20:14:54.190891Z","steps":["trace[327846518] 'process raft request' (duration: 637.746181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:54.191436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:53.552915Z","time spent":"638.078225ms","remote":"127.0.0.1:53860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:14:54.421707Z","caller":"traceutil/trace.go:171","msg":"trace[518447830] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"226.709277ms","start":"2026-03-31T20:14:54.194961Z","end":"2026-03-31T20:14:54.42167Z","steps":["trace[518447830] 'process raft request' (duration: 164.437473ms)","trace[518447830] 'compare' (duration: 62.121452ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:54.729936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.584557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:14:54.730046Z","caller":"traceutil/trace.go:171","msg":"trace[780664444] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:412; }","duration":"449.843098ms","start":"2026-03-31T20:14:54.280187Z","end":"2026-03-31T20:14:54.73003Z","steps":["trace[780664444] 'read index received' (duration: 79.298652ms)","trace[780664444] 'applied index is now lower than readState.Index' (duration: 370.543376ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:54.730222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.00098ms","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-03-31T20:14:54.730214Z","caller":"traceutil/trace.go:171","msg":"trace[1863021018] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"533.41255ms","start":"2026-03-31T20:14:54.19664Z","end":"2026-03-31T20:14:54.730053Z","steps":["trace[1863021018] 'process raft request' (duration: 334.628381ms)","trace[1863021018] 'compare' (duration: 198.477576ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:54.73026Z","caller":"traceutil/trace.go:171","msg":"trace[277986255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"450.06837ms","start":"2026-03-31T20:14:54.280181Z","end":"2026-03-31T20:14:54.730249Z","steps":["trace[277986255] 'agreement among raft nodes before linearized reading' (duration: 449.966039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:54.730357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:54.19662Z","time spent":"533.646173ms","remote":"127.0.0.1:54172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:14:54.73037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:54.280138Z","time spent":"450.221063ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:14:54.730457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.332021ms","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-03-31T20:14:54.73058Z","caller":"traceutil/trace.go:171","msg":"trace[1949676413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"418.578405ms","start":"2026-03-31T20:14:54.31198Z","end":"2026-03-31T20:14:54.730559Z","steps":["trace[1949676413] 'agreement among raft nodes before linearized reading' (duration: 418.25776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:54.730683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:54.311958Z","time spent":"418.708236ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:14:55.199214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.430535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:14:55.199475Z","caller":"traceutil/trace.go:171","msg":"trace[122330506] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:414; }","duration":"206.380457ms","start":"2026-03-31T20:14:54.993076Z","end":"2026-03-31T20:14:55.199457Z","steps":["trace[122330506] 'read index received' (duration: 69.573778ms)","trace[122330506] 'applied index is now lower than readState.Index' (duration: 136.805689ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:55.199518Z","caller":"traceutil/trace.go:171","msg":"trace[238180143] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"464.416224ms","start":"2026-03-31T20:14:54.735054Z","end":"2026-03-31T20:14:55.199471Z","steps":["trace[238180143] 'process raft request' (duration: 209.056118ms)","trace[238180143] 'compare' (duration: 254.295494ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:55.199849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:54.735032Z","time spent":"464.773789ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:14:55.199633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.552699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:14:55.200053Z","caller":"traceutil/trace.go:171","msg":"trace[574420136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"207.014225ms","start":"2026-03-31T20:14:54.99302Z","end":"2026-03-31T20:14:55.200034Z","steps":["trace[574420136] 'agreement among raft nodes before linearized reading' (duration: 206.540979ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:55.199706Z","caller":"traceutil/trace.go:171","msg":"trace[1054589043] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"463.844358ms","start":"2026-03-31T20:14:54.735775Z","end":"2026-03-31T20:14:55.19962Z","steps":["trace[1054589043] 'process raft request' (duration: 463.618815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:55.200198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:54.735752Z","time spent":"464.405745ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:14:55.88236Z","caller":"traceutil/trace.go:171","msg":"trace[900914512] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"160.108252ms","start":"2026-03-31T20:14:55.722222Z","end":"2026-03-31T20:14:55.882331Z","steps":["trace[900914512] 'read index received' (duration: 136.09047ms)","trace[900914512] 'applied index is now lower than readState.Index' (duration: 24.015802ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:55.882612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.368355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-03-31T20:14:55.882684Z","caller":"traceutil/trace.go:171","msg":"trace[1794177763] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:407; }","duration":"160.464977ms","start":"2026-03-31T20:14:55.722208Z","end":"2026-03-31T20:14:55.882673Z","steps":["trace[1794177763] 'agreement among raft nodes before linearized reading' (duration: 160.226774ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:55.882793Z","caller":"traceutil/trace.go:171","msg":"trace[1872292328] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"162.951156ms","start":"2026-03-31T20:14:55.719426Z","end":"2026-03-31T20:14:55.882377Z","steps":["trace[1872292328] 'process raft request' (duration: 138.880444ms)","trace[1872292328] 'compare' (duration: 23.751888ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:56.115819Z","caller":"traceutil/trace.go:171","msg":"trace[1684604014] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"224.926642ms","start":"2026-03-31T20:14:55.890846Z","end":"2026-03-31T20:14:56.115772Z","steps":["trace[1684604014] 'process raft request' (duration: 170.796419ms)","trace[1684604014] 'compare' (duration: 53.90159ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:57.718221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.676178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T20:14:57.718252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371250637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:14:57.718312Z","caller":"traceutil/trace.go:171","msg":"trace[1967193835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"514.815369ms","start":"2026-03-31T20:14:57.203476Z","end":"2026-03-31T20:14:57.718292Z","steps":["trace[1967193835] 'range keys from in-memory index tree' (duration: 514.582106ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:57.71835Z","caller":"traceutil/trace.go:171","msg":"trace[924032546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"1.371422911s","start":"2026-03-31T20:14:56.346906Z","end":"2026-03-31T20:14:57.718329Z","steps":["trace[924032546] 'range keys from in-memory index tree' (duration: 1.371131206s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:57.718365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.20346Z","time spent":"514.8952ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:14:57.71833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292435493s","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-03-31T20:14:57.718399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:56.346889Z","time spent":"1.371499392s","remote":"127.0.0.1:53698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:14:57.718431Z","caller":"traceutil/trace.go:171","msg":"trace[1050740489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"1.292582255s","start":"2026-03-31T20:14:56.425826Z","end":"2026-03-31T20:14:57.718408Z","steps":["trace[1050740489] 'range keys from in-memory index tree' (duration: 1.292281541s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:57.718489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:56.425807Z","time spent":"1.292669956s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:14:57.718601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.527456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-31T20:14:57.718705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.775947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:14:57.718708Z","caller":"traceutil/trace.go:171","msg":"trace[1286130574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"514.659977ms","start":"2026-03-31T20:14:57.20402Z","end":"2026-03-31T20:14:57.71868Z","steps":["trace[1286130574] 'range keys from in-memory index tree' (duration: 514.422205ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:57.718768Z","caller":"traceutil/trace.go:171","msg":"trace[508088443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"465.858538ms","start":"2026-03-31T20:14:57.252877Z","end":"2026-03-31T20:14:57.718736Z","steps":["trace[508088443] 'range keys from in-memory index tree' (duration: 465.687655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:57.718772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.204011Z","time spent":"514.749068ms","remote":"127.0.0.1:53942","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-03-31T20:14:57.718807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.252857Z","time spent":"465.941149ms","remote":"127.0.0.1:53942","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-03-31T20:14:58.228347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307471,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T20:14:58.356579Z","caller":"traceutil/trace.go:171","msg":"trace[425797529] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"632.456982ms","start":"2026-03-31T20:14:57.724086Z","end":"2026-03-31T20:14:58.356543Z","steps":["trace[425797529] 'process raft request' (duration: 632.135868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:58.356901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.724055Z","time spent":"632.631894ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:14:58.801957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.250241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:14:58.802235Z","caller":"traceutil/trace.go:171","msg":"trace[1591104506] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.077819235s","start":"2026-03-31T20:14:57.72434Z","end":"2026-03-31T20:14:58.80216Z","steps":["trace[1591104506] 'process raft request' (duration: 760.272939ms)","trace[1591104506] 'compare' (duration: 317.079099ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:58.802246Z","caller":"traceutil/trace.go:171","msg":"trace[254535119] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.076983313s","start":"2026-03-31T20:14:57.725241Z","end":"2026-03-31T20:14:58.802225Z","steps":["trace[254535119] 'process raft request' (duration: 1.076821881s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:58.802344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.72523Z","time spent":"1.077068784s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:14:58.802371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.72433Z","time spent":"1.077967747s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:14:58.802493Z","caller":"traceutil/trace.go:171","msg":"trace[2110381309] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.076982343s","start":"2026-03-31T20:14:57.725458Z","end":"2026-03-31T20:14:58.80244Z","steps":["trace[2110381309] 'process raft request' (duration: 1.076688169s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:58.802701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074724255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:14:58.802716Z","caller":"traceutil/trace.go:171","msg":"trace[109426594] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"1.075423994s","start":"2026-03-31T20:14:57.727263Z","end":"2026-03-31T20:14:58.802687Z","steps":["trace[109426594] 'process raft request' (duration: 1.07512177s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:58.802743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.725422Z","time spent":"1.077236707s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:14:58.802764Z","caller":"traceutil/trace.go:171","msg":"trace[938025287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"1.074833826s","start":"2026-03-31T20:14:57.727918Z","end":"2026-03-31T20:14:58.802751Z","steps":["trace[938025287] 'agreement among raft nodes before linearized reading' (duration: 1.074717545s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:58.80278Z","caller":"traceutil/trace.go:171","msg":"trace[1363563399] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"427.623032ms","start":"2026-03-31T20:14:58.375142Z","end":"2026-03-31T20:14:58.802765Z","steps":["trace[1363563399] 'process raft request' (duration: 427.546951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:58.802803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.72791Z","time spent":"1.074884907s","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:14:58.802811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:57.727233Z","time spent":"1.075529796s","remote":"127.0.0.1:53860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:14:58.802561Z","caller":"traceutil/trace.go:171","msg":"trace[187034531] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:420; }","duration":"1.074572222s","start":"2026-03-31T20:14:57.727953Z","end":"2026-03-31T20:14:58.802525Z","steps":["trace[187034531] 'read index received' (duration: 628.259057ms)","trace[187034531] 'applied index is now lower than readState.Index' (duration: 446.311955ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:14:58.802864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:58.375102Z","time spent":"427.720812ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:04.488747Z","caller":"traceutil/trace.go:171","msg":"trace[1639274190] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"105.469231ms","start":"2026-03-31T20:15:04.383254Z","end":"2026-03-31T20:15:04.488723Z","steps":["trace[1639274190] 'process raft request' (duration: 105.009204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:05.249995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.495555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:15:05.250165Z","caller":"traceutil/trace.go:171","msg":"trace[69441893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"413.718598ms","start":"2026-03-31T20:15:04.836424Z","end":"2026-03-31T20:15:05.250142Z","steps":["trace[69441893] 'range keys from in-memory index tree' (duration: 413.332672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:05.250238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:04.836403Z","time spent":"413.82223ms","remote":"127.0.0.1:53942","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-03-31T20:15:05.250282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.049553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:15:05.25039Z","caller":"traceutil/trace.go:171","msg":"trace[787780978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"406.194016ms","start":"2026-03-31T20:15:04.844168Z","end":"2026-03-31T20:15:05.250362Z","steps":["trace[787780978] 'range keys from in-memory index tree' (duration: 405.916111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:05.250307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.917912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T20:15:05.250494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:04.844151Z","time spent":"406.328668ms","remote":"127.0.0.1:53942","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-03-31T20:15:05.250505Z","caller":"traceutil/trace.go:171","msg":"trace[692392472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"422.173667ms","start":"2026-03-31T20:15:04.828307Z","end":"2026-03-31T20:15:05.25048Z","steps":["trace[692392472] 'range keys from in-memory index tree' (duration: 421.76388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:05.250563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:04.828281Z","time spent":"422.263618ms","remote":"127.0.0.1:53942","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-03-31T20:15:08.685382Z","caller":"traceutil/trace.go:171","msg":"trace[1978017075] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"180.708163ms","start":"2026-03-31T20:15:08.504644Z","end":"2026-03-31T20:15:08.685352Z","steps":["trace[1978017075] 'process raft request' (duration: 180.56114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:09.514091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.02263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:09.514161Z","caller":"traceutil/trace.go:171","msg":"trace[491628721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"101.134971ms","start":"2026-03-31T20:15:09.413011Z","end":"2026-03-31T20:15:09.514146Z","steps":["trace[491628721] 'range keys from in-memory index tree' (duration: 100.946368ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:15.033303Z","caller":"traceutil/trace.go:171","msg":"trace[2025403385] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"308.996943ms","start":"2026-03-31T20:15:14.724281Z","end":"2026-03-31T20:15:15.033278Z","steps":["trace[2025403385] 'process raft request' (duration: 308.803649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:15.033544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:14.724251Z","time spent":"309.215607ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:18.943607Z","caller":"traceutil/trace.go:171","msg":"trace[1383553787] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"118.700865ms","start":"2026-03-31T20:15:18.82488Z","end":"2026-03-31T20:15:18.943581Z","steps":["trace[1383553787] 'process raft request' (duration: 118.350817ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:19.100849Z","caller":"traceutil/trace.go:171","msg":"trace[100550922] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"133.287706ms","start":"2026-03-31T20:15:18.967528Z","end":"2026-03-31T20:15:19.100816Z","steps":["trace[100550922] 'process raft request' (duration: 33.656323ms)","trace[100550922] 'compare' (duration: 99.345467ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:19.100858Z","caller":"traceutil/trace.go:171","msg":"trace[482287217] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"133.137513ms","start":"2026-03-31T20:15:18.967698Z","end":"2026-03-31T20:15:19.100835Z","steps":["trace[482287217] 'process raft request' (duration: 132.993619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:19.486891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.422332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:15:19.487274Z","caller":"traceutil/trace.go:171","msg":"trace[2038143899] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"293.282125ms","start":"2026-03-31T20:15:19.193932Z","end":"2026-03-31T20:15:19.487214Z","steps":["trace[2038143899] 'process raft request' (duration: 293.086451ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:19.487307Z","caller":"traceutil/trace.go:171","msg":"trace[2119652542] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"380.35044ms","start":"2026-03-31T20:15:19.106908Z","end":"2026-03-31T20:15:19.487258Z","steps":["trace[2119652542] 'process raft request' (duration: 122.498089ms)","trace[2119652542] 'compare' (duration: 257.231598ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:19.487612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:19.106883Z","time spent":"380.646007ms","remote":"127.0.0.1:53784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:15:19.739535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.774353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:15:19.739738Z","caller":"traceutil/trace.go:171","msg":"trace[741247376] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:510; }","duration":"188.218882ms","start":"2026-03-31T20:15:19.551497Z","end":"2026-03-31T20:15:19.739716Z","steps":["trace[741247376] 'read index received' (duration: 38.212005ms)","trace[741247376] 'applied index is now lower than readState.Index' (duration: 150.005707ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:19.739842Z","caller":"traceutil/trace.go:171","msg":"trace[589016452] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"245.047258ms","start":"2026-03-31T20:15:19.494786Z","end":"2026-03-31T20:15:19.739833Z","steps":["trace[589016452] 'process raft request' (duration: 244.859274ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:19.739845Z","caller":"traceutil/trace.go:171","msg":"trace[865099857] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"245.406258ms","start":"2026-03-31T20:15:19.494324Z","end":"2026-03-31T20:15:19.73973Z","steps":["trace[865099857] 'process raft request' (duration: 95.362219ms)","trace[865099857] 'compare' (duration: 149.613889ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:19.740124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.614631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:15:19.740204Z","caller":"traceutil/trace.go:171","msg":"trace[932238746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"188.702553ms","start":"2026-03-31T20:15:19.551482Z","end":"2026-03-31T20:15:19.740184Z","steps":["trace[932238746] 'agreement among raft nodes before linearized reading' (duration: 188.510428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:19.740793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.710222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:15:19.740865Z","caller":"traceutil/trace.go:171","msg":"trace[2127495683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"179.389517ms","start":"2026-03-31T20:15:19.561458Z","end":"2026-03-31T20:15:19.740848Z","steps":["trace[2127495683] 'agreement among raft nodes before linearized reading' (duration: 178.521658ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.10954Z","caller":"traceutil/trace.go:171","msg":"trace[1703048289] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"123.830254ms","start":"2026-03-31T20:15:19.985686Z","end":"2026-03-31T20:15:20.109516Z","steps":["trace[1703048289] 'read index received' (duration: 101.938682ms)","trace[1703048289] 'applied index is now lower than readState.Index' (duration: 21.890552ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:20.109657Z","caller":"traceutil/trace.go:171","msg":"trace[1635029060] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"158.982763ms","start":"2026-03-31T20:15:19.950647Z","end":"2026-03-31T20:15:20.10963Z","steps":["trace[1635029060] 'process raft request' (duration: 136.915617ms)","trace[1635029060] 'compare' (duration: 21.833871ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:20.109927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.202883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:20.110018Z","caller":"traceutil/trace.go:171","msg":"trace[1146617508] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:507; }","duration":"124.367166ms","start":"2026-03-31T20:15:19.985628Z","end":"2026-03-31T20:15:20.109996Z","steps":["trace[1146617508] 'agreement among raft nodes before linearized reading' (duration: 124.166041ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.262657Z","caller":"traceutil/trace.go:171","msg":"trace[902559532] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"252.550361ms","start":"2026-03-31T20:15:20.010077Z","end":"2026-03-31T20:15:20.262627Z","steps":["trace[902559532] 'process raft request' (duration: 205.575307ms)","trace[902559532] 'compare' (duration: 46.76356ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:20.268065Z","caller":"traceutil/trace.go:171","msg":"trace[2005997193] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"154.188242ms","start":"2026-03-31T20:15:20.113852Z","end":"2026-03-31T20:15:20.26804Z","steps":["trace[2005997193] 'process raft request' (duration: 152.741559ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.26813Z","caller":"traceutil/trace.go:171","msg":"trace[918995573] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"151.861741ms","start":"2026-03-31T20:15:20.116245Z","end":"2026-03-31T20:15:20.268106Z","steps":["trace[918995573] 'process raft request' (duration: 151.798199ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.268517Z","caller":"traceutil/trace.go:171","msg":"trace[151197543] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"152.990526ms","start":"2026-03-31T20:15:20.115516Z","end":"2026-03-31T20:15:20.268507Z","steps":["trace[151197543] 'process raft request' (duration: 152.487534ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.269269Z","caller":"traceutil/trace.go:171","msg":"trace[1419200347] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"155.062192ms","start":"2026-03-31T20:15:20.114181Z","end":"2026-03-31T20:15:20.269243Z","steps":["trace[1419200347] 'process raft request' (duration: 153.727192ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.422034Z","caller":"traceutil/trace.go:171","msg":"trace[1493598888] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"146.638474ms","start":"2026-03-31T20:15:20.275351Z","end":"2026-03-31T20:15:20.42199Z","steps":["trace[1493598888] 'process raft request' (duration: 106.753978ms)","trace[1493598888] 'compare' (duration: 39.745493ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:20.444412Z","caller":"traceutil/trace.go:171","msg":"trace[649832475] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"168.925044ms","start":"2026-03-31T20:15:20.275446Z","end":"2026-03-31T20:15:20.444371Z","steps":["trace[649832475] 'process raft request' (duration: 168.701678ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.444591Z","caller":"traceutil/trace.go:171","msg":"trace[244381758] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"168.828632ms","start":"2026-03-31T20:15:20.275732Z","end":"2026-03-31T20:15:20.444561Z","steps":["trace[244381758] 'process raft request' (duration: 168.585516ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.444593Z","caller":"traceutil/trace.go:171","msg":"trace[436028727] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"169.081507ms","start":"2026-03-31T20:15:20.275496Z","end":"2026-03-31T20:15:20.444577Z","steps":["trace[436028727] 'process raft request' (duration: 168.76552ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.44461Z","caller":"traceutil/trace.go:171","msg":"trace[1347723436] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:528; }","duration":"169.082097ms","start":"2026-03-31T20:15:20.275508Z","end":"2026-03-31T20:15:20.44459Z","steps":["trace[1347723436] 'read index received' (duration: 106.624025ms)","trace[1347723436] 'applied index is now lower than readState.Index' (duration: 62.457142ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:20.444772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.241641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-31T20:15:20.444811Z","caller":"traceutil/trace.go:171","msg":"trace[859661591] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:518; }","duration":"169.319893ms","start":"2026-03-31T20:15:20.275483Z","end":"2026-03-31T20:15:20.444803Z","steps":["trace[859661591] 'agreement among raft nodes before linearized reading' (duration: 169.18258ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.444833Z","caller":"traceutil/trace.go:171","msg":"trace[1466640641] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"166.368886ms","start":"2026-03-31T20:15:20.278428Z","end":"2026-03-31T20:15:20.444797Z","steps":["trace[1466640641] 'process raft request' (duration: 165.992378ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.444856Z","caller":"traceutil/trace.go:171","msg":"trace[813183221] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"166.333016ms","start":"2026-03-31T20:15:20.278486Z","end":"2026-03-31T20:15:20.444819Z","steps":["trace[813183221] 'process raft request' (duration: 166.040659ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.599793Z","caller":"traceutil/trace.go:171","msg":"trace[1620387422] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"155.011391ms","start":"2026-03-31T20:15:20.444756Z","end":"2026-03-31T20:15:20.599767Z","steps":["trace[1620387422] 'read index received' (duration: 143.652426ms)","trace[1620387422] 'applied index is now lower than readState.Index' (duration: 11.357665ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:20.599868Z","caller":"traceutil/trace.go:171","msg":"trace[668267489] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"169.410295ms","start":"2026-03-31T20:15:20.430418Z","end":"2026-03-31T20:15:20.599829Z","steps":["trace[668267489] 'process raft request' (duration: 158.053549ms)","trace[668267489] 'compare' (duration: 11.173962ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:20.600282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.848977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-03-31T20:15:20.600327Z","caller":"traceutil/trace.go:171","msg":"trace[1835188105] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:519; }","duration":"264.97055ms","start":"2026-03-31T20:15:20.335344Z","end":"2026-03-31T20:15:20.600314Z","steps":["trace[1835188105] 'agreement among raft nodes before linearized reading' (duration: 264.535971ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.637071Z","caller":"traceutil/trace.go:171","msg":"trace[1832385790] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"185.272631ms","start":"2026-03-31T20:15:20.451772Z","end":"2026-03-31T20:15:20.637045Z","steps":["trace[1832385790] 'process raft request' (duration: 185.042226ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.637094Z","caller":"traceutil/trace.go:171","msg":"trace[1157336953] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"180.180956ms","start":"2026-03-31T20:15:20.4569Z","end":"2026-03-31T20:15:20.637081Z","steps":["trace[1157336953] 'process raft request' (duration: 180.039393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:20.637111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.866979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkxw9\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-03-31T20:15:20.637263Z","caller":"traceutil/trace.go:171","msg":"trace[605028715] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkxw9; range_end:; response_count:1; response_revision:521; }","duration":"184.048352ms","start":"2026-03-31T20:15:20.45319Z","end":"2026-03-31T20:15:20.637238Z","steps":["trace[605028715] 'agreement among raft nodes before linearized reading' (duration: 183.793307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:20.637709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.844416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14401"} {"level":"info","ts":"2026-03-31T20:15:20.637757Z","caller":"traceutil/trace.go:171","msg":"trace[1380128462] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:521; }","duration":"178.926638ms","start":"2026-03-31T20:15:20.458817Z","end":"2026-03-31T20:15:20.637744Z","steps":["trace[1380128462] 'agreement among raft nodes before linearized reading' (duration: 178.316104ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.756155Z","caller":"traceutil/trace.go:171","msg":"trace[630407662] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"112.525147ms","start":"2026-03-31T20:15:20.6436Z","end":"2026-03-31T20:15:20.756125Z","steps":["trace[630407662] 'process raft request' (duration: 67.074267ms)","trace[630407662] 'compare' (duration: 45.310487ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:20.771007Z","caller":"traceutil/trace.go:171","msg":"trace[1057271767] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"125.070318ms","start":"2026-03-31T20:15:20.645921Z","end":"2026-03-31T20:15:20.770991Z","steps":["trace[1057271767] 'process raft request' (duration: 124.72045ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.771045Z","caller":"traceutil/trace.go:171","msg":"trace[1526245856] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"121.240592ms","start":"2026-03-31T20:15:20.649783Z","end":"2026-03-31T20:15:20.771023Z","steps":["trace[1526245856] 'process raft request' (duration: 120.969736ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:20.771447Z","caller":"traceutil/trace.go:171","msg":"trace[126271210] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"114.566782ms","start":"2026-03-31T20:15:20.656861Z","end":"2026-03-31T20:15:20.771428Z","steps":["trace[126271210] 'process raft request' (duration: 113.981999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:21.20058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.907442ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T20:15:21.949515Z","caller":"traceutil/trace.go:171","msg":"trace[2090927396] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"101.965794ms","start":"2026-03-31T20:15:21.847515Z","end":"2026-03-31T20:15:21.949481Z","steps":["trace[2090927396] 'process raft request' (duration: 36.336578ms)","trace[2090927396] 'compare' (duration: 65.451932ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:22.257835Z","caller":"traceutil/trace.go:171","msg":"trace[278315365] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"130.260771ms","start":"2026-03-31T20:15:22.127549Z","end":"2026-03-31T20:15:22.257809Z","steps":["trace[278315365] 'process raft request' (duration: 77.364608ms)","trace[278315365] 'compare' (duration: 52.76136ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:22.26865Z","caller":"traceutil/trace.go:171","msg":"trace[622501165] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"137.309884ms","start":"2026-03-31T20:15:22.131326Z","end":"2026-03-31T20:15:22.268636Z","steps":["trace[622501165] 'process raft request' (duration: 136.642139ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.268761Z","caller":"traceutil/trace.go:171","msg":"trace[1648560796] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"134.723274ms","start":"2026-03-31T20:15:22.134031Z","end":"2026-03-31T20:15:22.268754Z","steps":["trace[1648560796] 'process raft request' (duration: 134.020768ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.268857Z","caller":"traceutil/trace.go:171","msg":"trace[1639663154] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"120.842343ms","start":"2026-03-31T20:15:22.147985Z","end":"2026-03-31T20:15:22.268828Z","steps":["trace[1639663154] 'process raft request' (duration: 120.105236ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.268933Z","caller":"traceutil/trace.go:171","msg":"trace[1820834952] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"112.896709ms","start":"2026-03-31T20:15:22.15603Z","end":"2026-03-31T20:15:22.268927Z","steps":["trace[1820834952] 'process raft request' (duration: 112.134382ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.269002Z","caller":"traceutil/trace.go:171","msg":"trace[410857380] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"112.91988ms","start":"2026-03-31T20:15:22.156077Z","end":"2026-03-31T20:15:22.268997Z","steps":["trace[410857380] 'process raft request' (duration: 112.146052ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.269073Z","caller":"traceutil/trace.go:171","msg":"trace[1230496168] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"102.314626ms","start":"2026-03-31T20:15:22.166753Z","end":"2026-03-31T20:15:22.269067Z","steps":["trace[1230496168] 'process raft request' (duration: 101.526328ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.269143Z","caller":"traceutil/trace.go:171","msg":"trace[526439023] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"102.338186ms","start":"2026-03-31T20:15:22.166798Z","end":"2026-03-31T20:15:22.269136Z","steps":["trace[526439023] 'process raft request' (duration: 101.522768ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.406693Z","caller":"traceutil/trace.go:171","msg":"trace[624082301] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"127.430466ms","start":"2026-03-31T20:15:22.279251Z","end":"2026-03-31T20:15:22.406681Z","steps":["trace[624082301] 'process raft request' (duration: 127.365794ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.406718Z","caller":"traceutil/trace.go:171","msg":"trace[757846365] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"127.493277ms","start":"2026-03-31T20:15:22.279207Z","end":"2026-03-31T20:15:22.4067Z","steps":["trace[757846365] 'process raft request' (duration: 127.339234ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.406669Z","caller":"traceutil/trace.go:171","msg":"trace[786186655] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"127.482307ms","start":"2026-03-31T20:15:22.279149Z","end":"2026-03-31T20:15:22.406631Z","steps":["trace[786186655] 'process raft request' (duration: 110.164856ms)","trace[786186655] 'compare' (duration: 17.074375ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:22.407017Z","caller":"traceutil/trace.go:171","msg":"trace[103261081] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"127.62888ms","start":"2026-03-31T20:15:22.279339Z","end":"2026-03-31T20:15:22.406968Z","steps":["trace[103261081] 'process raft request' (duration: 127.297363ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.407085Z","caller":"traceutil/trace.go:171","msg":"trace[1446958043] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"123.71964ms","start":"2026-03-31T20:15:22.28331Z","end":"2026-03-31T20:15:22.40703Z","steps":["trace[1446958043] 'process raft request' (duration: 123.556676ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.407271Z","caller":"traceutil/trace.go:171","msg":"trace[857028917] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"119.81244ms","start":"2026-03-31T20:15:22.287445Z","end":"2026-03-31T20:15:22.407258Z","steps":["trace[857028917] 'process raft request' (duration: 119.540794ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.407469Z","caller":"traceutil/trace.go:171","msg":"trace[1295101945] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"117.997027ms","start":"2026-03-31T20:15:22.289457Z","end":"2026-03-31T20:15:22.407454Z","steps":["trace[1295101945] 'process raft request' (duration: 117.757372ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.40752Z","caller":"traceutil/trace.go:171","msg":"trace[1192721987] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"102.372236ms","start":"2026-03-31T20:15:22.30512Z","end":"2026-03-31T20:15:22.407492Z","steps":["trace[1192721987] 'process raft request' (duration: 102.303525ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.407599Z","caller":"traceutil/trace.go:171","msg":"trace[491934995] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"112.780136ms","start":"2026-03-31T20:15:22.294804Z","end":"2026-03-31T20:15:22.407585Z","steps":["trace[491934995] 'process raft request' (duration: 112.528481ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.648848Z","caller":"traceutil/trace.go:171","msg":"trace[880698559] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"166.174171ms","start":"2026-03-31T20:15:22.482655Z","end":"2026-03-31T20:15:22.648829Z","steps":["trace[880698559] 'process raft request' (duration: 166.11416ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.64888Z","caller":"traceutil/trace.go:171","msg":"trace[1131147839] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"171.136746ms","start":"2026-03-31T20:15:22.47771Z","end":"2026-03-31T20:15:22.648847Z","steps":["trace[1131147839] 'process raft request' (duration: 146.525478ms)","trace[1131147839] 'compare' (duration: 24.387633ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:22.724644Z","caller":"traceutil/trace.go:171","msg":"trace[1863941630] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"215.84887ms","start":"2026-03-31T20:15:22.50878Z","end":"2026-03-31T20:15:22.724629Z","steps":["trace[1863941630] 'process raft request' (duration: 215.812259ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.724813Z","caller":"traceutil/trace.go:171","msg":"trace[3447738] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"220.738133ms","start":"2026-03-31T20:15:22.504051Z","end":"2026-03-31T20:15:22.724789Z","steps":["trace[3447738] 'process raft request' (duration: 220.357883ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:22.863112Z","caller":"traceutil/trace.go:171","msg":"trace[788482915] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"132.549333ms","start":"2026-03-31T20:15:22.730536Z","end":"2026-03-31T20:15:22.863085Z","steps":["trace[788482915] 'process raft request' (duration: 74.370158ms)","trace[788482915] 'compare' (duration: 57.982061ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:22.863462Z","caller":"traceutil/trace.go:171","msg":"trace[1701509134] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"107.371332ms","start":"2026-03-31T20:15:22.756076Z","end":"2026-03-31T20:15:22.863447Z","steps":["trace[1701509134] 'process raft request' (duration: 106.957262ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:23.041182Z","caller":"traceutil/trace.go:171","msg":"trace[2424625] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"157.067144ms","start":"2026-03-31T20:15:22.884075Z","end":"2026-03-31T20:15:23.041142Z","steps":["trace[2424625] 'process raft request' (duration: 79.372946ms)","trace[2424625] 'compare' (duration: 77.411111ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:23.041479Z","caller":"traceutil/trace.go:171","msg":"trace[1457592110] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"106.760001ms","start":"2026-03-31T20:15:22.934691Z","end":"2026-03-31T20:15:23.041451Z","steps":["trace[1457592110] 'process raft request' (duration: 106.32581ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:23.283203Z","caller":"traceutil/trace.go:171","msg":"trace[1447021995] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"142.08833ms","start":"2026-03-31T20:15:23.141098Z","end":"2026-03-31T20:15:23.283186Z","steps":["trace[1447021995] 'process raft request' (duration: 141.997958ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:23.452795Z","caller":"traceutil/trace.go:171","msg":"trace[163555868] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"206.224843ms","start":"2026-03-31T20:15:23.246541Z","end":"2026-03-31T20:15:23.452765Z","steps":["trace[163555868] 'process raft request' (duration: 112.102448ms)","trace[163555868] 'compare' (duration: 93.982692ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:23.472485Z","caller":"traceutil/trace.go:171","msg":"trace[949428897] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"172.515944ms","start":"2026-03-31T20:15:23.299935Z","end":"2026-03-31T20:15:23.472451Z","steps":["trace[949428897] 'process raft request' (duration: 172.304709ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:23.472518Z","caller":"traceutil/trace.go:171","msg":"trace[1278693950] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"167.64976ms","start":"2026-03-31T20:15:23.304852Z","end":"2026-03-31T20:15:23.472502Z","steps":["trace[1278693950] 'process raft request' (duration: 167.503595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:23.707832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.423298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:15:23.708602Z","caller":"traceutil/trace.go:171","msg":"trace[1733985850] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"306.661059ms","start":"2026-03-31T20:15:23.401906Z","end":"2026-03-31T20:15:23.708567Z","steps":["trace[1733985850] 'process raft request' (duration: 166.327659ms)","trace[1733985850] 'compare' (duration: 138.810464ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:23.708738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:23.401885Z","time spent":"306.782291ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:15:23.783907Z","caller":"traceutil/trace.go:171","msg":"trace[1564656288] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"289.347403ms","start":"2026-03-31T20:15:23.494535Z","end":"2026-03-31T20:15:23.783882Z","steps":["trace[1564656288] 'process raft request' (duration: 289.246581ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:23.784261Z","caller":"traceutil/trace.go:171","msg":"trace[1590217843] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"306.428713ms","start":"2026-03-31T20:15:23.477796Z","end":"2026-03-31T20:15:23.784225Z","steps":["trace[1590217843] 'process raft request' (duration: 305.720327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:23.784448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:23.477768Z","time spent":"306.585047ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:24.071324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.866456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:15:24.071487Z","caller":"traceutil/trace.go:171","msg":"trace[1105220792] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"272.804226ms","start":"2026-03-31T20:15:23.798657Z","end":"2026-03-31T20:15:24.071461Z","steps":["trace[1105220792] 'process raft request' (duration: 75.718445ms)","trace[1105220792] 'compare' (duration: 196.716643ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:24.07177Z","caller":"traceutil/trace.go:171","msg":"trace[882967604] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"265.31247ms","start":"2026-03-31T20:15:23.806415Z","end":"2026-03-31T20:15:24.071728Z","steps":["trace[882967604] 'process raft request' (duration: 265.028864ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:24.071842Z","caller":"traceutil/trace.go:171","msg":"trace[860226256] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:609; }","duration":"189.828531ms","start":"2026-03-31T20:15:23.881996Z","end":"2026-03-31T20:15:24.071825Z","steps":["trace[860226256] 'read index received' (duration: 157.919383ms)","trace[860226256] 'applied index is now lower than readState.Index' (duration: 31.908268ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:24.071924Z","caller":"traceutil/trace.go:171","msg":"trace[215609280] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"235.85626ms","start":"2026-03-31T20:15:23.836059Z","end":"2026-03-31T20:15:24.071915Z","steps":["trace[215609280] 'process raft request' (duration: 235.464601ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:24.072237Z","caller":"traceutil/trace.go:171","msg":"trace[802280751] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"203.329328ms","start":"2026-03-31T20:15:23.868865Z","end":"2026-03-31T20:15:24.072195Z","steps":["trace[802280751] 'process raft request' (duration: 202.860016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:24.072308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.306856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:15:24.072427Z","caller":"traceutil/trace.go:171","msg":"trace[1228523803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"118.47822ms","start":"2026-03-31T20:15:23.953919Z","end":"2026-03-31T20:15:24.072397Z","steps":["trace[1228523803] 'agreement among raft nodes before linearized reading' (duration: 118.176523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:24.072399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.349844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:15:24.072494Z","caller":"traceutil/trace.go:171","msg":"trace[1179766860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"190.479487ms","start":"2026-03-31T20:15:23.881992Z","end":"2026-03-31T20:15:24.072471Z","steps":["trace[1179766860] 'agreement among raft nodes before linearized reading' (duration: 190.18138ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:24.342286Z","caller":"traceutil/trace.go:171","msg":"trace[2145008393] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"195.841773ms","start":"2026-03-31T20:15:24.146421Z","end":"2026-03-31T20:15:24.342263Z","steps":["trace[2145008393] 'process raft request' (duration: 153.379714ms)","trace[2145008393] 'compare' (duration: 42.297805ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:24.364944Z","caller":"traceutil/trace.go:171","msg":"trace[1162641029] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"152.128724ms","start":"2026-03-31T20:15:24.212795Z","end":"2026-03-31T20:15:24.364924Z","steps":["trace[1162641029] 'process raft request' (duration: 151.94747ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:24.364999Z","caller":"traceutil/trace.go:171","msg":"trace[1565999146] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"185.812634ms","start":"2026-03-31T20:15:24.179161Z","end":"2026-03-31T20:15:24.364973Z","steps":["trace[1565999146] 'process raft request' (duration: 185.265692ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:24.736927Z","caller":"traceutil/trace.go:171","msg":"trace[739184676] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"376.275449ms","start":"2026-03-31T20:15:24.360617Z","end":"2026-03-31T20:15:24.736893Z","steps":["trace[739184676] 'process raft request' (duration: 361.5733ms)","trace[739184676] 'compare' (duration: 14.419873ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:24.737211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:24.360593Z","time spent":"376.431504ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:25.262506Z","caller":"traceutil/trace.go:171","msg":"trace[1399732805] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"862.022541ms","start":"2026-03-31T20:15:24.400446Z","end":"2026-03-31T20:15:25.262469Z","steps":["trace[1399732805] 'read index received' (duration: 321.809866ms)","trace[1399732805] 'applied index is now lower than readState.Index' (duration: 540.211625ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:25.262732Z","caller":"traceutil/trace.go:171","msg":"trace[2017240453] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"872.322238ms","start":"2026-03-31T20:15:24.390337Z","end":"2026-03-31T20:15:25.262659Z","steps":["trace[2017240453] 'process raft request' (duration: 797.607619ms)","trace[2017240453] 'compare' (duration: 74.230826ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:25.263077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:24.39032Z","time spent":"872.580655ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:25.263268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.797021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-03-31T20:15:25.263353Z","caller":"traceutil/trace.go:171","msg":"trace[626500954] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:600; }","duration":"862.932775ms","start":"2026-03-31T20:15:24.4004Z","end":"2026-03-31T20:15:25.263332Z","steps":["trace[626500954] 'agreement among raft nodes before linearized reading' (duration: 862.266799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:25.263407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:24.400383Z","time spent":"863.014286ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-03-31T20:15:25.342978Z","caller":"traceutil/trace.go:171","msg":"trace[45624668] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"579.988267ms","start":"2026-03-31T20:15:24.762949Z","end":"2026-03-31T20:15:25.342938Z","steps":["trace[45624668] 'process raft request' (duration: 579.753421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:25.343324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:24.762924Z","time spent":"580.225732ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:25.343004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.017161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:25.343654Z","caller":"traceutil/trace.go:171","msg":"trace[1555310971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"932.725608ms","start":"2026-03-31T20:15:24.410913Z","end":"2026-03-31T20:15:25.343639Z","steps":["trace[1555310971] 'agreement among raft nodes before linearized reading' (duration: 931.999691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:25.343764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:24.410896Z","time spent":"932.854881ms","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:15:25.6227Z","caller":"traceutil/trace.go:171","msg":"trace[722812502] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"279.779035ms","start":"2026-03-31T20:15:25.342887Z","end":"2026-03-31T20:15:25.622666Z","steps":["trace[722812502] 'read index received' (duration: 279.334214ms)","trace[722812502] 'applied index is now lower than readState.Index' (duration: 443.531µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:25.622864Z","caller":"traceutil/trace.go:171","msg":"trace[1347900400] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"332.949335ms","start":"2026-03-31T20:15:25.289904Z","end":"2026-03-31T20:15:25.622853Z","steps":["trace[1347900400] 'process raft request' (duration: 332.465244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:25.62303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:25.289888Z","time spent":"333.011917ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:25.623178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.275297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-03-31T20:15:25.623276Z","caller":"traceutil/trace.go:171","msg":"trace[696310355] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:602; }","duration":"339.434301ms","start":"2026-03-31T20:15:25.283815Z","end":"2026-03-31T20:15:25.62325Z","steps":["trace[696310355] 'agreement among raft nodes before linearized reading' (duration: 339.129264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:25.623328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:25.283793Z","time spent":"339.524923ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10893,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-03-31T20:15:26.123229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:26.624306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:26.795546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.234992ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:26.795738Z","caller":"traceutil/trace.go:171","msg":"trace[322644943] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:602; }","duration":"622.444147ms","start":"2026-03-31T20:15:26.173271Z","end":"2026-03-31T20:15:26.795715Z","steps":["trace[322644943] 'range keys from in-memory index tree' (duration: 622.214312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:26.905632Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.283308872s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:15:26.906381Z","caller":"traceutil/trace.go:171","msg":"trace[1956564874] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.520288067s","start":"2026-03-31T20:15:25.386058Z","end":"2026-03-31T20:15:26.906346Z","steps":["trace[1956564874] 'process raft request' (duration: 1.51998665s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:26.906674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:25.386032Z","time spent":"1.520512893s","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:27.33015Z","caller":"traceutil/trace.go:171","msg":"trace[1023302062] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.692595537s","start":"2026-03-31T20:15:25.637488Z","end":"2026-03-31T20:15:27.330083Z","steps":["trace[1023302062] 'process raft request' (duration: 1.614760835s)","trace[1023302062] 'compare' (duration: 77.564535ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:27.330249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.983817029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:15:27.330311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854551406s","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-03-31T20:15:27.33034Z","caller":"traceutil/trace.go:171","msg":"trace[633747068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"1.983945362s","start":"2026-03-31T20:15:25.346373Z","end":"2026-03-31T20:15:27.330318Z","steps":["trace[633747068] 'agreement among raft nodes before linearized reading' (duration: 1.983813919s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.330389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:25.346355Z","time spent":"1.984025833s","remote":"127.0.0.1:53698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:15:27.330402Z","caller":"traceutil/trace.go:171","msg":"trace[2114860364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"1.85472218s","start":"2026-03-31T20:15:25.47565Z","end":"2026-03-31T20:15:27.330372Z","steps":["trace[2114860364] 'agreement among raft nodes before linearized reading' (duration: 1.854440603s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.330455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:25.475622Z","time spent":"1.854822032s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T20:15:27.330185Z","caller":"traceutil/trace.go:171","msg":"trace[849671488] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"1.707263671s","start":"2026-03-31T20:15:25.622804Z","end":"2026-03-31T20:15:27.330068Z","steps":["trace[849671488] 'read index received' (duration: 1.283319234s)","trace[849671488] 'applied index is now lower than readState.Index' (duration: 423.942057ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:27.331993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:25.637447Z","time spent":"1.692857174s","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:27.419896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338816702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:15:27.420032Z","caller":"traceutil/trace.go:171","msg":"trace[354928458] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"478.981106ms","start":"2026-03-31T20:15:26.941026Z","end":"2026-03-31T20:15:27.420007Z","steps":["trace[354928458] 'process raft request' (duration: 478.420972ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:27.420068Z","caller":"traceutil/trace.go:171","msg":"trace[1008192737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"1.339049967s","start":"2026-03-31T20:15:26.080998Z","end":"2026-03-31T20:15:27.420048Z","steps":["trace[1008192737] 'agreement among raft nodes before linearized reading' (duration: 1.338721209s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.420162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:26.080977Z","time spent":"1.33917627s","remote":"127.0.0.1:53942","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-03-31T20:15:27.419935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334301452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:15:27.420364Z","caller":"traceutil/trace.go:171","msg":"trace[2056226434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"1.334760343s","start":"2026-03-31T20:15:26.085576Z","end":"2026-03-31T20:15:27.420336Z","steps":["trace[2056226434] 'agreement among raft nodes before linearized reading' (duration: 1.334148189s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.420427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785591332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"warn","ts":"2026-03-31T20:15:27.420165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:26.940994Z","time spent":"479.1011ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:27.419887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771236446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T20:15:27.420471Z","caller":"traceutil/trace.go:171","msg":"trace[1443930521] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"1.785653693s","start":"2026-03-31T20:15:25.634805Z","end":"2026-03-31T20:15:27.420458Z","steps":["trace[1443930521] 'agreement among raft nodes before linearized reading' (duration: 1.78551436s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.42052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:25.634788Z","time spent":"1.785724255s","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":59654,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-03-31T20:15:27.420544Z","caller":"traceutil/trace.go:171","msg":"trace[1203542950] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:605; }","duration":"1.771913562s","start":"2026-03-31T20:15:25.648584Z","end":"2026-03-31T20:15:27.420497Z","steps":["trace[1203542950] 'agreement among raft nodes before linearized reading' (duration: 1.771187235s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.420449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:26.085567Z","time spent":"1.334856836s","remote":"127.0.0.1:53942","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-03-31T20:15:27.42063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:25.648563Z","time spent":"1.772026166s","remote":"127.0.0.1:53712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.141\" "} {"level":"warn","ts":"2026-03-31T20:15:27.420687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333053721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:15:27.420724Z","caller":"traceutil/trace.go:171","msg":"trace[1507824977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"1.333154774s","start":"2026-03-31T20:15:26.08756Z","end":"2026-03-31T20:15:27.420715Z","steps":["trace[1507824977] 'agreement among raft nodes before linearized reading' (duration: 1.332634101s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.420753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:26.087528Z","time spent":"1.333218855s","remote":"127.0.0.1:53942","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-03-31T20:15:27.688947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.015123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:15:27.689105Z","caller":"traceutil/trace.go:171","msg":"trace[1534689238] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"352.313307ms","start":"2026-03-31T20:15:27.336758Z","end":"2026-03-31T20:15:27.689071Z","steps":["trace[1534689238] 'process raft request' (duration: 166.07448ms)","trace[1534689238] 'compare' (duration: 185.848628ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:27.689264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:27.336702Z","time spent":"352.472122ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:27.690109Z","caller":"traceutil/trace.go:171","msg":"trace[1644637601] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"269.786099ms","start":"2026-03-31T20:15:27.419685Z","end":"2026-03-31T20:15:27.689472Z","steps":["trace[1644637601] 'read index received' (duration: 83.207553ms)","trace[1644637601] 'applied index is now lower than readState.Index' (duration: 186.576806ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:27.690451Z","caller":"traceutil/trace.go:171","msg":"trace[1957930805] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"262.637604ms","start":"2026-03-31T20:15:27.427264Z","end":"2026-03-31T20:15:27.689901Z","steps":["trace[1957930805] 'process raft request' (duration: 261.968097ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:27.690699Z","caller":"traceutil/trace.go:171","msg":"trace[45242348] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"263.28526ms","start":"2026-03-31T20:15:27.427353Z","end":"2026-03-31T20:15:27.690638Z","steps":["trace[45242348] 'process raft request' (duration: 261.914916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.692032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.015995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:27.692117Z","caller":"traceutil/trace.go:171","msg":"trace[767130391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"353.206539ms","start":"2026-03-31T20:15:27.338892Z","end":"2026-03-31T20:15:27.692099Z","steps":["trace[767130391] 'agreement among raft nodes before linearized reading' (duration: 352.534363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.692171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:27.338868Z","time spent":"353.292252ms","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:15:27.692473Z","caller":"traceutil/trace.go:171","msg":"trace[1248537200] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"265.771391ms","start":"2026-03-31T20:15:27.42666Z","end":"2026-03-31T20:15:27.692432Z","steps":["trace[1248537200] 'process raft request' (duration: 262.517801ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:27.693398Z","caller":"traceutil/trace.go:171","msg":"trace[776546935] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"249.696495ms","start":"2026-03-31T20:15:27.443651Z","end":"2026-03-31T20:15:27.693348Z","steps":["trace[776546935] 'process raft request' (duration: 245.657875ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:27.695368Z","caller":"traceutil/trace.go:171","msg":"trace[1099355978] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"196.323737ms","start":"2026-03-31T20:15:27.499023Z","end":"2026-03-31T20:15:27.695347Z","steps":["trace[1099355978] 'process raft request' (duration: 190.440371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:27.69559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.080244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-03-31T20:15:27.695675Z","caller":"traceutil/trace.go:171","msg":"trace[772884830] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:611; }","duration":"250.208947ms","start":"2026-03-31T20:15:27.445439Z","end":"2026-03-31T20:15:27.695648Z","steps":["trace[772884830] 'agreement among raft nodes before linearized reading' (duration: 249.91454ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:28.222833Z","caller":"traceutil/trace.go:171","msg":"trace[927561488] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"461.517819ms","start":"2026-03-31T20:15:27.761273Z","end":"2026-03-31T20:15:28.222791Z","steps":["trace[927561488] 'read index received' (duration: 427.574501ms)","trace[927561488] 'applied index is now lower than readState.Index' (duration: 33.941748ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:28.222923Z","caller":"traceutil/trace.go:171","msg":"trace[906351595] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"475.916994ms","start":"2026-03-31T20:15:27.746936Z","end":"2026-03-31T20:15:28.222852Z","steps":["trace[906351595] 'process raft request' (duration: 441.916124ms)","trace[906351595] 'compare' (duration: 33.776924ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:28.223054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.753185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-03-31T20:15:28.223098Z","caller":"traceutil/trace.go:171","msg":"trace[2110549663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:613; }","duration":"461.830597ms","start":"2026-03-31T20:15:27.761253Z","end":"2026-03-31T20:15:28.223083Z","steps":["trace[2110549663] 'agreement among raft nodes before linearized reading' (duration: 461.655072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:28.223139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:27.761243Z","time spent":"461.884538ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7731,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-03-31T20:15:28.223217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:27.746912Z","time spent":"476.13132ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:28.295822Z","caller":"traceutil/trace.go:171","msg":"trace[1488875471] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"374.663336ms","start":"2026-03-31T20:15:27.921119Z","end":"2026-03-31T20:15:28.295782Z","steps":["trace[1488875471] 'process raft request' (duration: 373.346664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:28.29603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:27.921063Z","time spent":"374.849211ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:28.479472Z","caller":"traceutil/trace.go:171","msg":"trace[1752509506] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"171.960767ms","start":"2026-03-31T20:15:28.307476Z","end":"2026-03-31T20:15:28.479437Z","steps":["trace[1752509506] 'read index received' (duration: 105.23108ms)","trace[1752509506] 'applied index is now lower than readState.Index' (duration: 66.727987ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:28.479755Z","caller":"traceutil/trace.go:171","msg":"trace[703278252] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"218.789857ms","start":"2026-03-31T20:15:28.260943Z","end":"2026-03-31T20:15:28.479733Z","steps":["trace[703278252] 'process raft request' (duration: 151.548726ms)","trace[703278252] 'compare' (duration: 66.679737ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:28.480046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.535781ms","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-03-31T20:15:28.480246Z","caller":"traceutil/trace.go:171","msg":"trace[632176584] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:615; }","duration":"172.792038ms","start":"2026-03-31T20:15:28.307432Z","end":"2026-03-31T20:15:28.480224Z","steps":["trace[632176584] 'agreement among raft nodes before linearized reading' (duration: 172.295696ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:28.508715Z","caller":"traceutil/trace.go:171","msg":"trace[1238708803] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"168.267105ms","start":"2026-03-31T20:15:28.34042Z","end":"2026-03-31T20:15:28.508688Z","steps":["trace[1238708803] 'process raft request' (duration: 167.935217ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:28.859314Z","caller":"traceutil/trace.go:171","msg":"trace[1924646987] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"280.081348ms","start":"2026-03-31T20:15:28.579211Z","end":"2026-03-31T20:15:28.859292Z","steps":["trace[1924646987] 'read index received' (duration: 260.91495ms)","trace[1924646987] 'applied index is now lower than readState.Index' (duration: 19.165498ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:28.859425Z","caller":"traceutil/trace.go:171","msg":"trace[961752354] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"300.153099ms","start":"2026-03-31T20:15:28.559251Z","end":"2026-03-31T20:15:28.859404Z","steps":["trace[961752354] 'process raft request' (duration: 281.038433ms)","trace[961752354] 'compare' (duration: 18.881181ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:28.859942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.706135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-03-31T20:15:28.86003Z","caller":"traceutil/trace.go:171","msg":"trace[445516867] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:617; }","duration":"280.828877ms","start":"2026-03-31T20:15:28.579179Z","end":"2026-03-31T20:15:28.860008Z","steps":["trace[445516867] 'agreement among raft nodes before linearized reading' (duration: 280.201201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:28.860349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:28.559213Z","time spent":"300.329875ms","remote":"127.0.0.1:53726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:30.195199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:30.695758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:31.196041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307853,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:31.347314Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.710211164s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:15:31.34797Z","caller":"traceutil/trace.go:171","msg":"trace[1440104664] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.710994974s","start":"2026-03-31T20:15:29.636935Z","end":"2026-03-31T20:15:31.34793Z","steps":["trace[1440104664] 'process raft request' (duration: 1.710695997s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:31.348256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:29.636905Z","time spent":"1.71119764s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:31.394716Z","caller":"traceutil/trace.go:171","msg":"trace[196031681] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"1.699947682s","start":"2026-03-31T20:15:29.694736Z","end":"2026-03-31T20:15:31.394684Z","steps":["trace[196031681] 'read index received' (duration: 1.652856705s)","trace[196031681] 'applied index is now lower than readState.Index' (duration: 47.089297ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:31.39494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697440329s","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-03-31T20:15:31.394974Z","caller":"traceutil/trace.go:171","msg":"trace[299127018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.697515471s","start":"2026-03-31T20:15:29.69745Z","end":"2026-03-31T20:15:31.394965Z","steps":["trace[299127018] 'agreement among raft nodes before linearized reading' (duration: 1.697419639s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:31.395001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69746593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T20:15:31.395008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:29.697431Z","time spent":"1.697569302s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T20:15:31.395036Z","caller":"traceutil/trace.go:171","msg":"trace[948824470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.697506971s","start":"2026-03-31T20:15:29.69752Z","end":"2026-03-31T20:15:31.395027Z","steps":["trace[948824470] 'agreement among raft nodes before linearized reading' (duration: 1.697434099s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:31.395057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:29.697511Z","time spent":"1.697542321s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:15:31.395131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697226524s","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-03-31T20:15:31.395204Z","caller":"traceutil/trace.go:171","msg":"trace[1006044286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.697366968s","start":"2026-03-31T20:15:29.697821Z","end":"2026-03-31T20:15:31.395188Z","steps":["trace[1006044286] 'agreement among raft nodes before linearized reading' (duration: 1.697115951s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:31.395241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:29.697799Z","time spent":"1.697437299s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:15:31.395408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700170538s","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-03-31T20:15:31.395443Z","caller":"traceutil/trace.go:171","msg":"trace[742219845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"1.700736923s","start":"2026-03-31T20:15:29.694694Z","end":"2026-03-31T20:15:31.395431Z","steps":["trace[742219845] 'agreement among raft nodes before linearized reading' (duration: 1.700077025s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:31.395465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:29.694674Z","time spent":"1.700786034s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T20:15:32.199535Z","caller":"traceutil/trace.go:171","msg":"trace[107885595] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"287.704164ms","start":"2026-03-31T20:15:31.911802Z","end":"2026-03-31T20:15:32.199506Z","steps":["trace[107885595] 'process raft request' (duration: 268.116122ms)","trace[107885595] 'compare' (duration: 18.859453ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:32.851087Z","caller":"traceutil/trace.go:171","msg":"trace[2020604682] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"185.277103ms","start":"2026-03-31T20:15:32.665791Z","end":"2026-03-31T20:15:32.851068Z","steps":["trace[2020604682] 'process raft request' (duration: 184.701998ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:34.243387Z","caller":"traceutil/trace.go:171","msg":"trace[1688659569] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"136.866173ms","start":"2026-03-31T20:15:34.1065Z","end":"2026-03-31T20:15:34.243366Z","steps":["trace[1688659569] 'process raft request' (duration: 136.75842ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:34.9429Z","caller":"traceutil/trace.go:171","msg":"trace[1155896368] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"293.272457ms","start":"2026-03-31T20:15:34.649597Z","end":"2026-03-31T20:15:34.942869Z","steps":["trace[1155896368] 'process raft request' (duration: 292.120537ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:35.126389Z","caller":"traceutil/trace.go:171","msg":"trace[731178128] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"121.351732ms","start":"2026-03-31T20:15:35.004988Z","end":"2026-03-31T20:15:35.126339Z","steps":["trace[731178128] 'process raft request' (duration: 97.91542ms)","trace[731178128] 'compare' (duration: 23.228126ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:35.666614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.103279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:35.666754Z","caller":"traceutil/trace.go:171","msg":"trace[594016656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"254.318854ms","start":"2026-03-31T20:15:35.41241Z","end":"2026-03-31T20:15:35.666729Z","steps":["trace[594016656] 'range keys from in-memory index tree' (duration: 254.012657ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:35.677557Z","caller":"traceutil/trace.go:171","msg":"trace[1015672261] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"142.44278ms","start":"2026-03-31T20:15:35.535069Z","end":"2026-03-31T20:15:35.677511Z","steps":["trace[1015672261] 'process raft request' (duration: 141.967867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:37.128461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.986024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:37.128527Z","caller":"traceutil/trace.go:171","msg":"trace[380289179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"715.094007ms","start":"2026-03-31T20:15:36.413418Z","end":"2026-03-31T20:15:37.128512Z","steps":["trace[380289179] 'range keys from in-memory index tree' (duration: 714.915053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:37.128553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:36.4134Z","time spent":"715.148039ms","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:15:37.137534Z","caller":"traceutil/trace.go:171","msg":"trace[1392097560] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"462.33256ms","start":"2026-03-31T20:15:36.675119Z","end":"2026-03-31T20:15:37.137451Z","steps":["trace[1392097560] 'process raft request' (duration: 461.726073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:37.137896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:36.675089Z","time spent":"462.682ms","remote":"127.0.0.1:53776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:40.620831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.607231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkxw9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-31T20:15:40.620908Z","caller":"traceutil/trace.go:171","msg":"trace[1517404284] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkxw9; range_end:; response_count:1; response_revision:666; }","duration":"162.737504ms","start":"2026-03-31T20:15:40.458152Z","end":"2026-03-31T20:15:40.620889Z","steps":["trace[1517404284] 'range keys from in-memory index tree' (duration: 162.456027ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:41.080015Z","caller":"traceutil/trace.go:171","msg":"trace[1642536221] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"444.821319ms","start":"2026-03-31T20:15:40.635156Z","end":"2026-03-31T20:15:41.079978Z","steps":["trace[1642536221] 'process raft request' (duration: 444.592222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:41.08067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:40.63513Z","time spent":"445.348793ms","remote":"127.0.0.1:53860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:15:41.846325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.741515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:41.846413Z","caller":"traceutil/trace.go:171","msg":"trace[780774000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"431.879ms","start":"2026-03-31T20:15:41.414512Z","end":"2026-03-31T20:15:41.846391Z","steps":["trace[780774000] 'range keys from in-memory index tree' (duration: 431.637173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:41.846451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:41.414491Z","time spent":"431.951321ms","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:15:41.893267Z","caller":"traceutil/trace.go:171","msg":"trace[1470869611] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"132.650028ms","start":"2026-03-31T20:15:41.760597Z","end":"2026-03-31T20:15:41.893247Z","steps":["trace[1470869611] 'read index received' (duration: 132.510484ms)","trace[1470869611] 'applied index is now lower than readState.Index' (duration: 138.454µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:15:41.893451Z","caller":"traceutil/trace.go:171","msg":"trace[1919974038] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"300.622741ms","start":"2026-03-31T20:15:41.592817Z","end":"2026-03-31T20:15:41.89344Z","steps":["trace[1919974038] 'process raft request' (duration: 300.23704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:41.893595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.327195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-31T20:15:41.893609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:41.592796Z","time spent":"300.719944ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:41.893673Z","caller":"traceutil/trace.go:171","msg":"trace[1092945724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"128.430808ms","start":"2026-03-31T20:15:41.765224Z","end":"2026-03-31T20:15:41.893655Z","steps":["trace[1092945724] 'agreement among raft nodes before linearized reading' (duration: 128.236873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:41.893836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.386278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:15:41.893868Z","caller":"traceutil/trace.go:171","msg":"trace[1960748804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"107.4565ms","start":"2026-03-31T20:15:41.786404Z","end":"2026-03-31T20:15:41.893861Z","steps":["trace[1960748804] 'agreement among raft nodes before linearized reading' (duration: 107.373888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:41.894044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.743488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:15:41.894135Z","caller":"traceutil/trace.go:171","msg":"trace[1230728457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"107.885472ms","start":"2026-03-31T20:15:41.786227Z","end":"2026-03-31T20:15:41.894113Z","steps":["trace[1230728457] 'agreement among raft nodes before linearized reading' (duration: 107.653826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:41.894416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.751469ms","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-03-31T20:15:41.894706Z","caller":"traceutil/trace.go:171","msg":"trace[200318303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"133.902214ms","start":"2026-03-31T20:15:41.760545Z","end":"2026-03-31T20:15:41.894447Z","steps":["trace[200318303] 'agreement among raft nodes before linearized reading' (duration: 133.209193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:42.742595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307963,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:43.242926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307963,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:43.743269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307963,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:44.244438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946307963,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:15:44.539369Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.539145732s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:15:44.539861Z","caller":"traceutil/trace.go:171","msg":"trace[1391421843] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"2.539705019s","start":"2026-03-31T20:15:42.000123Z","end":"2026-03-31T20:15:44.539828Z","steps":["trace[1391421843] 'process raft request' (duration: 2.539470872s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:44.540047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:42.000103Z","time spent":"2.539842842s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:15:44.723583Z","caller":"traceutil/trace.go:171","msg":"trace[1157970085] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"2.481939866s","start":"2026-03-31T20:15:42.24162Z","end":"2026-03-31T20:15:44.72356Z","steps":["trace[1157970085] 'read index received' (duration: 2.297966207s)","trace[1157970085] 'applied index is now lower than readState.Index' (duration: 183.970069ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:15:44.723812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.482162042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:44.723897Z","caller":"traceutil/trace.go:171","msg":"trace[2080292573] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:673; }","duration":"2.482297177s","start":"2026-03-31T20:15:42.24158Z","end":"2026-03-31T20:15:44.723877Z","steps":["trace[2080292573] 'agreement among raft nodes before linearized reading' (duration: 2.482123771s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:44.724076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:42.241563Z","time spent":"2.482496833s","remote":"127.0.0.1:53976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:15:44.724317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.159145876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T20:15:44.724356Z","caller":"traceutil/trace.go:171","msg":"trace[1552656108] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:673; }","duration":"2.159229239s","start":"2026-03-31T20:15:42.565116Z","end":"2026-03-31T20:15:44.724345Z","steps":["trace[1552656108] 'agreement among raft nodes before linearized reading' (duration: 2.159144426s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:44.724389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:42.5651Z","time spent":"2.15927725s","remote":"127.0.0.1:54190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:15:44.72448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.038839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:15:44.724523Z","caller":"traceutil/trace.go:171","msg":"trace[1584093944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"786.093612ms","start":"2026-03-31T20:15:43.938418Z","end":"2026-03-31T20:15:44.724511Z","steps":["trace[1584093944] 'agreement among raft nodes before linearized reading' (duration: 785.953548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:44.724551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311252658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:15:44.724554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:43.938412Z","time spent":"786.134803ms","remote":"127.0.0.1:53942","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-03-31T20:15:44.724577Z","caller":"traceutil/trace.go:171","msg":"trace[921368140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"1.311312049s","start":"2026-03-31T20:15:43.413258Z","end":"2026-03-31T20:15:44.72457Z","steps":["trace[921368140] 'agreement among raft nodes before linearized reading' (duration: 1.311274358s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:44.724594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:43.413232Z","time spent":"1.31135871s","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:15:44.724753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.999778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:15:44.724792Z","caller":"traceutil/trace.go:171","msg":"trace[720177447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:673; }","duration":"786.07342ms","start":"2026-03-31T20:15:43.938709Z","end":"2026-03-31T20:15:44.724783Z","steps":["trace[720177447] 'agreement among raft nodes before linearized reading' (duration: 785.976508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:44.724819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:43.938689Z","time spent":"786.123992ms","remote":"127.0.0.1:53942","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-03-31T20:15:44.724943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.545664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:15:44.724974Z","caller":"traceutil/trace.go:171","msg":"trace[1994761458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:673; }","duration":"786.594986ms","start":"2026-03-31T20:15:43.938371Z","end":"2026-03-31T20:15:44.724966Z","steps":["trace[1994761458] 'agreement among raft nodes before linearized reading' (duration: 786.518714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:44.725013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:43.938361Z","time spent":"786.634157ms","remote":"127.0.0.1:53942","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-03-31T20:15:44.724968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.886119ms","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-03-31T20:15:44.725162Z","caller":"traceutil/trace.go:171","msg":"trace[408459909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"811.133847ms","start":"2026-03-31T20:15:43.913999Z","end":"2026-03-31T20:15:44.725132Z","steps":["trace[408459909] 'agreement among raft nodes before linearized reading' (duration: 810.494108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:44.725216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:43.913977Z","time spent":"811.226999ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:15:47.897854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.430999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkxw9\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-03-31T20:15:47.897961Z","caller":"traceutil/trace.go:171","msg":"trace[1873605159] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkxw9; range_end:; response_count:1; response_revision:690; }","duration":"409.582033ms","start":"2026-03-31T20:15:47.488351Z","end":"2026-03-31T20:15:47.897933Z","steps":["trace[1873605159] 'range keys from in-memory index tree' (duration: 409.213572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:47.898021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:15:47.488327Z","time spent":"409.680887ms","remote":"127.0.0.1:53860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-wkxw9\" "} {"level":"info","ts":"2026-03-31T20:15:48.076941Z","caller":"traceutil/trace.go:171","msg":"trace[1784297585] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"164.389205ms","start":"2026-03-31T20:15:47.912524Z","end":"2026-03-31T20:15:48.076914Z","steps":["trace[1784297585] 'process raft request' (duration: 164.172779ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:48.777551Z","caller":"traceutil/trace.go:171","msg":"trace[315512430] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"111.312829ms","start":"2026-03-31T20:15:48.666214Z","end":"2026-03-31T20:15:48.777527Z","steps":["trace[315512430] 'process raft request' (duration: 103.479253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:51.083768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.315021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:15:51.083887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.231399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:51.083952Z","caller":"traceutil/trace.go:171","msg":"trace[1653358811] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:745; }","duration":"283.547558ms","start":"2026-03-31T20:15:50.800376Z","end":"2026-03-31T20:15:51.083923Z","steps":["trace[1653358811] 'range keys from in-memory index tree' (duration: 283.205208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:51.084145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.969503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:15:51.084196Z","caller":"traceutil/trace.go:171","msg":"trace[2109936919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:745; }","duration":"249.038216ms","start":"2026-03-31T20:15:50.835144Z","end":"2026-03-31T20:15:51.084183Z","steps":["trace[2109936919] 'range keys from in-memory index tree' (duration: 248.811939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:51.08423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.768837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:15:51.08432Z","caller":"traceutil/trace.go:171","msg":"trace[1019722316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:745; }","duration":"248.887362ms","start":"2026-03-31T20:15:50.835413Z","end":"2026-03-31T20:15:51.0843Z","steps":["trace[1019722316] 'range keys from in-memory index tree' (duration: 248.671595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:51.084381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.58159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:15:51.084481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.25261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:51.084489Z","caller":"traceutil/trace.go:171","msg":"trace[541206496] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:745; }","duration":"186.928131ms","start":"2026-03-31T20:15:50.897539Z","end":"2026-03-31T20:15:51.084467Z","steps":["trace[541206496] 'range keys from in-memory index tree' (duration: 186.448916ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:51.084556Z","caller":"traceutil/trace.go:171","msg":"trace[1258716259] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:745; }","duration":"283.340273ms","start":"2026-03-31T20:15:50.801196Z","end":"2026-03-31T20:15:51.084536Z","steps":["trace[1258716259] 'range keys from in-memory index tree' (duration: 283.194628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:51.084154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.191853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-03-31T20:15:51.084481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.527755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T20:15:51.084839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.333272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:15:51.084856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.628849ms","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-03-31T20:15:51.084879Z","caller":"traceutil/trace.go:171","msg":"trace[532769261] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:745; }","duration":"284.378724ms","start":"2026-03-31T20:15:50.800489Z","end":"2026-03-31T20:15:51.084868Z","steps":["trace[532769261] 'count revisions from in-memory index tree' (duration: 284.25265ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:51.084897Z","caller":"traceutil/trace.go:171","msg":"trace[1444313884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"261.690531ms","start":"2026-03-31T20:15:50.823195Z","end":"2026-03-31T20:15:51.084885Z","steps":["trace[1444313884] 'range keys from in-memory index tree' (duration: 260.703041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:51.084844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.816868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:15:51.084853Z","caller":"traceutil/trace.go:171","msg":"trace[938434481] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:745; }","duration":"237.902425ms","start":"2026-03-31T20:15:50.846922Z","end":"2026-03-31T20:15:51.084824Z","steps":["trace[938434481] 'count revisions from in-memory index tree' (duration: 237.473073ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:51.08497Z","caller":"traceutil/trace.go:171","msg":"trace[1985865204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"267.996564ms","start":"2026-03-31T20:15:50.816961Z","end":"2026-03-31T20:15:51.084958Z","steps":["trace[1985865204] 'range keys from in-memory index tree' (duration: 267.612352ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:51.084782Z","caller":"traceutil/trace.go:171","msg":"trace[698433881] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:745; }","duration":"176.834063ms","start":"2026-03-31T20:15:50.907926Z","end":"2026-03-31T20:15:51.084761Z","steps":["trace[698433881] 'range keys from in-memory index tree' (duration: 175.990967ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:15:51.083995Z","caller":"traceutil/trace.go:171","msg":"trace[803325553] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:745; }","duration":"186.388584ms","start":"2026-03-31T20:15:50.89758Z","end":"2026-03-31T20:15:51.083968Z","steps":["trace[803325553] 'count revisions from in-memory index tree' (duration: 186.134607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:15:51.085381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.326133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:15:51.085432Z","caller":"traceutil/trace.go:171","msg":"trace[629333609] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:745; }","duration":"187.382475ms","start":"2026-03-31T20:15:50.898037Z","end":"2026-03-31T20:15:51.08542Z","steps":["trace[629333609] 'range keys from in-memory index tree' (duration: 186.002572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:16:03.73348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946308380,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:16:04.233882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946308380,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:16:04.734375Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527789946308380,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:16:04.936214Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.704560619s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:16:04.936625Z","caller":"traceutil/trace.go:171","msg":"trace[1743925248] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"1.705056995s","start":"2026-03-31T20:16:03.231538Z","end":"2026-03-31T20:16:04.936595Z","steps":["trace[1743925248] 'process raft request' (duration: 1.70489703s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:16:04.936806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:03.23152Z","time spent":"1.70519241s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:16:05.175485Z","caller":"traceutil/trace.go:171","msg":"trace[439872669] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:869; }","duration":"1.942905789s","start":"2026-03-31T20:16:03.23255Z","end":"2026-03-31T20:16:05.175456Z","steps":["trace[439872669] 'read index received' (duration: 1.703888497s)","trace[439872669] 'applied index is now lower than readState.Index' (duration: 239.016382ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:16:05.175567Z","caller":"traceutil/trace.go:171","msg":"trace[1058800125] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"1.583638439s","start":"2026-03-31T20:16:03.591913Z","end":"2026-03-31T20:16:05.175551Z","steps":["trace[1058800125] 'process raft request' (duration: 1.583281258s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:16:05.175592Z","caller":"traceutil/trace.go:171","msg":"trace[310360331] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"506.169499ms","start":"2026-03-31T20:16:04.669413Z","end":"2026-03-31T20:16:05.175582Z","steps":["trace[310360331] 'process raft request' (duration: 505.977073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:16:05.175708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:03.591888Z","time spent":"1.583718392s","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:16:05.1757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:04.669383Z","time spent":"506.232352ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:16:05.175812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.942830666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-31T20:16:05.175894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.585341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-31T20:16:05.175903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943273321s","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-03-31T20:16:05.175908Z","caller":"traceutil/trace.go:171","msg":"trace[778950706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:842; }","duration":"1.942942239s","start":"2026-03-31T20:16:03.232942Z","end":"2026-03-31T20:16:05.175884Z","steps":["trace[778950706] 'agreement among raft nodes before linearized reading' (duration: 1.942690501s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:16:05.175955Z","caller":"traceutil/trace.go:171","msg":"trace[479697636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:842; }","duration":"664.684404ms","start":"2026-03-31T20:16:04.511255Z","end":"2026-03-31T20:16:05.175939Z","steps":["trace[479697636] 'agreement among raft nodes before linearized reading' (duration: 664.55ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:16:05.175966Z","caller":"traceutil/trace.go:171","msg":"trace[513533804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:842; }","duration":"1.943423147s","start":"2026-03-31T20:16:03.232526Z","end":"2026-03-31T20:16:05.175949Z","steps":["trace[513533804] 'agreement among raft nodes before linearized reading' (duration: 1.943109666s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:16:05.176007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:04.511232Z","time spent":"664.752596ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T20:16:05.176025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:03.232509Z","time spent":"1.943501719s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:16:05.176077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:03.232937Z","time spent":"1.943030283s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:16:05.176097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.924028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T20:16:05.176195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.80075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T20:16:05.176203Z","caller":"traceutil/trace.go:171","msg":"trace[1422696540] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:842; }","duration":"572.130895ms","start":"2026-03-31T20:16:04.604045Z","end":"2026-03-31T20:16:05.176176Z","steps":["trace[1422696540] 'agreement among raft nodes before linearized reading' (duration: 571.823955ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:16:05.176236Z","caller":"traceutil/trace.go:171","msg":"trace[447298957] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:842; }","duration":"874.881693ms","start":"2026-03-31T20:16:04.301342Z","end":"2026-03-31T20:16:05.176223Z","steps":["trace[447298957] 'agreement among raft nodes before linearized reading' (duration: 874.80178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:16:05.176267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:04.301323Z","time spent":"874.936334ms","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:16:05.176268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:04.604025Z","time spent":"572.226079ms","remote":"127.0.0.1:53856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:16:05.176279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.761702683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:16:05.176325Z","caller":"traceutil/trace.go:171","msg":"trace[776788596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"1.761803047s","start":"2026-03-31T20:16:03.414509Z","end":"2026-03-31T20:16:05.176312Z","steps":["trace[776788596] 'agreement among raft nodes before linearized reading' (duration: 1.761725884s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:16:05.176362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:03.414489Z","time spent":"1.761862598s","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:16:05.175285Z","caller":"traceutil/trace.go:171","msg":"trace[2113464956] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"1.941571205s","start":"2026-03-31T20:16:03.23369Z","end":"2026-03-31T20:16:05.175261Z","steps":["trace[2113464956] 'process raft request' (duration: 1.941343597s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:16:05.177216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:16:03.233679Z","time spent":"1.943351634s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:16:19.262922Z","caller":"traceutil/trace.go:171","msg":"trace[15308332] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"205.792227ms","start":"2026-03-31T20:16:19.057109Z","end":"2026-03-31T20:16:19.262902Z","steps":["trace[15308332] 'process raft request' (duration: 205.32981ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:16:19.26329Z","caller":"traceutil/trace.go:171","msg":"trace[424517935] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1031; }","duration":"105.226655ms","start":"2026-03-31T20:16:19.15804Z","end":"2026-03-31T20:16:19.263267Z","steps":["trace[424517935] 'read index received' (duration: 104.378646ms)","trace[424517935] 'applied index is now lower than readState.Index' (duration: 846.609µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:16:19.263376Z","caller":"traceutil/trace.go:171","msg":"trace[1013376134] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"205.832028ms","start":"2026-03-31T20:16:19.05753Z","end":"2026-03-31T20:16:19.263362Z","steps":["trace[1013376134] 'process raft request' (duration: 205.556328ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:16:19.263292Z","caller":"traceutil/trace.go:171","msg":"trace[1924296805] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"205.929481ms","start":"2026-03-31T20:16:19.057336Z","end":"2026-03-31T20:16:19.263265Z","steps":["trace[1924296805] 'process raft request' (duration: 205.501836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:16:19.263519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.462404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-g9xl5\" ","response":"range_response_count:1 size:2275"} {"level":"info","ts":"2026-03-31T20:16:19.263579Z","caller":"traceutil/trace.go:171","msg":"trace[354865936] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-g9xl5; range_end:; response_count:1; response_revision:1000; }","duration":"105.564358ms","start":"2026-03-31T20:16:19.157998Z","end":"2026-03-31T20:16:19.263562Z","steps":["trace[354865936] 'agreement among raft nodes before linearized reading' (duration: 105.375531ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:16:49.906036Z","caller":"traceutil/trace.go:171","msg":"trace[534217551] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"113.829722ms","start":"2026-03-31T20:16:49.792157Z","end":"2026-03-31T20:16:49.905987Z","steps":["trace[534217551] 'process raft request' (duration: 113.552121ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:17:41.612959Z","caller":"traceutil/trace.go:171","msg":"trace[1990766741] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"104.209389ms","start":"2026-03-31T20:17:41.508706Z","end":"2026-03-31T20:17:41.612915Z","steps":["trace[1990766741] 'process raft request' (duration: 101.639302ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:17:51.393363Z","caller":"traceutil/trace.go:171","msg":"trace[1712351229] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"444.586574ms","start":"2026-03-31T20:17:50.94875Z","end":"2026-03-31T20:17:51.393337Z","steps":["trace[1712351229] 'read index received' (duration: 414.365609ms)","trace[1712351229] 'applied index is now lower than readState.Index' (duration: 30.219405ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:17:51.393527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.509411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:17:51.393618Z","caller":"traceutil/trace.go:171","msg":"trace[1064997563] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1352; }","duration":"374.646337ms","start":"2026-03-31T20:17:51.018955Z","end":"2026-03-31T20:17:51.393601Z","steps":["trace[1064997563] 'agreement among raft nodes before linearized reading' (duration: 374.520082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:17:51.393666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.83838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-03-31T20:17:51.393699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:17:51.018936Z","time spent":"374.747611ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-03-31T20:17:51.393704Z","caller":"traceutil/trace.go:171","msg":"trace[2021650135] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1352; }","duration":"265.907414ms","start":"2026-03-31T20:17:51.127784Z","end":"2026-03-31T20:17:51.393692Z","steps":["trace[2021650135] 'agreement among raft nodes before linearized reading' (duration: 265.797509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:17:51.393527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.759071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:17:51.394026Z","caller":"traceutil/trace.go:171","msg":"trace[234444138] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1352; }","duration":"445.331035ms","start":"2026-03-31T20:17:50.948682Z","end":"2026-03-31T20:17:51.394013Z","steps":["trace[234444138] 'agreement among raft nodes before linearized reading' (duration: 444.775602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:17:51.394069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:17:50.948663Z","time spent":"445.391308ms","remote":"127.0.0.1:59710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-03-31T20:17:58.030663Z","caller":"traceutil/trace.go:171","msg":"trace[1079257845] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1437; }","duration":"174.150994ms","start":"2026-03-31T20:17:57.856487Z","end":"2026-03-31T20:17:58.030638Z","steps":["trace[1079257845] 'read index received' (duration: 173.935715ms)","trace[1079257845] 'applied index is now lower than readState.Index' (duration: 214.279µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:17:58.030766Z","caller":"traceutil/trace.go:171","msg":"trace[1801535259] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"176.302634ms","start":"2026-03-31T20:17:57.85444Z","end":"2026-03-31T20:17:58.030742Z","steps":["trace[1801535259] 'process raft request' (duration: 176.027053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:17:58.030818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.30219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:17:58.030868Z","caller":"traceutil/trace.go:171","msg":"trace[1618639299] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1381; }","duration":"174.389924ms","start":"2026-03-31T20:17:57.856464Z","end":"2026-03-31T20:17:58.030853Z","steps":["trace[1618639299] 'agreement among raft nodes before linearized reading' (duration: 174.269049ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:17:58.202678Z","caller":"traceutil/trace.go:171","msg":"trace[1851067242] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"123.191773ms","start":"2026-03-31T20:17:58.079464Z","end":"2026-03-31T20:17:58.202656Z","steps":["trace[1851067242] 'process raft request' (duration: 67.648917ms)","trace[1851067242] 'compare' (duration: 55.39798ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:17:58.203184Z","caller":"traceutil/trace.go:171","msg":"trace[1540796758] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"123.684565ms","start":"2026-03-31T20:17:58.079475Z","end":"2026-03-31T20:17:58.20316Z","steps":["trace[1540796758] 'process raft request' (duration: 123.149012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:17:58.800623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.129746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:17:58.800763Z","caller":"traceutil/trace.go:171","msg":"trace[337148784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1386; }","duration":"385.379216ms","start":"2026-03-31T20:17:58.415341Z","end":"2026-03-31T20:17:58.80072Z","steps":["trace[337148784] 'range keys from in-memory index tree' (duration: 385.002021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:17:58.800815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:17:58.415326Z","time spent":"385.47575ms","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:17:58.800818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.021224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T20:17:58.800893Z","caller":"traceutil/trace.go:171","msg":"trace[2010779029] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1386; }","duration":"449.153419ms","start":"2026-03-31T20:17:58.351722Z","end":"2026-03-31T20:17:58.800876Z","steps":["trace[2010779029] 'count revisions from in-memory index tree' (duration: 448.92953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:17:58.800946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:17:58.351699Z","time spent":"449.236413ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "}