{"level":"warn","ts":"2026-03-31T22:14:10.25869Z","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-31T22:14:10.258779Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-31T22:14:10.258867Z","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-31T22:14:10.258885Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-03-31T22:14:10.258909Z","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-31T22:14:10.260084Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-03-31T22:14:10.260191Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-31T22:14:10.269398Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.933463ms"} {"level":"info","ts":"2026-03-31T22:14:10.282649Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-03-31T22:14:10.28278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T22:14:10.282808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-03-31T22:14:10.282825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T22:14:10.282847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-03-31T22:14:10.28289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-03-31T22:14:10.299938Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T22:14:10.301891Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T22:14:10.303812Z","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-31T22:14:10.306137Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T22:14:10.30632Z","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-31T22:14:10.306387Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T22:14:10.306432Z","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-31T22:14:10.306934Z","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-31T22:14:10.30997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-03-31T22:14:10.310082Z","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-31T22:14:10.310221Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-03-31T22:14:10.310321Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-03-31T22:14:10.310395Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-03-31T22:14:10.310426Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T22:14:10.310479Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T22:14:11.085999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T22:14:11.086063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T22:14:11.086123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-03-31T22:14:11.086164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-03-31T22:14:11.086186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-03-31T22:14:11.086212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-03-31T22:14:11.086233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-03-31T22:14:11.087292Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:14:11.088967Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T22:14:11.089183Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T22:14:11.089258Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T22:14:11.089311Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T22:14:11.089418Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T22:14:11.091011Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:14:11.091289Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:14:11.091356Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T22:14:11.094785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-03-31T22:14:11.095408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-31T22:14:18.844557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.458802ms","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-31T22:14:18.844671Z","caller":"traceutil/trace.go:171","msg":"trace[1945478422] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:334; }","duration":"114.614113ms","start":"2026-03-31T22:14:18.73004Z","end":"2026-03-31T22:14:18.844654Z","steps":["trace[1945478422] 'range keys from in-memory index tree' (duration: 114.394922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:14:22.640439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.723953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:14:22.640607Z","caller":"traceutil/trace.go:171","msg":"trace[297974383] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"115.215398ms","start":"2026-03-31T22:14:22.525354Z","end":"2026-03-31T22:14:22.640569Z","steps":["trace[297974383] 'process raft request' (duration: 10.683859ms)","trace[297974383] 'compare' (duration: 103.610662ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:14:39.068287Z","caller":"traceutil/trace.go:171","msg":"trace[2075101165] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"224.340357ms","start":"2026-03-31T22:14:38.84391Z","end":"2026-03-31T22:14:39.06825Z","steps":["trace[2075101165] 'read index received' (duration: 185.745728ms)","trace[2075101165] 'applied index is now lower than readState.Index' (duration: 38.592759ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:14:39.068462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.526318ms","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-31T22:14:39.068435Z","caller":"traceutil/trace.go:171","msg":"trace[372324706] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"268.295929ms","start":"2026-03-31T22:14:38.800099Z","end":"2026-03-31T22:14:39.068395Z","steps":["trace[372324706] 'process raft request' (duration: 229.614159ms)","trace[372324706] 'compare' (duration: 38.403928ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:14:39.068505Z","caller":"traceutil/trace.go:171","msg":"trace[1163307422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:444; }","duration":"224.608688ms","start":"2026-03-31T22:14:38.843883Z","end":"2026-03-31T22:14:39.068491Z","steps":["trace[1163307422] 'agreement among raft nodes before linearized reading' (duration: 224.504438ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.925341Z","caller":"traceutil/trace.go:171","msg":"trace[2132502570] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"232.839945ms","start":"2026-03-31T22:16:57.692476Z","end":"2026-03-31T22:16:57.925316Z","steps":["trace[2132502570] 'process raft request' (duration: 232.699233ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.933836Z","caller":"traceutil/trace.go:171","msg":"trace[1422213811] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"238.280509ms","start":"2026-03-31T22:16:57.69554Z","end":"2026-03-31T22:16:57.933821Z","steps":["trace[1422213811] 'process raft request' (duration: 238.069466ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.933934Z","caller":"traceutil/trace.go:171","msg":"trace[878044208] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"231.332343ms","start":"2026-03-31T22:16:57.702591Z","end":"2026-03-31T22:16:57.933923Z","steps":["trace[878044208] 'process raft request' (duration: 231.16652ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.93399Z","caller":"traceutil/trace.go:171","msg":"trace[345602866] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"238.30265ms","start":"2026-03-31T22:16:57.695681Z","end":"2026-03-31T22:16:57.933984Z","steps":["trace[345602866] 'process raft request' (duration: 238.043736ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.934053Z","caller":"traceutil/trace.go:171","msg":"trace[141935279] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"224.707001ms","start":"2026-03-31T22:16:57.70934Z","end":"2026-03-31T22:16:57.934047Z","steps":["trace[141935279] 'process raft request' (duration: 224.481747ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.934083Z","caller":"traceutil/trace.go:171","msg":"trace[1365189301] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"228.044752ms","start":"2026-03-31T22:16:57.706007Z","end":"2026-03-31T22:16:57.934052Z","steps":["trace[1365189301] 'process raft request' (duration: 227.781658ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.934181Z","caller":"traceutil/trace.go:171","msg":"trace[1996687426] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"224.228423ms","start":"2026-03-31T22:16:57.709935Z","end":"2026-03-31T22:16:57.934163Z","steps":["trace[1996687426] 'process raft request' (duration: 223.916489ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.934306Z","caller":"traceutil/trace.go:171","msg":"trace[1329303078] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"222.876132ms","start":"2026-03-31T22:16:57.711421Z","end":"2026-03-31T22:16:57.934297Z","steps":["trace[1329303078] 'process raft request' (duration: 222.465286ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.935311Z","caller":"traceutil/trace.go:171","msg":"trace[87143325] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:874; }","duration":"197.705167ms","start":"2026-03-31T22:16:57.737588Z","end":"2026-03-31T22:16:57.935294Z","steps":["trace[87143325] 'read index received' (duration: 187.702363ms)","trace[87143325] 'applied index is now lower than readState.Index' (duration: 10.001904ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:16:57.93543Z","caller":"traceutil/trace.go:171","msg":"trace[1039368178] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"191.309709ms","start":"2026-03-31T22:16:57.744099Z","end":"2026-03-31T22:16:57.935409Z","steps":["trace[1039368178] 'process raft request' (duration: 191.031094ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.935545Z","caller":"traceutil/trace.go:171","msg":"trace[1521030956] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"206.344518ms","start":"2026-03-31T22:16:57.729191Z","end":"2026-03-31T22:16:57.935535Z","steps":["trace[1521030956] 'process raft request' (duration: 205.885561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:16:57.935712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.105833ms","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-31T22:16:57.935762Z","caller":"traceutil/trace.go:171","msg":"trace[1159332465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:837; }","duration":"198.195754ms","start":"2026-03-31T22:16:57.737552Z","end":"2026-03-31T22:16:57.935748Z","steps":["trace[1159332465] 'agreement among raft nodes before linearized reading' (duration: 198.011171ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:57.936175Z","caller":"traceutil/trace.go:171","msg":"trace[1096379406] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"220.502217ms","start":"2026-03-31T22:16:57.714873Z","end":"2026-03-31T22:16:57.935376Z","steps":["trace[1096379406] 'process raft request' (duration: 219.530042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:16:57.945159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.814844ms","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-31T22:16:57.945224Z","caller":"traceutil/trace.go:171","msg":"trace[640939008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"200.903315ms","start":"2026-03-31T22:16:57.744307Z","end":"2026-03-31T22:16:57.94521Z","steps":["trace[640939008] 'agreement among raft nodes before linearized reading' (duration: 200.787083ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:58.956122Z","caller":"traceutil/trace.go:171","msg":"trace[208541186] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"148.096933ms","start":"2026-03-31T22:16:58.807999Z","end":"2026-03-31T22:16:58.956096Z","steps":["trace[208541186] 'process raft request' (duration: 147.486934ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:16:58.973792Z","caller":"traceutil/trace.go:171","msg":"trace[1083691616] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"120.014974ms","start":"2026-03-31T22:16:58.853757Z","end":"2026-03-31T22:16:58.973772Z","steps":["trace[1083691616] 'process raft request' (duration: 118.304338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:04.658766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878102567482809,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:17:04.981615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.151739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T22:17:04.981735Z","caller":"traceutil/trace.go:171","msg":"trace[882823307] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:932; }","duration":"840.319892ms","start":"2026-03-31T22:17:04.141392Z","end":"2026-03-31T22:17:04.981712Z","steps":["trace[882823307] 'range keys from in-memory index tree' (duration: 840.046128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:04.981776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:04.141373Z","time spent":"840.394043ms","remote":"127.0.0.1:37016","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.132\" "} {"level":"warn","ts":"2026-03-31T22:17:04.982561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.109142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T22:17:04.982701Z","caller":"traceutil/trace.go:171","msg":"trace[2143740433] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"826.603658ms","start":"2026-03-31T22:17:04.156065Z","end":"2026-03-31T22:17:04.982668Z","steps":["trace[2143740433] 'process raft request' (duration: 706.291963ms)","trace[2143740433] 'compare' (duration: 119.043536ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:04.982739Z","caller":"traceutil/trace.go:171","msg":"trace[465788163] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"824.225383ms","start":"2026-03-31T22:17:04.158435Z","end":"2026-03-31T22:17:04.982661Z","steps":["trace[465788163] 'read index received' (duration: 703.733005ms)","trace[465788163] 'applied index is now lower than readState.Index' (duration: 120.490868ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:17:04.982865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:04.156007Z","time spent":"826.760611ms","remote":"127.0.0.1:37094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:17:04.983009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.565008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4bqc4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-31T22:17:04.983072Z","caller":"traceutil/trace.go:171","msg":"trace[451792412] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4bqc4; range_end:; response_count:1; response_revision:933; }","duration":"824.7073ms","start":"2026-03-31T22:17:04.158352Z","end":"2026-03-31T22:17:04.983059Z","steps":["trace[451792412] 'agreement among raft nodes before linearized reading' (duration: 824.495847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:04.983124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:04.158337Z","time spent":"824.776031ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-4bqc4\" "} {"level":"warn","ts":"2026-03-31T22:17:05.704095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.077519ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-31T22:17:05.704175Z","caller":"traceutil/trace.go:171","msg":"trace[668718274] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"721.381449ms","start":"2026-03-31T22:17:04.982781Z","end":"2026-03-31T22:17:05.704162Z","steps":["trace[668718274] 'read index received' (duration: 495.140267ms)","trace[668718274] 'applied index is now lower than readState.Index' (duration: 226.240172ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:17:05.704191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:04.982608Z","time spent":"721.572042ms","remote":"127.0.0.1:37016","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-31T22:17:05.704307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184596864s","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-31T22:17:05.704335Z","caller":"traceutil/trace.go:171","msg":"trace[1432095928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"1.184661855s","start":"2026-03-31T22:17:04.519666Z","end":"2026-03-31T22:17:05.704327Z","steps":["trace[1432095928] 'agreement among raft nodes before linearized reading' (duration: 1.184544564s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:05.704362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:04.519645Z","time spent":"1.184711396s","remote":"127.0.0.1:37276","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-31T22:17:05.704397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.682077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T22:17:05.704524Z","caller":"traceutil/trace.go:171","msg":"trace[1266271688] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:933; }","duration":"854.854331ms","start":"2026-03-31T22:17:04.849625Z","end":"2026-03-31T22:17:05.704479Z","steps":["trace[1266271688] 'agreement among raft nodes before linearized reading' (duration: 854.607017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:05.704605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:04.849606Z","time spent":"854.985742ms","remote":"127.0.0.1:37376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:17:05.704472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.412431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:05.705025Z","caller":"traceutil/trace.go:171","msg":"trace[1415101404] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:933; }","duration":"964.144752ms","start":"2026-03-31T22:17:04.74078Z","end":"2026-03-31T22:17:05.704925Z","steps":["trace[1415101404] 'agreement among raft nodes before linearized reading' (duration: 963.409931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:05.705187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:04.740762Z","time spent":"964.299334ms","remote":"127.0.0.1:37512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:17:05.705391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473405721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:05.705484Z","caller":"traceutil/trace.go:171","msg":"trace[1684761759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"1.473536132s","start":"2026-03-31T22:17:04.231936Z","end":"2026-03-31T22:17:05.705472Z","steps":["trace[1684761759] 'agreement among raft nodes before linearized reading' (duration: 1.4733727s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:05.70556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:04.231913Z","time spent":"1.473639193s","remote":"127.0.0.1:36980","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-31T22:17:06.205067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878102567482816,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T22:17:06.368272Z","caller":"traceutil/trace.go:171","msg":"trace[1063167694] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:984; }","duration":"664.056156ms","start":"2026-03-31T22:17:05.704193Z","end":"2026-03-31T22:17:06.368249Z","steps":["trace[1063167694] 'read index received' (duration: 582.226833ms)","trace[1063167694] 'applied index is now lower than readState.Index' (duration: 81.828413ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:06.368404Z","caller":"traceutil/trace.go:171","msg":"trace[1708768727] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.366777557s","start":"2026-03-31T22:17:05.001615Z","end":"2026-03-31T22:17:06.368392Z","steps":["trace[1708768727] 'process raft request' (duration: 1.284788062s)","trace[1708768727] 'compare' (duration: 81.450518ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:06.368444Z","caller":"traceutil/trace.go:171","msg":"trace[654304752] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.328127827s","start":"2026-03-31T22:17:05.040289Z","end":"2026-03-31T22:17:06.368417Z","steps":["trace[654304752] 'process raft request' (duration: 1.327797282s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:06.36854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:05.001583Z","time spent":"1.366862079s","remote":"127.0.0.1:37200","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-31T22:17:06.368571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:05.040265Z","time spent":"1.328245409s","remote":"127.0.0.1:37512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:17:06.368614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.929467ms","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-31T22:17:06.368672Z","caller":"traceutil/trace.go:171","msg":"trace[195202893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:937; }","duration":"893.030208ms","start":"2026-03-31T22:17:05.475628Z","end":"2026-03-31T22:17:06.368658Z","steps":["trace[195202893] 'agreement among raft nodes before linearized reading' (duration: 892.811345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:06.368713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:05.475606Z","time spent":"893.0994ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T22:17:06.368826Z","caller":"traceutil/trace.go:171","msg":"trace[635385785] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"660.31212ms","start":"2026-03-31T22:17:05.708493Z","end":"2026-03-31T22:17:06.368805Z","steps":["trace[635385785] 'process raft request' (duration: 659.715442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:06.369626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:05.708477Z","time spent":"661.081812ms","remote":"127.0.0.1:37276","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-31T22:17:06.36885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.745197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:06.369836Z","caller":"traceutil/trace.go:171","msg":"trace[1988015178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"659.753092ms","start":"2026-03-31T22:17:05.710053Z","end":"2026-03-31T22:17:06.369806Z","steps":["trace[1988015178] 'agreement among raft nodes before linearized reading' (duration: 658.764818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:06.369917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:05.710033Z","time spent":"659.860643ms","remote":"127.0.0.1:36984","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-31T22:17:06.368902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.335585ms","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":"info","ts":"2026-03-31T22:17:06.370029Z","caller":"traceutil/trace.go:171","msg":"trace[922939015] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:937; }","duration":"666.480672ms","start":"2026-03-31T22:17:05.70351Z","end":"2026-03-31T22:17:06.369991Z","steps":["trace[922939015] 'agreement among raft nodes before linearized reading' (duration: 665.350715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:06.368939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.221255ms","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-31T22:17:06.370174Z","caller":"traceutil/trace.go:171","msg":"trace[1495632940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"369.477154ms","start":"2026-03-31T22:17:06.000669Z","end":"2026-03-31T22:17:06.370147Z","steps":["trace[1495632940] 'agreement among raft nodes before linearized reading' (duration: 368.213326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:06.370296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:06.000638Z","time spent":"369.621576ms","remote":"127.0.0.1:37276","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-31T22:17:06.368976Z","caller":"traceutil/trace.go:171","msg":"trace[1499763691] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"663.702501ms","start":"2026-03-31T22:17:05.705263Z","end":"2026-03-31T22:17:06.368966Z","steps":["trace[1499763691] 'process raft request' (duration: 662.901439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:06.369006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.466418ms","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-31T22:17:06.370485Z","caller":"traceutil/trace.go:171","msg":"trace[2061535831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:937; }","duration":"371.970231ms","start":"2026-03-31T22:17:05.998499Z","end":"2026-03-31T22:17:06.370469Z","steps":["trace[2061535831] 'agreement among raft nodes before linearized reading' (duration: 370.419288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:06.370561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:05.998479Z","time spent":"372.065962ms","remote":"127.0.0.1:37276","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-31T22:17:06.370797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:05.705241Z","time spent":"665.512907ms","remote":"127.0.0.1:37016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:17:06.373993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:05.703487Z","time spent":"670.397929ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":20,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:17:07.854056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337696625s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-03-31T22:17:07.85391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.551932ms","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-31T22:17:07.854151Z","caller":"traceutil/trace.go:171","msg":"trace[69208416] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:938; }","duration":"475.814295ms","start":"2026-03-31T22:17:07.378305Z","end":"2026-03-31T22:17:07.854119Z","steps":["trace[69208416] 'range keys from in-memory index tree' (duration: 475.525931ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:07.854192Z","caller":"traceutil/trace.go:171","msg":"trace[1086651644] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"1.471844167s","start":"2026-03-31T22:17:06.382335Z","end":"2026-03-31T22:17:07.854179Z","steps":["trace[1086651644] 'process raft request' (duration: 133.942639ms)","trace[1086651644] 'compare' (duration: 1.337236099s)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:17:07.854237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:06.382316Z","time spent":"1.471899768s","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:17:07.854735Z","caller":"traceutil/trace.go:171","msg":"trace[421843464] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"1.471964028s","start":"2026-03-31T22:17:06.382753Z","end":"2026-03-31T22:17:07.854717Z","steps":["trace[421843464] 'process raft request' (duration: 1.47138393s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:07.854824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:06.382746Z","time spent":"1.47203215s","remote":"127.0.0.1:37276","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-31T22:17:07.855127Z","caller":"traceutil/trace.go:171","msg":"trace[1520802901] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:988; }","duration":"1.471871427s","start":"2026-03-31T22:17:06.382923Z","end":"2026-03-31T22:17:07.854794Z","steps":["trace[1520802901] 'read index received' (duration: 26.134144ms)","trace[1520802901] 'applied index is now lower than readState.Index' (duration: 1.445731633s)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:17:07.855285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472330334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T22:17:07.855364Z","caller":"traceutil/trace.go:171","msg":"trace[594445706] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:941; }","duration":"1.472509976s","start":"2026-03-31T22:17:06.382838Z","end":"2026-03-31T22:17:07.855348Z","steps":["trace[594445706] 'agreement among raft nodes before linearized reading' (duration: 1.472150971s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:07.855455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:06.382819Z","time spent":"1.472623088s","remote":"127.0.0.1:37016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-03-31T22:17:07.855395Z","caller":"traceutil/trace.go:171","msg":"trace[595642229] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"1.379643302s","start":"2026-03-31T22:17:06.475283Z","end":"2026-03-31T22:17:07.854926Z","steps":["trace[595642229] 'process raft request' (duration: 1.379402499s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:07.856053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:06.475249Z","time spent":"1.380547215s","remote":"127.0.0.1:37276","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-31T22:17:08.355933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878102567482830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:17:08.641602Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.875139955s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T22:17:08.643886Z","caller":"traceutil/trace.go:171","msg":"trace[1069410375] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"788.655564ms","start":"2026-03-31T22:17:07.855199Z","end":"2026-03-31T22:17:08.643854Z","steps":["trace[1069410375] 'read index received' (duration: 786.644594ms)","trace[1069410375] 'applied index is now lower than readState.Index' (duration: 2.00909ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:08.64397Z","caller":"traceutil/trace.go:171","msg":"trace[1623599832] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"1.885596138s","start":"2026-03-31T22:17:06.758339Z","end":"2026-03-31T22:17:08.643935Z","steps":["trace[1623599832] 'process raft request' (duration: 1.884108406s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:08.644066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.373329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-31T22:17:08.644069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412718169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:08.644113Z","caller":"traceutil/trace.go:171","msg":"trace[1758969956] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:942; }","duration":"786.462611ms","start":"2026-03-31T22:17:07.857639Z","end":"2026-03-31T22:17:08.644101Z","steps":["trace[1758969956] 'agreement among raft nodes before linearized reading' (duration: 786.36619ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:08.644136Z","caller":"traceutil/trace.go:171","msg":"trace[1764562367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"1.412848031s","start":"2026-03-31T22:17:07.231268Z","end":"2026-03-31T22:17:08.644116Z","steps":["trace[1764562367] 'agreement among raft nodes before linearized reading' (duration: 1.412704879s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:08.644103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619544387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T22:17:08.644198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:07.231248Z","time spent":"1.412937012s","remote":"127.0.0.1:36984","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-31T22:17:08.644201Z","caller":"traceutil/trace.go:171","msg":"trace[1662715997] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:942; }","duration":"1.619683799s","start":"2026-03-31T22:17:07.024498Z","end":"2026-03-31T22:17:08.644182Z","steps":["trace[1662715997] 'agreement among raft nodes before linearized reading' (duration: 1.619500817s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:08.644253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.209327ms","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-31T22:17:08.644228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:06.758232Z","time spent":"1.885823241s","remote":"127.0.0.1:37094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:17:08.644282Z","caller":"traceutil/trace.go:171","msg":"trace[484174224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"270.274979ms","start":"2026-03-31T22:17:08.373996Z","end":"2026-03-31T22:17:08.644271Z","steps":["trace[484174224] 'agreement among raft nodes before linearized reading' (duration: 270.152467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:08.644288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:07.024477Z","time spent":"1.619795471s","remote":"127.0.0.1:37444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-31T22:17:08.644634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:07.85762Z","time spent":"787.005679ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-03-31T22:17:08.644798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.789559ms","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-31T22:17:08.644891Z","caller":"traceutil/trace.go:171","msg":"trace[475767967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"229.91168ms","start":"2026-03-31T22:17:08.41494Z","end":"2026-03-31T22:17:08.644852Z","steps":["trace[475767967] 'agreement among raft nodes before linearized reading' (duration: 229.690497ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:09.283849Z","caller":"traceutil/trace.go:171","msg":"trace[504008673] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"106.743409ms","start":"2026-03-31T22:17:09.177075Z","end":"2026-03-31T22:17:09.283818Z","steps":["trace[504008673] 'process raft request' (duration: 106.566626ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:09.313923Z","caller":"traceutil/trace.go:171","msg":"trace[1323892661] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"121.725366ms","start":"2026-03-31T22:17:09.192175Z","end":"2026-03-31T22:17:09.3139Z","steps":["trace[1323892661] 'process raft request' (duration: 121.26853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:10.707073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.683199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-31T22:17:10.707171Z","caller":"traceutil/trace.go:171","msg":"trace[1951950336] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:964; }","duration":"371.80228ms","start":"2026-03-31T22:17:10.335335Z","end":"2026-03-31T22:17:10.707137Z","steps":["trace[1951950336] 'range keys from in-memory index tree' (duration: 371.590547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:10.707216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:10.335317Z","time spent":"371.888131ms","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-31T22:17:11.338812Z","caller":"traceutil/trace.go:171","msg":"trace[576584356] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"147.595873ms","start":"2026-03-31T22:17:11.191187Z","end":"2026-03-31T22:17:11.338783Z","steps":["trace[576584356] 'read index received' (duration: 48.546198ms)","trace[576584356] 'applied index is now lower than readState.Index' (duration: 99.048234ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:17:11.339069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.868106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-psrfk\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-03-31T22:17:11.339116Z","caller":"traceutil/trace.go:171","msg":"trace[1948978855] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-psrfk; range_end:; response_count:1; response_revision:977; }","duration":"147.988358ms","start":"2026-03-31T22:17:11.191115Z","end":"2026-03-31T22:17:11.339104Z","steps":["trace[1948978855] 'agreement among raft nodes before linearized reading' (duration: 147.773945ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:11.339101Z","caller":"traceutil/trace.go:171","msg":"trace[623048252] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"189.320253ms","start":"2026-03-31T22:17:11.149751Z","end":"2026-03-31T22:17:11.339071Z","steps":["trace[623048252] 'process raft request' (duration: 89.969744ms)","trace[623048252] 'compare' (duration: 98.912122ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:11.345638Z","caller":"traceutil/trace.go:171","msg":"trace[457867948] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"148.036609ms","start":"2026-03-31T22:17:11.197575Z","end":"2026-03-31T22:17:11.345612Z","steps":["trace[457867948] 'process raft request' (duration: 147.903227ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:11.469723Z","caller":"traceutil/trace.go:171","msg":"trace[621747615] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"124.678483ms","start":"2026-03-31T22:17:11.34497Z","end":"2026-03-31T22:17:11.469648Z","steps":["trace[621747615] 'process raft request' (duration: 124.357468ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:11.642849Z","caller":"traceutil/trace.go:171","msg":"trace[1428021670] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"296.969992ms","start":"2026-03-31T22:17:11.345848Z","end":"2026-03-31T22:17:11.642818Z","steps":["trace[1428021670] 'process raft request' (duration: 267.101682ms)","trace[1428021670] 'compare' (duration: 29.640246ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:11.643086Z","caller":"traceutil/trace.go:171","msg":"trace[1337466895] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1030; }","duration":"262.273682ms","start":"2026-03-31T22:17:11.380777Z","end":"2026-03-31T22:17:11.64305Z","steps":["trace[1337466895] 'read index received' (duration: 88.475972ms)","trace[1337466895] 'applied index is now lower than readState.Index' (duration: 173.7945ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:11.643218Z","caller":"traceutil/trace.go:171","msg":"trace[335608336] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"297.276825ms","start":"2026-03-31T22:17:11.345891Z","end":"2026-03-31T22:17:11.643167Z","steps":["trace[335608336] 'process raft request' (duration: 296.856329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:11.643469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.653887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:11.64359Z","caller":"traceutil/trace.go:171","msg":"trace[576021008] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:981; }","duration":"262.83895ms","start":"2026-03-31T22:17:11.380728Z","end":"2026-03-31T22:17:11.643567Z","steps":["trace[576021008] 'agreement among raft nodes before linearized reading' (duration: 262.591377ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:11.7213Z","caller":"traceutil/trace.go:171","msg":"trace[730476890] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"245.346008ms","start":"2026-03-31T22:17:11.475912Z","end":"2026-03-31T22:17:11.721258Z","steps":["trace[730476890] 'process raft request' (duration: 245.166066ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:11.721492Z","caller":"traceutil/trace.go:171","msg":"trace[484411419] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"175.513104ms","start":"2026-03-31T22:17:11.545962Z","end":"2026-03-31T22:17:11.721476Z","steps":["trace[484411419] 'process raft request' (duration: 175.174ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:11.721636Z","caller":"traceutil/trace.go:171","msg":"trace[715009705] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"246.781489ms","start":"2026-03-31T22:17:11.474822Z","end":"2026-03-31T22:17:11.721603Z","steps":["trace[715009705] 'process raft request' (duration: 246.16508ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:12.120423Z","caller":"traceutil/trace.go:171","msg":"trace[258836415] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"390.325947ms","start":"2026-03-31T22:17:11.730072Z","end":"2026-03-31T22:17:12.120398Z","steps":["trace[258836415] 'process raft request' (duration: 390.136104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.120581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:11.730044Z","time spent":"390.453498ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:17:12.355481Z","caller":"traceutil/trace.go:171","msg":"trace[1064817090] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"624.943646ms","start":"2026-03-31T22:17:11.730512Z","end":"2026-03-31T22:17:12.355456Z","steps":["trace[1064817090] 'process raft request' (duration: 624.700663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.355627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:11.730481Z","time spent":"625.068499ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:17:12.356197Z","caller":"traceutil/trace.go:171","msg":"trace[110371076] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"609.88639ms","start":"2026-03-31T22:17:11.746287Z","end":"2026-03-31T22:17:12.356173Z","steps":["trace[110371076] 'process raft request' (duration: 609.695838ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:12.356327Z","caller":"traceutil/trace.go:171","msg":"trace[1972291503] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1036; }","duration":"488.419703ms","start":"2026-03-31T22:17:11.867894Z","end":"2026-03-31T22:17:12.356314Z","steps":["trace[1972291503] 'read index received' (duration: 252.326122ms)","trace[1972291503] 'applied index is now lower than readState.Index' (duration: 236.092761ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:12.356409Z","caller":"traceutil/trace.go:171","msg":"trace[821661929] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"410.682285ms","start":"2026-03-31T22:17:11.945714Z","end":"2026-03-31T22:17:12.356397Z","steps":["trace[821661929] 'process raft request' (duration: 410.562813ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:12.356441Z","caller":"traceutil/trace.go:171","msg":"trace[670347767] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"602.335911ms","start":"2026-03-31T22:17:11.754085Z","end":"2026-03-31T22:17:12.356421Z","steps":["trace[670347767] 'process raft request' (duration: 602.004766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.356473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:11.945672Z","time spent":"410.775786ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:17:12.35651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:11.754062Z","time spent":"602.415053ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:17:12.35635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:11.746249Z","time spent":"610.023012ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:17:12.357413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.486819ms","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-31T22:17:12.357517Z","caller":"traceutil/trace.go:171","msg":"trace[1076417387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"489.65804ms","start":"2026-03-31T22:17:11.867835Z","end":"2026-03-31T22:17:12.357493Z","steps":["trace[1076417387] 'agreement among raft nodes before linearized reading' (duration: 488.603615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.358823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:11.867809Z","time spent":"489.757242ms","remote":"127.0.0.1:37276","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-31T22:17:12.499368Z","caller":"traceutil/trace.go:171","msg":"trace[2098912297] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1041; }","duration":"142.905636ms","start":"2026-03-31T22:17:12.356414Z","end":"2026-03-31T22:17:12.49932Z","steps":["trace[2098912297] 'read index received' (duration: 142.545011ms)","trace[2098912297] 'applied index is now lower than readState.Index' (duration: 359.595µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:12.499553Z","caller":"traceutil/trace.go:171","msg":"trace[1266050820] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"354.397295ms","start":"2026-03-31T22:17:12.145145Z","end":"2026-03-31T22:17:12.499542Z","steps":["trace[1266050820] 'process raft request' (duration: 353.728136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.499711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.145114Z","time spent":"354.472586ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:17:12.49978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.222496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:12.499869Z","caller":"traceutil/trace.go:171","msg":"trace[2023975752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"267.367049ms","start":"2026-03-31T22:17:12.232485Z","end":"2026-03-31T22:17:12.499852Z","steps":["trace[2023975752] 'agreement among raft nodes before linearized reading' (duration: 267.139846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.500102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.529808ms","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-31T22:17:12.500136Z","caller":"traceutil/trace.go:171","msg":"trace[790684187] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"151.273636ms","start":"2026-03-31T22:17:12.348844Z","end":"2026-03-31T22:17:12.500117Z","steps":["trace[790684187] 'process raft request' (duration: 150.383323ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:12.500283Z","caller":"traceutil/trace.go:171","msg":"trace[1438827021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"630.615419ms","start":"2026-03-31T22:17:11.869529Z","end":"2026-03-31T22:17:12.500145Z","steps":["trace[1438827021] 'agreement among raft nodes before linearized reading' (duration: 630.443217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.500334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:11.869525Z","time spent":"630.798612ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T22:17:12.551832Z","caller":"traceutil/trace.go:171","msg":"trace[913898896] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"188.806224ms","start":"2026-03-31T22:17:12.363003Z","end":"2026-03-31T22:17:12.551809Z","steps":["trace[913898896] 'process raft request' (duration: 188.454619ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:12.552101Z","caller":"traceutil/trace.go:171","msg":"trace[509183405] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"186.566572ms","start":"2026-03-31T22:17:12.365527Z","end":"2026-03-31T22:17:12.552094Z","steps":["trace[509183405] 'process raft request' (duration: 186.021354ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:12.552181Z","caller":"traceutil/trace.go:171","msg":"trace[1986357390] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"184.703295ms","start":"2026-03-31T22:17:12.367473Z","end":"2026-03-31T22:17:12.552176Z","steps":["trace[1986357390] 'process raft request' (duration: 184.121647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.552313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.035717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-psrfk\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-03-31T22:17:12.552349Z","caller":"traceutil/trace.go:171","msg":"trace[1676699490] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-psrfk; range_end:; response_count:1; response_revision:994; }","duration":"189.114988ms","start":"2026-03-31T22:17:12.363226Z","end":"2026-03-31T22:17:12.552342Z","steps":["trace[1676699490] 'agreement among raft nodes before linearized reading' (duration: 189.014077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.552535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.461449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:116"} {"level":"info","ts":"2026-03-31T22:17:12.552607Z","caller":"traceutil/trace.go:171","msg":"trace[106853048] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:994; }","duration":"195.581831ms","start":"2026-03-31T22:17:12.357008Z","end":"2026-03-31T22:17:12.55259Z","steps":["trace[106853048] 'agreement among raft nodes before linearized reading' (duration: 195.402948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:12.552534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.298077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-03-31T22:17:12.552752Z","caller":"traceutil/trace.go:171","msg":"trace[1536171419] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:994; }","duration":"195.52493ms","start":"2026-03-31T22:17:12.357184Z","end":"2026-03-31T22:17:12.552709Z","steps":["trace[1536171419] 'agreement among raft nodes before linearized reading' (duration: 195.280676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.096326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878102567482922,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T22:17:13.165953Z","caller":"traceutil/trace.go:171","msg":"trace[869885672] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"598.275537ms","start":"2026-03-31T22:17:12.567651Z","end":"2026-03-31T22:17:13.165927Z","steps":["trace[869885672] 'process raft request' (duration: 598.191296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.166136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.567619Z","time spent":"598.42042ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:17:13.166253Z","caller":"traceutil/trace.go:171","msg":"trace[1833706668] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"605.315118ms","start":"2026-03-31T22:17:12.560895Z","end":"2026-03-31T22:17:13.16621Z","steps":["trace[1833706668] 'process raft request' (duration: 604.881822ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:13.166292Z","caller":"traceutil/trace.go:171","msg":"trace[1053065452] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"608.1736ms","start":"2026-03-31T22:17:12.558077Z","end":"2026-03-31T22:17:13.166251Z","steps":["trace[1053065452] 'process raft request' (duration: 607.48116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.166417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.56088Z","time spent":"605.42994ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T22:17:13.166608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.558066Z","time spent":"608.316972ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:17:13.220095Z","caller":"traceutil/trace.go:171","msg":"trace[1276244034] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1048; }","duration":"623.86305ms","start":"2026-03-31T22:17:12.596197Z","end":"2026-03-31T22:17:13.22006Z","steps":["trace[1276244034] 'read index received' (duration: 569.438874ms)","trace[1276244034] 'applied index is now lower than readState.Index' (duration: 54.422606ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:13.220108Z","caller":"traceutil/trace.go:171","msg":"trace[915303462] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"308.824159ms","start":"2026-03-31T22:17:12.911256Z","end":"2026-03-31T22:17:13.22008Z","steps":["trace[915303462] 'process raft request' (duration: 308.628067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.220251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.911235Z","time spent":"308.944041ms","remote":"127.0.0.1:37074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T22:17:13.220413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.809357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:2 size:1908"} {"level":"info","ts":"2026-03-31T22:17:13.220482Z","caller":"traceutil/trace.go:171","msg":"trace[2081343258] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:2; response_revision:1000; }","duration":"664.894478ms","start":"2026-03-31T22:17:12.555568Z","end":"2026-03-31T22:17:13.220463Z","steps":["trace[2081343258] 'agreement among raft nodes before linearized reading' (duration: 664.777546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.220532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.555557Z","time spent":"664.96538ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":2,"response size":1931,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-03-31T22:17:13.220374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.770632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:2 size:1908"} {"level":"info","ts":"2026-03-31T22:17:13.220602Z","caller":"traceutil/trace.go:171","msg":"trace[228675622] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:2; response_revision:1000; }","duration":"664.019086ms","start":"2026-03-31T22:17:12.556566Z","end":"2026-03-31T22:17:13.220585Z","steps":["trace[228675622] 'agreement among raft nodes before linearized reading' (duration: 663.61775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.220655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.556559Z","time spent":"664.082986ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":2,"response size":1931,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-03-31T22:17:13.225987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.537565ms","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-31T22:17:13.226027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.330658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-03-31T22:17:13.226075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.742472ms","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-31T22:17:13.226095Z","caller":"traceutil/trace.go:171","msg":"trace[1844315797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"505.677598ms","start":"2026-03-31T22:17:12.720401Z","end":"2026-03-31T22:17:13.226079Z","steps":["trace[1844315797] 'agreement among raft nodes before linearized reading' (duration: 505.448455ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:13.226107Z","caller":"traceutil/trace.go:171","msg":"trace[80576479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"508.819263ms","start":"2026-03-31T22:17:12.717279Z","end":"2026-03-31T22:17:13.226099Z","steps":["trace[80576479] 'agreement among raft nodes before linearized reading' (duration: 508.718312ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:13.226116Z","caller":"traceutil/trace.go:171","msg":"trace[1585806341] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:1003; }","duration":"284.47162ms","start":"2026-03-31T22:17:12.941621Z","end":"2026-03-31T22:17:13.226093Z","steps":["trace[1585806341] 'agreement among raft nodes before linearized reading' (duration: 284.204526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.226146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.717245Z","time spent":"508.894584ms","remote":"127.0.0.1:37276","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-31T22:17:13.226174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.955941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:13.226206Z","caller":"traceutil/trace.go:171","msg":"trace[1541959359] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:1003; }","duration":"485.009832ms","start":"2026-03-31T22:17:12.741187Z","end":"2026-03-31T22:17:13.226197Z","steps":["trace[1541959359] 'agreement among raft nodes before linearized reading' (duration: 484.961941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.226233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.741174Z","time spent":"485.052442ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-31T22:17:13.226181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.762587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T22:17:13.226325Z","caller":"traceutil/trace.go:171","msg":"trace[117124853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"473.03131ms","start":"2026-03-31T22:17:12.753279Z","end":"2026-03-31T22:17:13.226311Z","steps":["trace[117124853] 'agreement among raft nodes before linearized reading' (duration: 472.721166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:13.226392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.753267Z","time spent":"473.112451ms","remote":"127.0.0.1:37276","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-31T22:17:13.22613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:12.720382Z","time spent":"505.738189ms","remote":"127.0.0.1:37276","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-31T22:17:19.441258Z","caller":"traceutil/trace.go:171","msg":"trace[1839718825] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"144.157816ms","start":"2026-03-31T22:17:19.297062Z","end":"2026-03-31T22:17:19.44122Z","steps":["trace[1839718825] 'process raft request' (duration: 86.96377ms)","trace[1839718825] 'compare' (duration: 57.061834ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:20.072852Z","caller":"traceutil/trace.go:171","msg":"trace[1183119005] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"150.098575ms","start":"2026-03-31T22:17:19.922729Z","end":"2026-03-31T22:17:20.072828Z","steps":["trace[1183119005] 'read index received' (duration: 150.014344ms)","trace[1183119005] 'applied index is now lower than readState.Index' (duration: 83.181µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:17:20.072983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.224346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.073016Z","caller":"traceutil/trace.go:171","msg":"trace[1245301838] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1054; }","duration":"150.303238ms","start":"2026-03-31T22:17:19.922702Z","end":"2026-03-31T22:17:20.073005Z","steps":["trace[1245301838] 'agreement among raft nodes before linearized reading' (duration: 150.219167ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:20.073048Z","caller":"traceutil/trace.go:171","msg":"trace[759145090] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"165.925326ms","start":"2026-03-31T22:17:19.907107Z","end":"2026-03-31T22:17:20.073032Z","steps":["trace[759145090] 'process raft request' (duration: 165.572981ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:20.12547Z","caller":"traceutil/trace.go:171","msg":"trace[1579003491] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"156.788675ms","start":"2026-03-31T22:17:19.968645Z","end":"2026-03-31T22:17:20.125434Z","steps":["trace[1579003491] 'process raft request' (duration: 152.187661ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:20.624363Z","caller":"traceutil/trace.go:171","msg":"trace[260882478] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"144.922718ms","start":"2026-03-31T22:17:20.479413Z","end":"2026-03-31T22:17:20.624336Z","steps":["trace[260882478] 'read index received' (duration: 144.720746ms)","trace[260882478] 'applied index is now lower than readState.Index' (duration: 200.592µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:20.624476Z","caller":"traceutil/trace.go:171","msg":"trace[948811152] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"178.617386ms","start":"2026-03-31T22:17:20.445821Z","end":"2026-03-31T22:17:20.624439Z","steps":["trace[948811152] 'process raft request' (duration: 178.329382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:20.624564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.094451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.624604Z","caller":"traceutil/trace.go:171","msg":"trace[694713631] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1056; }","duration":"145.164112ms","start":"2026-03-31T22:17:20.479428Z","end":"2026-03-31T22:17:20.624592Z","steps":["trace[694713631] 'agreement among raft nodes before linearized reading' (duration: 145.065511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:20.62458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.126731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.62468Z","caller":"traceutil/trace.go:171","msg":"trace[2145364016] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1056; }","duration":"145.288414ms","start":"2026-03-31T22:17:20.479369Z","end":"2026-03-31T22:17:20.624657Z","steps":["trace[2145364016] 'agreement among raft nodes before linearized reading' (duration: 145.094371ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:20.894299Z","caller":"traceutil/trace.go:171","msg":"trace[1468680468] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"252.832444ms","start":"2026-03-31T22:17:20.64144Z","end":"2026-03-31T22:17:20.894272Z","steps":["trace[1468680468] 'process raft request' (duration: 252.59127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:20.894534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.917504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:17:20.894533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.766641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.894632Z","caller":"traceutil/trace.go:171","msg":"trace[279677414] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1058; }","duration":"232.048245ms","start":"2026-03-31T22:17:20.662559Z","end":"2026-03-31T22:17:20.894607Z","steps":["trace[279677414] 'agreement among raft nodes before linearized reading' (duration: 231.886483ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:20.894644Z","caller":"traceutil/trace.go:171","msg":"trace[1256431179] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"218.180643ms","start":"2026-03-31T22:17:20.676053Z","end":"2026-03-31T22:17:20.894234Z","steps":["trace[1256431179] 'read index received' (duration: 217.900479ms)","trace[1256431179] 'applied index is now lower than readState.Index' (duration: 276.664µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:20.894654Z","caller":"traceutil/trace.go:171","msg":"trace[2015913241] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1058; }","duration":"231.976395ms","start":"2026-03-31T22:17:20.662652Z","end":"2026-03-31T22:17:20.894628Z","steps":["trace[2015913241] 'agreement among raft nodes before linearized reading' (duration: 231.67181ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:20.92164Z","caller":"traceutil/trace.go:171","msg":"trace[550868939] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"240.524812ms","start":"2026-03-31T22:17:20.68107Z","end":"2026-03-31T22:17:20.921594Z","steps":["trace[550868939] 'process raft request' (duration: 240.288349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:20.94203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.25351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:17:20.942079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.501123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.942123Z","caller":"traceutil/trace.go:171","msg":"trace[1409404266] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1059; }","duration":"113.389671ms","start":"2026-03-31T22:17:20.828714Z","end":"2026-03-31T22:17:20.942104Z","steps":["trace[1409404266] 'agreement among raft nodes before linearized reading' (duration: 113.219879ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:20.942136Z","caller":"traceutil/trace.go:171","msg":"trace[1658122558] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1059; }","duration":"113.588934ms","start":"2026-03-31T22:17:20.828532Z","end":"2026-03-31T22:17:20.942121Z","steps":["trace[1658122558] 'agreement among raft nodes before linearized reading' (duration: 113.487642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:20.942229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.269888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.942716Z","caller":"traceutil/trace.go:171","msg":"trace[1322669757] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1059; }","duration":"245.769485ms","start":"2026-03-31T22:17:20.69693Z","end":"2026-03-31T22:17:20.9427Z","steps":["trace[1322669757] 'agreement among raft nodes before linearized reading' (duration: 245.245247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:20.942291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.773007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.943006Z","caller":"traceutil/trace.go:171","msg":"trace[1239922027] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1059; }","duration":"114.490636ms","start":"2026-03-31T22:17:20.828472Z","end":"2026-03-31T22:17:20.942963Z","steps":["trace[1239922027] 'agreement among raft nodes before linearized reading' (duration: 113.790837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:20.942445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.528852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.943259Z","caller":"traceutil/trace.go:171","msg":"trace[1241219741] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1059; }","duration":"246.290552ms","start":"2026-03-31T22:17:20.696864Z","end":"2026-03-31T22:17:20.943155Z","steps":["trace[1241219741] 'agreement among raft nodes before linearized reading' (duration: 245.497481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:20.942556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.098846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:20.943428Z","caller":"traceutil/trace.go:171","msg":"trace[565762782] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1059; }","duration":"246.15866ms","start":"2026-03-31T22:17:20.697219Z","end":"2026-03-31T22:17:20.943378Z","steps":["trace[565762782] 'agreement among raft nodes before linearized reading' (duration: 245.095795ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:17:32.890357Z","caller":"traceutil/trace.go:171","msg":"trace[885120601] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"157.424158ms","start":"2026-03-31T22:17:32.732913Z","end":"2026-03-31T22:17:32.890337Z","steps":["trace[885120601] 'process raft request' (duration: 157.309447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:36.25506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878102567483301,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:17:36.755563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878102567483301,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T22:17:36.895968Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.279523802s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T22:17:36.896487Z","caller":"traceutil/trace.go:171","msg":"trace[981984225] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"1.28013303s","start":"2026-03-31T22:17:35.616321Z","end":"2026-03-31T22:17:36.896454Z","steps":["trace[981984225] 'process raft request' (duration: 1.279934567s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:36.896655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:35.616288Z","time spent":"1.280260661s","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T22:17:37.020161Z","caller":"traceutil/trace.go:171","msg":"trace[1470757605] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1195; }","duration":"1.265785745s","start":"2026-03-31T22:17:35.754348Z","end":"2026-03-31T22:17:37.020134Z","steps":["trace[1470757605] 'read index received' (duration: 1.141968744s)","trace[1470757605] 'applied index is now lower than readState.Index' (duration: 123.815881ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:17:37.020303Z","caller":"traceutil/trace.go:171","msg":"trace[1480957573] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"684.348901ms","start":"2026-03-31T22:17:36.335942Z","end":"2026-03-31T22:17:37.020291Z","steps":["trace[1480957573] 'process raft request' (duration: 683.985056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:37.020425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:36.335913Z","time spent":"684.413423ms","remote":"127.0.0.1:37254","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-31T22:17:37.020445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.34952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T22:17:37.020489Z","caller":"traceutil/trace.go:171","msg":"trace[268496585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"787.429771ms","start":"2026-03-31T22:17:36.233047Z","end":"2026-03-31T22:17:37.020477Z","steps":["trace[268496585] 'agreement among raft nodes before linearized reading' (duration: 787.36521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:37.02052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:36.233022Z","time spent":"787.492802ms","remote":"127.0.0.1:36984","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-31T22:17:37.020846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.329219ms","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-31T22:17:37.020883Z","caller":"traceutil/trace.go:171","msg":"trace[1102079553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1139; }","duration":"115.418581ms","start":"2026-03-31T22:17:36.905456Z","end":"2026-03-31T22:17:37.020875Z","steps":["trace[1102079553] 'agreement among raft nodes before linearized reading' (duration: 115.288399ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:37.020904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266508445s","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-31T22:17:37.021275Z","caller":"traceutil/trace.go:171","msg":"trace[1404605377] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1139; }","duration":"1.266956771s","start":"2026-03-31T22:17:35.75427Z","end":"2026-03-31T22:17:37.021227Z","steps":["trace[1404605377] 'agreement among raft nodes before linearized reading' (duration: 1.266487184s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:17:37.021358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T22:17:35.754218Z","time spent":"1.267101843s","remote":"127.0.0.1:37170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-03-31T22:18:53.478325Z","caller":"traceutil/trace.go:171","msg":"trace[2124034295] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"173.954972ms","start":"2026-03-31T22:18:53.304336Z","end":"2026-03-31T22:18:53.478291Z","steps":["trace[2124034295] 'process raft request' (duration: 173.679229ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:01.630154Z","caller":"traceutil/trace.go:171","msg":"trace[1112213580] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"117.685712ms","start":"2026-03-31T22:19:01.512437Z","end":"2026-03-31T22:19:01.630122Z","steps":["trace[1112213580] 'process raft request' (duration: 117.557868ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:01.704249Z","caller":"traceutil/trace.go:171","msg":"trace[1305312561] linearizableReadLoop","detail":"{readStateIndex:1627; appliedIndex:1625; }","duration":"185.27234ms","start":"2026-03-31T22:19:01.518945Z","end":"2026-03-31T22:19:01.704217Z","steps":["trace[1305312561] 'read index received' (duration: 111.079899ms)","trace[1305312561] 'applied index is now lower than readState.Index' (duration: 74.191161ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:19:01.704427Z","caller":"traceutil/trace.go:171","msg":"trace[603222063] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"191.043676ms","start":"2026-03-31T22:19:01.513332Z","end":"2026-03-31T22:19:01.704375Z","steps":["trace[603222063] 'process raft request' (duration: 160.310565ms)","trace[603222063] 'compare' (duration: 30.442181ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:19:01.704561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.58966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T22:19:01.70462Z","caller":"traceutil/trace.go:171","msg":"trace[1417105123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1549; }","duration":"185.681053ms","start":"2026-03-31T22:19:01.518924Z","end":"2026-03-31T22:19:01.704605Z","steps":["trace[1417105123] 'agreement among raft nodes before linearized reading' (duration: 185.471886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:01.705911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.494897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-03-31T22:19:01.706034Z","caller":"traceutil/trace.go:171","msg":"trace[1528721291] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1549; }","duration":"140.669852ms","start":"2026-03-31T22:19:01.565333Z","end":"2026-03-31T22:19:01.706003Z","steps":["trace[1528721291] 'agreement among raft nodes before linearized reading' (duration: 139.262487ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:38.228425Z","caller":"traceutil/trace.go:171","msg":"trace[1771985770] linearizableReadLoop","detail":"{readStateIndex:1802; appliedIndex:1801; }","duration":"168.544731ms","start":"2026-03-31T22:19:38.059841Z","end":"2026-03-31T22:19:38.228386Z","steps":["trace[1771985770] 'read index received' (duration: 150.023335ms)","trace[1771985770] 'applied index is now lower than readState.Index' (duration: 18.520266ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T22:19:38.228479Z","caller":"traceutil/trace.go:171","msg":"trace[1986032316] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"176.515929ms","start":"2026-03-31T22:19:38.051923Z","end":"2026-03-31T22:19:38.228439Z","steps":["trace[1986032316] 'process raft request' (duration: 157.89062ms)","trace[1986032316] 'compare' (duration: 18.328601ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:19:38.228639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.434156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-03-31T22:19:38.228685Z","caller":"traceutil/trace.go:171","msg":"trace[497604182] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1714; }","duration":"146.533819ms","start":"2026-03-31T22:19:38.082137Z","end":"2026-03-31T22:19:38.228671Z","steps":["trace[497604182] 'agreement among raft nodes before linearized reading' (duration: 146.437706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:38.228676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.831409ms","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-31T22:19:38.228668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.825061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-03-31T22:19:38.228743Z","caller":"traceutil/trace.go:171","msg":"trace[2031381699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1714; }","duration":"168.917512ms","start":"2026-03-31T22:19:38.059808Z","end":"2026-03-31T22:19:38.228725Z","steps":["trace[2031381699] 'agreement among raft nodes before linearized reading' (duration: 168.716436ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:38.228754Z","caller":"traceutil/trace.go:171","msg":"trace[1761201525] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1714; }","duration":"118.947925ms","start":"2026-03-31T22:19:38.109788Z","end":"2026-03-31T22:19:38.228736Z","steps":["trace[1761201525] 'agreement among raft nodes before linearized reading' (duration: 118.816951ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:49.549984Z","caller":"traceutil/trace.go:171","msg":"trace[1399285598] linearizableReadLoop","detail":"{readStateIndex:1839; appliedIndex:1838; }","duration":"121.008247ms","start":"2026-03-31T22:19:49.428943Z","end":"2026-03-31T22:19:49.549951Z","steps":["trace[1399285598] 'read index received' (duration: 37.113511ms)","trace[1399285598] 'applied index is now lower than readState.Index' (duration: 83.893176ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:19:49.550353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.63821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-public/\" range_end:\"/registry/services/endpoints/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T22:19:49.550424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.476531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/default\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-31T22:19:49.550455Z","caller":"traceutil/trace.go:171","msg":"trace[1545694872] range","detail":"{range_begin:/registry/services/endpoints/kube-public/; range_end:/registry/services/endpoints/kube-public0; response_count:0; response_revision:1748; }","duration":"114.767613ms","start":"2026-03-31T22:19:49.435667Z","end":"2026-03-31T22:19:49.550435Z","steps":["trace[1545694872] 'agreement among raft nodes before linearized reading' (duration: 114.571998ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:49.550491Z","caller":"traceutil/trace.go:171","msg":"trace[1786667448] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/default; range_end:; response_count:1; response_revision:1748; }","duration":"110.606724ms","start":"2026-03-31T22:19:49.439868Z","end":"2026-03-31T22:19:49.550474Z","steps":["trace[1786667448] 'agreement among raft nodes before linearized reading' (duration: 110.4417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T22:19:49.550853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.9002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-31T22:19:49.550913Z","caller":"traceutil/trace.go:171","msg":"trace[1529917042] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1748; }","duration":"121.991143ms","start":"2026-03-31T22:19:49.428906Z","end":"2026-03-31T22:19:49.550897Z","steps":["trace[1529917042] 'agreement among raft nodes before linearized reading' (duration: 121.204282ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:52.148495Z","caller":"traceutil/trace.go:171","msg":"trace[2062152383] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"137.407093ms","start":"2026-03-31T22:19:52.011067Z","end":"2026-03-31T22:19:52.148474Z","steps":["trace[2062152383] 'process raft request' (duration: 94.74356ms)","trace[2062152383] 'compare' (duration: 42.55736ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T22:19:52.148709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.751285ms","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-31T22:19:52.148738Z","caller":"traceutil/trace.go:171","msg":"trace[758270166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1758; }","duration":"117.821157ms","start":"2026-03-31T22:19:52.030909Z","end":"2026-03-31T22:19:52.14873Z","steps":["trace[758270166] 'agreement among raft nodes before linearized reading' (duration: 117.682913ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T22:19:58.470389Z","caller":"traceutil/trace.go:171","msg":"trace[865206595] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"118.689409ms","start":"2026-03-31T22:19:58.351659Z","end":"2026-03-31T22:19:58.470349Z","steps":["trace[865206595] 'process raft request' (duration: 105.797293ms)","trace[865206595] 'compare' (duration: 12.698461ms)"],"step_count":2}