{"level":"warn","ts":"2026-05-08T05:16:47.248963Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T05:16:47.249037Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T05:16:47.249108Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T05:16:47.249123Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-08T05:16:47.249143Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T05:16:47.249807Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-05-08T05:16:47.249906Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T05:16:47.25883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.730325ms"} {"level":"info","ts":"2026-05-08T05:16:47.280191Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-05-08T05:16:47.280321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T05:16:47.280349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-05-08T05:16:47.280368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T05:16:47.280382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-05-08T05:16:47.280428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-05-08T05:16:47.293197Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T05:16:47.295112Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T05:16:47.296612Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T05:16:47.299964Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T05:16:47.300121Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T05:16:47.301703Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T05:16:47.30211Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T05:16:47.302147Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T05:16:47.302476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-05-08T05:16:47.304013Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-08T05:16:47.309127Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T05:16:47.309207Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-08T05:16:47.309239Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-08T05:16:47.309345Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T05:16:47.309371Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T05:16:47.381038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T05:16:47.381173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T05:16:47.381205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-05-08T05:16:47.381254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-05-08T05:16:47.381271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-08T05:16:47.381295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-05-08T05:16:47.381334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-08T05:16:47.38243Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T05:16:47.382446Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T05:16:47.38249Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T05:16:47.382421Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T05:16:47.382898Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T05:16:47.382961Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T05:16:47.383481Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T05:16:47.383591Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T05:16:47.383631Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T05:16:47.386634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T05:16:47.389103Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-05-08T05:16:49.641747Z","caller":"traceutil/trace.go:171","msg":"trace[2017066728] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"387.246715ms","start":"2026-05-08T05:16:49.254473Z","end":"2026-05-08T05:16:49.641719Z","steps":["trace[2017066728] 'read index received' (duration: 386.698455ms)","trace[2017066728] 'applied index is now lower than readState.Index' (duration: 547.03µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:16:49.641823Z","caller":"traceutil/trace.go:171","msg":"trace[1318652770] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"388.160113ms","start":"2026-05-08T05:16:49.253636Z","end":"2026-05-08T05:16:49.641796Z","steps":["trace[1318652770] 'process raft request' (duration: 387.582262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:49.642057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.547701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T05:16:49.642137Z","caller":"traceutil/trace.go:171","msg":"trace[272511049] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:2; }","duration":"387.690084ms","start":"2026-05-08T05:16:49.254436Z","end":"2026-05-08T05:16:49.642126Z","steps":["trace[272511049] 'agreement among raft nodes before linearized reading' (duration: 387.4719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:49.642204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.254417Z","time spent":"387.752286ms","remote":"127.0.0.1:54004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-08T05:16:49.642954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.253625Z","time spent":"388.257365ms","remote":"127.0.0.1:54000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:16:50.142419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495897792262272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T05:16:50.785395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.212633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-08T05:16:50.785913Z","caller":"traceutil/trace.go:171","msg":"trace[866334180] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.452750422s","start":"2026-05-08T05:16:49.33309Z","end":"2026-05-08T05:16:50.78584Z","steps":["trace[866334180] 'process raft request' (duration: 1.128287065s)","trace[866334180] 'compare' (duration: 322.752735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:16:50.786109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.333046Z","time spent":"1.452941186s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T05:16:50.78643Z","caller":"traceutil/trace.go:171","msg":"trace[83650249] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"1.144560583s","start":"2026-05-08T05:16:49.641854Z","end":"2026-05-08T05:16:50.786415Z","steps":["trace[83650249] 'read index received' (duration: 819.454392ms)","trace[83650249] 'applied index is now lower than readState.Index' (duration: 325.105221ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:16:50.786422Z","caller":"traceutil/trace.go:171","msg":"trace[1704001329] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.452901126s","start":"2026-05-08T05:16:49.33348Z","end":"2026-05-08T05:16:50.786381Z","steps":["trace[1704001329] 'process raft request' (duration: 1.452424887s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.787328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.680151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-08T05:16:50.787394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.441946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T05:16:50.787442Z","caller":"traceutil/trace.go:171","msg":"trace[2090233982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"510.516907ms","start":"2026-05-08T05:16:50.276911Z","end":"2026-05-08T05:16:50.787428Z","steps":["trace[2090233982] 'agreement among raft nodes before linearized reading' (duration: 510.449616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.787483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:50.276899Z","time spent":"510.578638ms","remote":"127.0.0.1:53956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T05:16:50.787546Z","caller":"traceutil/trace.go:171","msg":"trace[374893911] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"556.867653ms","start":"2026-05-08T05:16:50.230496Z","end":"2026-05-08T05:16:50.787364Z","steps":["trace[374893911] 'agreement among raft nodes before linearized reading' (duration: 556.70575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.787619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:50.230464Z","time spent":"557.129159ms","remote":"127.0.0.1:54260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-08T05:16:50.787321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.333464Z","time spent":"1.453743881s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T05:16:50.78791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.965986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T05:16:50.786574Z","caller":"traceutil/trace.go:171","msg":"trace[41534312] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.440202377s","start":"2026-05-08T05:16:49.346307Z","end":"2026-05-08T05:16:50.786509Z","steps":["trace[41534312] 'process raft request' (duration: 1.439939412s)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:16:50.786764Z","caller":"traceutil/trace.go:171","msg":"trace[1687998878] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.45307328s","start":"2026-05-08T05:16:49.333655Z","end":"2026-05-08T05:16:50.786728Z","steps":["trace[1687998878] 'process raft request' (duration: 1.452404186s)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:16:50.786805Z","caller":"traceutil/trace.go:171","msg":"trace[411036413] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.452911427s","start":"2026-05-08T05:16:49.33388Z","end":"2026-05-08T05:16:50.786792Z","steps":["trace[411036413] 'process raft request' (duration: 1.452208423s)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:16:50.786823Z","caller":"traceutil/trace.go:171","msg":"trace[1696497513] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.453349874s","start":"2026-05-08T05:16:49.333461Z","end":"2026-05-08T05:16:50.786811Z","steps":["trace[1696497513] 'process raft request' (duration: 1.452398166s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.786861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453075719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-08T05:16:50.786946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480888116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T05:16:50.78698Z","caller":"traceutil/trace.go:171","msg":"trace[1135961780] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.143443352s","start":"2026-05-08T05:16:49.643509Z","end":"2026-05-08T05:16:50.786953Z","steps":["trace[1135961780] 'process raft request' (duration: 1.142847381s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.786976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456177048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-08T05:16:50.787002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455591148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-08T05:16:50.78702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403143135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T05:16:50.787071Z","caller":"traceutil/trace.go:171","msg":"trace[1758089019] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.445405464s","start":"2026-05-08T05:16:49.341651Z","end":"2026-05-08T05:16:50.787057Z","steps":["trace[1758089019] 'process raft request' (duration: 1.444505516s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.787069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518985086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T05:16:50.786616Z","caller":"traceutil/trace.go:171","msg":"trace[85987368] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.453290063s","start":"2026-05-08T05:16:49.333313Z","end":"2026-05-08T05:16:50.786603Z","steps":["trace[85987368] 'process raft request' (duration: 1.452453767s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.788523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.333301Z","time spent":"1.455092877s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T05:16:50.788884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.346294Z","time spent":"1.44252664s","remote":"127.0.0.1:54158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T05:16:50.789234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.333644Z","time spent":"1.455495406s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T05:16:50.78931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.333436Z","time spent":"1.455792601s","remote":"127.0.0.1:54342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T05:16:50.789365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.341636Z","time spent":"1.447708997s","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T05:16:50.789265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.333861Z","time spent":"1.455263211s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T05:16:50.789602Z","caller":"traceutil/trace.go:171","msg":"trace[231284109] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.405796005s","start":"2026-05-08T05:16:49.383788Z","end":"2026-05-08T05:16:50.789584Z","steps":["trace[231284109] 'agreement among raft nodes before linearized reading' (duration: 1.403163875s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.78966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.383772Z","time spent":"1.405868046s","remote":"127.0.0.1:54038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-08T05:16:50.789712Z","caller":"traceutil/trace.go:171","msg":"trace[1676958738] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.521612276s","start":"2026-05-08T05:16:49.268059Z","end":"2026-05-08T05:16:50.789671Z","steps":["trace[1676958738] 'agreement among raft nodes before linearized reading' (duration: 1.518993827s)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:16:50.789721Z","caller":"traceutil/trace.go:171","msg":"trace[119321635] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.456009985s","start":"2026-05-08T05:16:49.333694Z","end":"2026-05-08T05:16:50.789704Z","steps":["trace[119321635] 'agreement among raft nodes before linearized reading' (duration: 1.452891586s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.789797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.333664Z","time spent":"1.456116227s","remote":"127.0.0.1:54022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-05-08T05:16:50.78875Z","caller":"traceutil/trace.go:171","msg":"trace[829395085] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.48272261s","start":"2026-05-08T05:16:49.30601Z","end":"2026-05-08T05:16:50.788733Z","steps":["trace[829395085] 'agreement among raft nodes before linearized reading' (duration: 1.480844495s)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:16:50.789953Z","caller":"traceutil/trace.go:171","msg":"trace[1481861695] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:0; response_revision:11; }","duration":"1.459228825s","start":"2026-05-08T05:16:49.330713Z","end":"2026-05-08T05:16:50.789942Z","steps":["trace[1481861695] 'agreement among raft nodes before linearized reading' (duration: 1.456137037s)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:16:50.789214Z","caller":"traceutil/trace.go:171","msg":"trace[809330835] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.45785197s","start":"2026-05-08T05:16:49.331333Z","end":"2026-05-08T05:16:50.789185Z","steps":["trace[809330835] 'agreement among raft nodes before linearized reading' (duration: 1.455651468s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.789972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.643466Z","time spent":"1.14596392s","remote":"127.0.0.1:54004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:16:50.790004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.330703Z","time spent":"1.459291446s","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-05-08T05:16:50.790008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.331304Z","time spent":"1.458694685s","remote":"127.0.0.1:54038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-08T05:16:50.789923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.305999Z","time spent":"1.483881112s","remote":"127.0.0.1:54038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-08T05:16:50.788794Z","caller":"traceutil/trace.go:171","msg":"trace[1230954087] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"557.850063ms","start":"2026-05-08T05:16:50.230933Z","end":"2026-05-08T05:16:50.788783Z","steps":["trace[1230954087] 'agreement among raft nodes before linearized reading' (duration: 556.933966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:16:50.791888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:49.268047Z","time spent":"1.521707378s","remote":"127.0.0.1:54302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-08T05:16:50.791991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:16:50.2309Z","time spent":"560.941782ms","remote":"127.0.0.1:54274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-05-08T05:17:16.721387Z","caller":"traceutil/trace.go:171","msg":"trace[1202368598] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"177.04557ms","start":"2026-05-08T05:17:16.544321Z","end":"2026-05-08T05:17:16.721367Z","steps":["trace[1202368598] 'process raft request' (duration: 176.930308ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:18.090788Z","caller":"traceutil/trace.go:171","msg":"trace[336160118] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"167.477199ms","start":"2026-05-08T05:17:17.923296Z","end":"2026-05-08T05:17:18.090773Z","steps":["trace[336160118] 'process raft request' (duration: 167.169303ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:18.099712Z","caller":"traceutil/trace.go:171","msg":"trace[611079999] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"172.193197ms","start":"2026-05-08T05:17:17.927499Z","end":"2026-05-08T05:17:18.099693Z","steps":["trace[611079999] 'process raft request' (duration: 171.879871ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:18.100265Z","caller":"traceutil/trace.go:171","msg":"trace[821663171] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:477; }","duration":"151.292282ms","start":"2026-05-08T05:17:17.94895Z","end":"2026-05-08T05:17:18.100242Z","steps":["trace[821663171] 'read index received' (duration: 141.522017ms)","trace[821663171] 'applied index is now lower than readState.Index' (duration: 9.769365ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:17:18.100417Z","caller":"traceutil/trace.go:171","msg":"trace[498068219] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"172.049045ms","start":"2026-05-08T05:17:17.928356Z","end":"2026-05-08T05:17:18.100405Z","steps":["trace[498068219] 'process raft request' (duration: 171.308791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:18.100557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.605998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:18.100591Z","caller":"traceutil/trace.go:171","msg":"trace[1212786608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"151.677468ms","start":"2026-05-08T05:17:17.948904Z","end":"2026-05-08T05:17:18.100582Z","steps":["trace[1212786608] 'agreement among raft nodes before linearized reading' (duration: 151.621637ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.873159Z","caller":"traceutil/trace.go:171","msg":"trace[1007875424] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"255.246248ms","start":"2026-05-08T05:17:19.617888Z","end":"2026-05-08T05:17:19.873134Z","steps":["trace[1007875424] 'process raft request' (duration: 255.119546ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881066Z","caller":"traceutil/trace.go:171","msg":"trace[1137235501] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"218.959452ms","start":"2026-05-08T05:17:19.662075Z","end":"2026-05-08T05:17:19.881035Z","steps":["trace[1137235501] 'process raft request' (duration: 218.732167ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881162Z","caller":"traceutil/trace.go:171","msg":"trace[1284624361] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"223.327305ms","start":"2026-05-08T05:17:19.657818Z","end":"2026-05-08T05:17:19.881145Z","steps":["trace[1284624361] 'process raft request' (duration: 222.883366ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881277Z","caller":"traceutil/trace.go:171","msg":"trace[430861713] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"204.691382ms","start":"2026-05-08T05:17:19.676548Z","end":"2026-05-08T05:17:19.88124Z","steps":["trace[430861713] 'process raft request' (duration: 204.378075ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881407Z","caller":"traceutil/trace.go:171","msg":"trace[2145238785] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"204.235834ms","start":"2026-05-08T05:17:19.677146Z","end":"2026-05-08T05:17:19.881381Z","steps":["trace[2145238785] 'process raft request' (duration: 203.876227ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881569Z","caller":"traceutil/trace.go:171","msg":"trace[1055259440] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"186.204723ms","start":"2026-05-08T05:17:19.695352Z","end":"2026-05-08T05:17:19.881557Z","steps":["trace[1055259440] 'process raft request' (duration: 185.781475ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881054Z","caller":"traceutil/trace.go:171","msg":"trace[1934327583] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"221.076572ms","start":"2026-05-08T05:17:19.659947Z","end":"2026-05-08T05:17:19.881023Z","steps":["trace[1934327583] 'process raft request' (duration: 220.796866ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881661Z","caller":"traceutil/trace.go:171","msg":"trace[418066843] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"213.315566ms","start":"2026-05-08T05:17:19.668287Z","end":"2026-05-08T05:17:19.881602Z","steps":["trace[418066843] 'process raft request' (duration: 212.557371ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881784Z","caller":"traceutil/trace.go:171","msg":"trace[744294043] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"248.765167ms","start":"2026-05-08T05:17:19.633009Z","end":"2026-05-08T05:17:19.881775Z","steps":["trace[744294043] 'process raft request' (duration: 247.595745ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.881792Z","caller":"traceutil/trace.go:171","msg":"trace[1297284563] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"260.536159ms","start":"2026-05-08T05:17:19.621237Z","end":"2026-05-08T05:17:19.881773Z","steps":["trace[1297284563] 'process raft request' (duration: 259.273215ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:19.882032Z","caller":"traceutil/trace.go:171","msg":"trace[1249395252] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"231.702085ms","start":"2026-05-08T05:17:19.649291Z","end":"2026-05-08T05:17:19.880993Z","steps":["trace[1249395252] 'process raft request' (duration: 231.372508ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:22.968815Z","caller":"traceutil/trace.go:171","msg":"trace[2055123809] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"212.109943ms","start":"2026-05-08T05:17:22.756681Z","end":"2026-05-08T05:17:22.968791Z","steps":["trace[2055123809] 'read index received' (duration: 211.91687ms)","trace[2055123809] 'applied index is now lower than readState.Index' (duration: 192.213µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:17:22.96891Z","caller":"traceutil/trace.go:171","msg":"trace[1625345969] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"214.487828ms","start":"2026-05-08T05:17:22.754386Z","end":"2026-05-08T05:17:22.968874Z","steps":["trace[1625345969] 'process raft request' (duration: 214.281444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:22.96898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.263296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:22.969069Z","caller":"traceutil/trace.go:171","msg":"trace[1083130913] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:0; response_revision:552; }","duration":"212.401919ms","start":"2026-05-08T05:17:22.756645Z","end":"2026-05-08T05:17:22.969047Z","steps":["trace[1083130913] 'agreement among raft nodes before linearized reading' (duration: 212.241806ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:26.645117Z","caller":"traceutil/trace.go:171","msg":"trace[780335916] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"200.167257ms","start":"2026-05-08T05:17:26.444918Z","end":"2026-05-08T05:17:26.645085Z","steps":["trace[780335916] 'process raft request' (duration: 199.999813ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:27.233069Z","caller":"traceutil/trace.go:171","msg":"trace[1674097900] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"756.027283ms","start":"2026-05-08T05:17:26.477019Z","end":"2026-05-08T05:17:27.233047Z","steps":["trace[1674097900] 'process raft request' (duration: 754.402141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:27.233208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:26.47699Z","time spent":"756.143345ms","remote":"127.0.0.1:54024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:17:27.509503Z","caller":"traceutil/trace.go:171","msg":"trace[249162784] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"560.275489ms","start":"2026-05-08T05:17:26.949197Z","end":"2026-05-08T05:17:27.509472Z","steps":["trace[249162784] 'read index received' (duration: 281.359443ms)","trace[249162784] 'applied index is now lower than readState.Index' (duration: 278.914216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:17:27.509701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.490093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:27.509748Z","caller":"traceutil/trace.go:171","msg":"trace[389898098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"560.596915ms","start":"2026-05-08T05:17:26.949135Z","end":"2026-05-08T05:17:27.509732Z","steps":["trace[389898098] 'agreement among raft nodes before linearized reading' (duration: 560.502293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:27.509813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:26.949109Z","time spent":"560.692756ms","remote":"127.0.0.1:53956","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-05-08T05:17:27.523489Z","caller":"traceutil/trace.go:171","msg":"trace[1403680737] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"232.183443ms","start":"2026-05-08T05:17:27.291266Z","end":"2026-05-08T05:17:27.523449Z","steps":["trace[1403680737] 'process raft request' (duration: 231.853967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:27.523522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.741372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-08T05:17:27.524432Z","caller":"traceutil/trace.go:171","msg":"trace[983240234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"308.69384ms","start":"2026-05-08T05:17:27.215692Z","end":"2026-05-08T05:17:27.524386Z","steps":["trace[983240234] 'agreement among raft nodes before linearized reading' (duration: 307.64574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:27.524502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:27.215652Z","time spent":"308.826013ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T05:17:27.524153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.124765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T05:17:27.524663Z","caller":"traceutil/trace.go:171","msg":"trace[989373215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"304.309358ms","start":"2026-05-08T05:17:27.220335Z","end":"2026-05-08T05:17:27.524644Z","steps":["trace[989373215] 'agreement among raft nodes before linearized reading' (duration: 302.984493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:27.524728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:27.220326Z","time spent":"304.387169ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T05:17:27.524897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.655939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T05:17:27.524973Z","caller":"traceutil/trace.go:171","msg":"trace[657342344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"503.70938ms","start":"2026-05-08T05:17:27.021227Z","end":"2026-05-08T05:17:27.524936Z","steps":["trace[657342344] 'agreement among raft nodes before linearized reading' (duration: 502.256853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:27.525027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:27.021174Z","time spent":"503.840252ms","remote":"127.0.0.1:54164","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-05-08T05:17:28.539286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.070382ms","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-05-08T05:17:28.539436Z","caller":"traceutil/trace.go:171","msg":"trace[1090601370] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:581; }","duration":"535.283087ms","start":"2026-05-08T05:17:28.004132Z","end":"2026-05-08T05:17:28.539415Z","steps":["trace[1090601370] 'range keys from in-memory index tree' (duration: 535.051542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:28.539568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.423665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T05:17:28.539815Z","caller":"traceutil/trace.go:171","msg":"trace[1807590840] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:603; }","duration":"998.727785ms","start":"2026-05-08T05:17:27.54106Z","end":"2026-05-08T05:17:28.539787Z","steps":["trace[1807590840] 'read index received' (duration: 4.301213ms)","trace[1807590840] 'applied index is now lower than readState.Index' (duration: 994.425382ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:17:28.539876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.814637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:28.539904Z","caller":"traceutil/trace.go:171","msg":"trace[1057180908] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:584; }","duration":"998.842017ms","start":"2026-05-08T05:17:27.54105Z","end":"2026-05-08T05:17:28.539892Z","steps":["trace[1057180908] 'agreement among raft nodes before linearized reading' (duration: 998.796666ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:28.539923Z","caller":"traceutil/trace.go:171","msg":"trace[2038680360] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.006246326s","start":"2026-05-08T05:17:27.533661Z","end":"2026-05-08T05:17:28.539907Z","steps":["trace[2038680360] 'process raft request' (duration: 87.379333ms)","trace[2038680360] 'compare' (duration: 918.17071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:17:28.539938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:27.541017Z","time spent":"998.913138ms","remote":"127.0.0.1:54140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-08T05:17:28.540023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:27.533649Z","time spent":"1.006308037s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:17:28.540136Z","caller":"traceutil/trace.go:171","msg":"trace[1482299825] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.006336738s","start":"2026-05-08T05:17:27.533768Z","end":"2026-05-08T05:17:28.540104Z","steps":["trace[1482299825] 'process raft request' (duration: 1.005980011s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:28.540244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:27.533763Z","time spent":"1.00643546s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:17:28.540564Z","caller":"traceutil/trace.go:171","msg":"trace[1249064571] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.006818637s","start":"2026-05-08T05:17:27.533732Z","end":"2026-05-08T05:17:28.540551Z","steps":["trace[1249064571] 'process raft request' (duration: 1.005961371s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:28.540826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:27.533726Z","time spent":"1.006871618s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:17:30.083896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.457487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:30.083997Z","caller":"traceutil/trace.go:171","msg":"trace[1656235661] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"131.583099ms","start":"2026-05-08T05:17:29.952382Z","end":"2026-05-08T05:17:30.083965Z","steps":["trace[1656235661] 'count revisions from in-memory index tree' (duration: 131.377196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.084115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.857044ms","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":"warn","ts":"2026-05-08T05:17:30.083987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.768104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:30.084159Z","caller":"traceutil/trace.go:171","msg":"trace[487803481] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:596; }","duration":"196.947526ms","start":"2026-05-08T05:17:29.887198Z","end":"2026-05-08T05:17:30.084146Z","steps":["trace[487803481] 'count revisions from in-memory index tree' (duration: 196.772163ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:30.08419Z","caller":"traceutil/trace.go:171","msg":"trace[600315822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"130.977798ms","start":"2026-05-08T05:17:29.95319Z","end":"2026-05-08T05:17:30.084168Z","steps":["trace[600315822] 'range keys from in-memory index tree' (duration: 130.684873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.084352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.335844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T05:17:30.08432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.645108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T05:17:30.084456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.470086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:30.084493Z","caller":"traceutil/trace.go:171","msg":"trace[1527342481] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:596; }","duration":"114.522127ms","start":"2026-05-08T05:17:29.969957Z","end":"2026-05-08T05:17:30.084479Z","steps":["trace[1527342481] 'count revisions from in-memory index tree' (duration: 114.345164ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:30.08451Z","caller":"traceutil/trace.go:171","msg":"trace[79803818] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"161.904773ms","start":"2026-05-08T05:17:29.922562Z","end":"2026-05-08T05:17:30.084467Z","steps":["trace[79803818] 'count revisions from in-memory index tree' (duration: 161.530856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.084595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.238718ms","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":"warn","ts":"2026-05-08T05:17:30.084601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.465646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:30.084637Z","caller":"traceutil/trace.go:171","msg":"trace[256421533] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:596; }","duration":"329.293529ms","start":"2026-05-08T05:17:29.755333Z","end":"2026-05-08T05:17:30.084627Z","steps":["trace[256421533] 'count revisions from in-memory index tree' (duration: 329.210858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.084661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.027227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:30.084676Z","caller":"traceutil/trace.go:171","msg":"trace[1211646517] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:596; }","duration":"196.576719ms","start":"2026-05-08T05:17:29.888084Z","end":"2026-05-08T05:17:30.084661Z","steps":["trace[1211646517] 'range keys from in-memory index tree' (duration: 196.387945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.084676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:29.75533Z","time spent":"329.335ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-08T05:17:30.084692Z","caller":"traceutil/trace.go:171","msg":"trace[1119649992] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:596; }","duration":"114.083918ms","start":"2026-05-08T05:17:29.970599Z","end":"2026-05-08T05:17:30.084683Z","steps":["trace[1119649992] 'range keys from in-memory index tree' (duration: 113.959015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.084832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.534874ms","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-05-08T05:17:30.084862Z","caller":"traceutil/trace.go:171","msg":"trace[640446918] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:596; }","duration":"329.585875ms","start":"2026-05-08T05:17:29.755265Z","end":"2026-05-08T05:17:30.084851Z","steps":["trace[640446918] 'range keys from in-memory index tree' (duration: 329.441103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.08489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:29.755251Z","time spent":"329.631306ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-05-08T05:17:30.084412Z","caller":"traceutil/trace.go:171","msg":"trace[1818974955] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:596; }","duration":"114.418845ms","start":"2026-05-08T05:17:29.969978Z","end":"2026-05-08T05:17:30.084397Z","steps":["trace[1818974955] 'range keys from in-memory index tree' (duration: 114.235392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.084967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.487395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:30.084986Z","caller":"traceutil/trace.go:171","msg":"trace[1666867787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"133.522346ms","start":"2026-05-08T05:17:29.951457Z","end":"2026-05-08T05:17:30.08498Z","steps":["trace[1666867787] 'range keys from in-memory index tree' (duration: 133.434564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.085031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.875363ms","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-05-08T05:17:30.085058Z","caller":"traceutil/trace.go:171","msg":"trace[1755151232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:596; }","duration":"197.941904ms","start":"2026-05-08T05:17:29.887107Z","end":"2026-05-08T05:17:30.085049Z","steps":["trace[1755151232] 'range keys from in-memory index tree' (duration: 197.809402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.08509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.458533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:30.085135Z","caller":"traceutil/trace.go:171","msg":"trace[1808850683] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"162.525115ms","start":"2026-05-08T05:17:29.922596Z","end":"2026-05-08T05:17:30.085121Z","steps":["trace[1808850683] 'range keys from in-memory index tree' (duration: 162.398072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:30.085225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.876734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:17:30.085241Z","caller":"traceutil/trace.go:171","msg":"trace[1021086515] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:596; }","duration":"132.911614ms","start":"2026-05-08T05:17:29.952324Z","end":"2026-05-08T05:17:30.085236Z","steps":["trace[1021086515] 'range keys from in-memory index tree' (duration: 132.761092ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:41.888096Z","caller":"traceutil/trace.go:171","msg":"trace[2041441913] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"179.942914ms","start":"2026-05-08T05:17:41.708114Z","end":"2026-05-08T05:17:41.888056Z","steps":["trace[2041441913] 'read index received' (duration: 179.77152ms)","trace[2041441913] 'applied index is now lower than readState.Index' (duration: 170.264µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:17:41.888223Z","caller":"traceutil/trace.go:171","msg":"trace[654159205] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"220.938779ms","start":"2026-05-08T05:17:41.667154Z","end":"2026-05-08T05:17:41.888093Z","steps":["trace[654159205] 'process raft request' (duration: 220.758596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:41.888364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.267981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T05:17:41.888409Z","caller":"traceutil/trace.go:171","msg":"trace[1407483360] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:728; }","duration":"180.367713ms","start":"2026-05-08T05:17:41.708031Z","end":"2026-05-08T05:17:41.888398Z","steps":["trace[1407483360] 'agreement among raft nodes before linearized reading' (duration: 180.140468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:17:44.017504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.438774ms","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-05-08T05:17:44.017585Z","caller":"traceutil/trace.go:171","msg":"trace[1099514901] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"101.107203ms","start":"2026-05-08T05:17:43.91639Z","end":"2026-05-08T05:17:44.017498Z","steps":["trace[1099514901] 'process raft request' (duration: 68.949404ms)","trace[1099514901] 'compare' (duration: 31.610529ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:17:44.017663Z","caller":"traceutil/trace.go:171","msg":"trace[119168839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"124.658248ms","start":"2026-05-08T05:17:43.892978Z","end":"2026-05-08T05:17:44.017636Z","steps":["trace[119168839] 'range keys from in-memory index tree' (duration: 124.19729ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:44.398485Z","caller":"traceutil/trace.go:171","msg":"trace[1639904363] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"374.783914ms","start":"2026-05-08T05:17:44.02366Z","end":"2026-05-08T05:17:44.398444Z","steps":["trace[1639904363] 'process raft request' (duration: 320.660137ms)","trace[1639904363] 'compare' (duration: 53.910434ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:17:44.398742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:17:44.023643Z","time spent":"374.972439ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:17:47.174549Z","caller":"traceutil/trace.go:171","msg":"trace[416249097] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"186.741608ms","start":"2026-05-08T05:17:46.987775Z","end":"2026-05-08T05:17:47.174516Z","steps":["trace[416249097] 'process raft request' (duration: 186.570444ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:47.318097Z","caller":"traceutil/trace.go:171","msg":"trace[530792394] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"137.814929ms","start":"2026-05-08T05:17:47.180258Z","end":"2026-05-08T05:17:47.318073Z","steps":["trace[530792394] 'process raft request' (duration: 137.772708ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:17:47.318171Z","caller":"traceutil/trace.go:171","msg":"trace[880229774] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"138.42557ms","start":"2026-05-08T05:17:47.179675Z","end":"2026-05-08T05:17:47.3181Z","steps":["trace[880229774] 'process raft request' (duration: 87.849799ms)","trace[880229774] 'compare' (duration: 50.202294ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:18:01.256848Z","caller":"traceutil/trace.go:171","msg":"trace[1836200622] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"123.264553ms","start":"2026-05-08T05:18:01.133559Z","end":"2026-05-08T05:18:01.256824Z","steps":["trace[1836200622] 'process raft request' (duration: 123.063889ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:01.294729Z","caller":"traceutil/trace.go:171","msg":"trace[2074462965] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"160.825837ms","start":"2026-05-08T05:18:01.133882Z","end":"2026-05-08T05:18:01.294707Z","steps":["trace[2074462965] 'process raft request' (duration: 160.646463ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:01.294882Z","caller":"traceutil/trace.go:171","msg":"trace[1696596492] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"159.501951ms","start":"2026-05-08T05:18:01.135345Z","end":"2026-05-08T05:18:01.294847Z","steps":["trace[1696596492] 'process raft request' (duration: 159.308608ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:07.110022Z","caller":"traceutil/trace.go:171","msg":"trace[1533532572] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"200.559441ms","start":"2026-05-08T05:18:06.909438Z","end":"2026-05-08T05:18:07.109998Z","steps":["trace[1533532572] 'process raft request' (duration: 200.48132ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:07.110046Z","caller":"traceutil/trace.go:171","msg":"trace[1624993614] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"200.629302ms","start":"2026-05-08T05:18:06.909384Z","end":"2026-05-08T05:18:07.110013Z","steps":["trace[1624993614] 'process raft request' (duration: 200.362968ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:07.110665Z","caller":"traceutil/trace.go:171","msg":"trace[1003927567] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:953; }","duration":"161.963188ms","start":"2026-05-08T05:18:06.948633Z","end":"2026-05-08T05:18:07.110596Z","steps":["trace[1003927567] 'read index received' (duration: 93.412915ms)","trace[1003927567] 'applied index is now lower than readState.Index' (duration: 68.546043ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:18:07.110766Z","caller":"traceutil/trace.go:171","msg":"trace[1941941938] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"201.279115ms","start":"2026-05-08T05:18:06.909468Z","end":"2026-05-08T05:18:07.110748Z","steps":["trace[1941941938] 'process raft request' (duration: 200.492729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:07.110925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.247443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:18:07.111091Z","caller":"traceutil/trace.go:171","msg":"trace[110494911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"162.434356ms","start":"2026-05-08T05:18:06.948607Z","end":"2026-05-08T05:18:07.111041Z","steps":["trace[110494911] 'agreement among raft nodes before linearized reading' (duration: 162.161761ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:12.861674Z","caller":"traceutil/trace.go:171","msg":"trace[1993621212] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"115.432214ms","start":"2026-05-08T05:18:12.746216Z","end":"2026-05-08T05:18:12.861649Z","steps":["trace[1993621212] 'process raft request' (duration: 103.465167ms)","trace[1993621212] 'compare' (duration: 11.820834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:18:12.861695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.967334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T05:18:12.861811Z","caller":"traceutil/trace.go:171","msg":"trace[13883522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:937; }","duration":"116.129277ms","start":"2026-05-08T05:18:12.745647Z","end":"2026-05-08T05:18:12.861776Z","steps":["trace[13883522] 'range keys from in-memory index tree' (duration: 115.79363ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:12.944042Z","caller":"traceutil/trace.go:171","msg":"trace[722716117] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"142.538679ms","start":"2026-05-08T05:18:12.801479Z","end":"2026-05-08T05:18:12.944018Z","steps":["trace[722716117] 'process raft request' (duration: 142.335195ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:16.167075Z","caller":"traceutil/trace.go:171","msg":"trace[1446691405] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"153.489317ms","start":"2026-05-08T05:18:16.013568Z","end":"2026-05-08T05:18:16.167058Z","steps":["trace[1446691405] 'process raft request' (duration: 153.387145ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:16.468216Z","caller":"traceutil/trace.go:171","msg":"trace[1547011993] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"290.5023ms","start":"2026-05-08T05:18:16.177681Z","end":"2026-05-08T05:18:16.468183Z","steps":["trace[1547011993] 'process raft request' (duration: 284.67437ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:16.892556Z","caller":"traceutil/trace.go:171","msg":"trace[857757401] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"109.337437ms","start":"2026-05-08T05:18:16.782995Z","end":"2026-05-08T05:18:16.892333Z","steps":["trace[857757401] 'process raft request' (duration: 109.102993ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:17.379103Z","caller":"traceutil/trace.go:171","msg":"trace[2107998683] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"108.982851ms","start":"2026-05-08T05:18:17.270093Z","end":"2026-05-08T05:18:17.379076Z","steps":["trace[2107998683] 'process raft request' (duration: 94.937924ms)","trace[2107998683] 'compare' (duration: 13.927615ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:18:19.106816Z","caller":"traceutil/trace.go:171","msg":"trace[461290228] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"206.836171ms","start":"2026-05-08T05:18:18.899962Z","end":"2026-05-08T05:18:19.106799Z","steps":["trace[461290228] 'process raft request' (duration: 206.733279ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:19.114675Z","caller":"traceutil/trace.go:171","msg":"trace[115940307] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"167.851889ms","start":"2026-05-08T05:18:18.946813Z","end":"2026-05-08T05:18:19.114665Z","steps":["trace[115940307] 'read index received' (duration: 159.93845ms)","trace[115940307] 'applied index is now lower than readState.Index' (duration: 7.912899ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:18:19.114733Z","caller":"traceutil/trace.go:171","msg":"trace[1741589642] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"214.056807ms","start":"2026-05-08T05:18:18.90067Z","end":"2026-05-08T05:18:19.114727Z","steps":["trace[1741589642] 'process raft request' (duration: 213.939455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:19.114957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.139385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:18:19.114985Z","caller":"traceutil/trace.go:171","msg":"trace[580277138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"168.200847ms","start":"2026-05-08T05:18:18.946774Z","end":"2026-05-08T05:18:19.114974Z","steps":["trace[580277138] 'agreement among raft nodes before linearized reading' (duration: 168.150965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:19.114989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.527908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T05:18:19.115081Z","caller":"traceutil/trace.go:171","msg":"trace[1156122745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"112.659961ms","start":"2026-05-08T05:18:19.002383Z","end":"2026-05-08T05:18:19.115043Z","steps":["trace[1156122745] 'agreement among raft nodes before linearized reading' (duration: 112.458166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:20.426891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042881417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-08T05:18:20.426958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.099718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:18:20.42699Z","caller":"traceutil/trace.go:171","msg":"trace[840297294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"1.043024101s","start":"2026-05-08T05:18:19.383945Z","end":"2026-05-08T05:18:20.426969Z","steps":["trace[840297294] 'range keys from in-memory index tree' (duration: 1.042659763s)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:20.427032Z","caller":"traceutil/trace.go:171","msg":"trace[631104275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"476.216771ms","start":"2026-05-08T05:18:19.950796Z","end":"2026-05-08T05:18:20.427013Z","steps":["trace[631104275] 'range keys from in-memory index tree' (duration: 475.973105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:20.427044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:19.383927Z","time spent":"1.043106942s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T05:18:20.427074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:19.95078Z","time spent":"476.286382ms","remote":"127.0.0.1:53956","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-05-08T05:18:20.427242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186012498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-08T05:18:20.427314Z","caller":"traceutil/trace.go:171","msg":"trace[1093447501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"1.18612414s","start":"2026-05-08T05:18:19.24117Z","end":"2026-05-08T05:18:20.427294Z","steps":["trace[1093447501] 'range keys from in-memory index tree' (duration: 1.185810915s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:20.427353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:19.24115Z","time spent":"1.186194291s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T05:18:20.427388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15765446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T05:18:20.427429Z","caller":"traceutil/trace.go:171","msg":"trace[2095754536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"1.15772808s","start":"2026-05-08T05:18:19.269688Z","end":"2026-05-08T05:18:20.427416Z","steps":["trace[2095754536] 'range keys from in-memory index tree' (duration: 1.157536317s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:20.427464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:19.269667Z","time spent":"1.157790142s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T05:18:21.349044Z","caller":"traceutil/trace.go:171","msg":"trace[573591464] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"917.986025ms","start":"2026-05-08T05:18:20.431034Z","end":"2026-05-08T05:18:21.34902Z","steps":["trace[573591464] 'process raft request' (duration: 917.777681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:21.349204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:20.431014Z","time spent":"918.103957ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:18:21.827278Z","caller":"traceutil/trace.go:171","msg":"trace[1652189010] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.395875016s","start":"2026-05-08T05:18:20.431376Z","end":"2026-05-08T05:18:21.827251Z","steps":["trace[1652189010] 'process raft request' (duration: 1.395708293s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:21.827452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:20.431356Z","time spent":"1.396019889s","remote":"127.0.0.1:54164","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-05-08T05:18:21.82746Z","caller":"traceutil/trace.go:171","msg":"trace[354090668] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.39553784s","start":"2026-05-08T05:18:20.431904Z","end":"2026-05-08T05:18:21.827442Z","steps":["trace[354090668] 'process raft request' (duration: 1.395307086s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:21.827564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:20.431893Z","time spent":"1.395613871s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:18:21.827657Z","caller":"traceutil/trace.go:171","msg":"trace[359552619] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"716.800842ms","start":"2026-05-08T05:18:21.110843Z","end":"2026-05-08T05:18:21.827644Z","steps":["trace[359552619] 'process raft request' (duration: 716.551207ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:21.82762Z","caller":"traceutil/trace.go:171","msg":"trace[932911483] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:999; }","duration":"710.097954ms","start":"2026-05-08T05:18:21.117484Z","end":"2026-05-08T05:18:21.827582Z","steps":["trace[932911483] 'read index received' (duration: 231.340146ms)","trace[932911483] 'applied index is now lower than readState.Index' (duration: 478.756438ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:18:21.827717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:21.110818Z","time spent":"716.865312ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:18:21.827896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.88019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T05:18:21.827924Z","caller":"traceutil/trace.go:171","msg":"trace[713502914] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:967; }","duration":"117.938641ms","start":"2026-05-08T05:18:21.709977Z","end":"2026-05-08T05:18:21.827915Z","steps":["trace[713502914] 'agreement among raft nodes before linearized reading' (duration: 117.851489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:21.827913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.40373ms","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-05-08T05:18:21.827962Z","caller":"traceutil/trace.go:171","msg":"trace[2046387693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"710.496891ms","start":"2026-05-08T05:18:21.11745Z","end":"2026-05-08T05:18:21.827947Z","steps":["trace[2046387693] 'agreement among raft nodes before linearized reading' (duration: 710.293057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:21.827997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:21.117434Z","time spent":"710.554392ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T05:18:21.828014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.309444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-08T05:18:21.828106Z","caller":"traceutil/trace.go:171","msg":"trace[847099019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"684.423557ms","start":"2026-05-08T05:18:21.143658Z","end":"2026-05-08T05:18:21.828081Z","steps":["trace[847099019] 'agreement among raft nodes before linearized reading' (duration: 684.164962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:21.828149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:21.143651Z","time spent":"684.489598ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T05:18:32.068856Z","caller":"traceutil/trace.go:171","msg":"trace[1379408655] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"163.079389ms","start":"2026-05-08T05:18:31.905749Z","end":"2026-05-08T05:18:32.068828Z","steps":["trace[1379408655] 'read index received' (duration: 116.543405ms)","trace[1379408655] 'applied index is now lower than readState.Index' (duration: 46.534604ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:18:32.069144Z","caller":"traceutil/trace.go:171","msg":"trace[1476518626] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"182.756633ms","start":"2026-05-08T05:18:31.88637Z","end":"2026-05-08T05:18:32.069126Z","steps":["trace[1476518626] 'process raft request' (duration: 135.963013ms)","trace[1476518626] 'compare' (duration: 46.368541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:18:32.069294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.40733ms","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-05-08T05:18:32.069349Z","caller":"traceutil/trace.go:171","msg":"trace[1614572269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"158.513372ms","start":"2026-05-08T05:18:31.910822Z","end":"2026-05-08T05:18:32.069335Z","steps":["trace[1614572269] 'agreement among raft nodes before linearized reading' (duration: 158.356439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:32.069322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.547058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T05:18:32.069457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.35487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T05:18:32.069452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.984393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T05:18:32.06955Z","caller":"traceutil/trace.go:171","msg":"trace[1932358339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"128.531243ms","start":"2026-05-08T05:18:31.941Z","end":"2026-05-08T05:18:32.069507Z","steps":["trace[1932358339] 'agreement among raft nodes before linearized reading' (duration: 128.296438ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:32.069573Z","caller":"traceutil/trace.go:171","msg":"trace[1264826349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"128.175406ms","start":"2026-05-08T05:18:31.941382Z","end":"2026-05-08T05:18:32.069557Z","steps":["trace[1264826349] 'agreement among raft nodes before linearized reading' (duration: 127.903421ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:32.069468Z","caller":"traceutil/trace.go:171","msg":"trace[1739965359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"163.727652ms","start":"2026-05-08T05:18:31.905721Z","end":"2026-05-08T05:18:32.069449Z","steps":["trace[1739965359] 'agreement among raft nodes before linearized reading' (duration: 163.446037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:32.070236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.730215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:18:32.070343Z","caller":"traceutil/trace.go:171","msg":"trace[794398558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"120.892427ms","start":"2026-05-08T05:18:31.949428Z","end":"2026-05-08T05:18:32.07032Z","steps":["trace[794398558] 'agreement among raft nodes before linearized reading' (duration: 120.339297ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:32.356136Z","caller":"traceutil/trace.go:171","msg":"trace[1379871846] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"281.968499ms","start":"2026-05-08T05:18:32.074144Z","end":"2026-05-08T05:18:32.356112Z","steps":["trace[1379871846] 'process raft request' (duration: 281.716243ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:32.35645Z","caller":"traceutil/trace.go:171","msg":"trace[1480417283] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"281.771654ms","start":"2026-05-08T05:18:32.074643Z","end":"2026-05-08T05:18:32.356415Z","steps":["trace[1480417283] 'process raft request' (duration: 281.405318ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:32.356497Z","caller":"traceutil/trace.go:171","msg":"trace[796733892] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"281.801285ms","start":"2026-05-08T05:18:32.074651Z","end":"2026-05-08T05:18:32.356453Z","steps":["trace[796733892] 'process raft request' (duration: 281.622162ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:36.478522Z","caller":"traceutil/trace.go:171","msg":"trace[223614834] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"100.860337ms","start":"2026-05-08T05:18:36.377637Z","end":"2026-05-08T05:18:36.478498Z","steps":["trace[223614834] 'process raft request' (duration: 65.652078ms)","trace[223614834] 'compare' (duration: 34.964694ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:18:36.478748Z","caller":"traceutil/trace.go:171","msg":"trace[714644588] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"100.140853ms","start":"2026-05-08T05:18:36.378593Z","end":"2026-05-08T05:18:36.478733Z","steps":["trace[714644588] 'process raft request' (duration: 99.840658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:44.757467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495897792264406,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T05:18:44.859106Z","caller":"traceutil/trace.go:171","msg":"trace[1573706773] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"602.244074ms","start":"2026-05-08T05:18:44.256838Z","end":"2026-05-08T05:18:44.859082Z","steps":["trace[1573706773] 'read index received' (duration: 602.093031ms)","trace[1573706773] 'applied index is now lower than readState.Index' (duration: 149.783µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:18:44.859202Z","caller":"traceutil/trace.go:171","msg":"trace[1203814861] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"677.267439ms","start":"2026-05-08T05:18:44.181866Z","end":"2026-05-08T05:18:44.859134Z","steps":["trace[1203814861] 'process raft request' (duration: 677.076296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:44.859375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:44.181843Z","time spent":"677.441994ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:18:44.859401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.701525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T05:18:44.859387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.52443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T05:18:44.859454Z","caller":"traceutil/trace.go:171","msg":"trace[2115107727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"420.808968ms","start":"2026-05-08T05:18:44.438626Z","end":"2026-05-08T05:18:44.859435Z","steps":["trace[2115107727] 'agreement among raft nodes before linearized reading' (duration: 420.634884ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:44.859471Z","caller":"traceutil/trace.go:171","msg":"trace[1421916904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"602.661043ms","start":"2026-05-08T05:18:44.256792Z","end":"2026-05-08T05:18:44.859453Z","steps":["trace[1421916904] 'agreement among raft nodes before linearized reading' (duration: 602.417207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:44.859503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:44.438604Z","time spent":"420.886519ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T05:18:44.859517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:44.256776Z","time spent":"602.733494ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T05:18:44.859568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.745241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-08T05:18:44.85961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.721654ms","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-05-08T05:18:44.859633Z","caller":"traceutil/trace.go:171","msg":"trace[59843453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"345.813192ms","start":"2026-05-08T05:18:44.513805Z","end":"2026-05-08T05:18:44.859618Z","steps":["trace[59843453] 'agreement among raft nodes before linearized reading' (duration: 345.651619ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:44.859663Z","caller":"traceutil/trace.go:171","msg":"trace[1782585748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"341.805386ms","start":"2026-05-08T05:18:44.517845Z","end":"2026-05-08T05:18:44.85965Z","steps":["trace[1782585748] 'agreement among raft nodes before linearized reading' (duration: 341.585782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:44.859677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:44.513729Z","time spent":"345.938394ms","remote":"127.0.0.1:54164","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-05-08T05:18:44.859702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:44.517831Z","time spent":"341.863576ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T05:18:45.143704Z","caller":"traceutil/trace.go:171","msg":"trace[530758304] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"279.37887ms","start":"2026-05-08T05:18:44.864295Z","end":"2026-05-08T05:18:45.143674Z","steps":["trace[530758304] 'process raft request' (duration: 238.707927ms)","trace[530758304] 'compare' (duration: 40.379697ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:18:45.184615Z","caller":"traceutil/trace.go:171","msg":"trace[373271074] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"320.339827ms","start":"2026-05-08T05:18:44.864252Z","end":"2026-05-08T05:18:45.184592Z","steps":["trace[373271074] 'process raft request' (duration: 320.196825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:45.184753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:44.864225Z","time spent":"320.45395ms","remote":"127.0.0.1:54164","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-05-08T05:18:45.184899Z","caller":"traceutil/trace.go:171","msg":"trace[342655328] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"320.4861ms","start":"2026-05-08T05:18:44.864386Z","end":"2026-05-08T05:18:45.184872Z","steps":["trace[342655328] 'process raft request' (duration: 320.168825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:45.18508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:44.864364Z","time spent":"320.655763ms","remote":"127.0.0.1:54164","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-05-08T05:18:45.185148Z","caller":"traceutil/trace.go:171","msg":"trace[1941093146] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"320.582482ms","start":"2026-05-08T05:18:44.8645Z","end":"2026-05-08T05:18:45.185082Z","steps":["trace[1941093146] 'process raft request' (duration: 320.284007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:45.185911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:44.864482Z","time spent":"321.314996ms","remote":"127.0.0.1:54164","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-05-08T05:18:47.021972Z","caller":"traceutil/trace.go:171","msg":"trace[660247323] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"156.953683ms","start":"2026-05-08T05:18:46.864995Z","end":"2026-05-08T05:18:47.021948Z","steps":["trace[660247323] 'process raft request' (duration: 156.81185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:47.212947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.487989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-08T05:18:47.374366Z","caller":"traceutil/trace.go:171","msg":"trace[1652959592] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"156.707708ms","start":"2026-05-08T05:18:47.217633Z","end":"2026-05-08T05:18:47.374341Z","steps":["trace[1652959592] 'process raft request' (duration: 156.532865ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:47.418326Z","caller":"traceutil/trace.go:171","msg":"trace[1764784964] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"200.645423ms","start":"2026-05-08T05:18:47.217663Z","end":"2026-05-08T05:18:47.418308Z","steps":["trace[1764784964] 'process raft request' (duration: 200.51304ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:47.418616Z","caller":"traceutil/trace.go:171","msg":"trace[587421299] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"200.822216ms","start":"2026-05-08T05:18:47.217765Z","end":"2026-05-08T05:18:47.418588Z","steps":["trace[587421299] 'process raft request' (duration: 200.50418ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:47.418673Z","caller":"traceutil/trace.go:171","msg":"trace[1803284356] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"200.221115ms","start":"2026-05-08T05:18:47.218433Z","end":"2026-05-08T05:18:47.418654Z","steps":["trace[1803284356] 'process raft request' (duration: 200.046712ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:47.731292Z","caller":"traceutil/trace.go:171","msg":"trace[2052795783] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"154.854903ms","start":"2026-05-08T05:18:47.576414Z","end":"2026-05-08T05:18:47.731269Z","steps":["trace[2052795783] 'process raft request' (duration: 154.714001ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:18:49.336962Z","caller":"traceutil/trace.go:171","msg":"trace[376308072] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"309.630815ms","start":"2026-05-08T05:18:49.027306Z","end":"2026-05-08T05:18:49.336937Z","steps":["trace[376308072] 'process raft request' (duration: 309.458911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:18:49.337156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:18:49.027284Z","time spent":"309.763537ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:18:49.498995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.070566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T05:18:49.499079Z","caller":"traceutil/trace.go:171","msg":"trace[251391418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1052; }","duration":"119.156168ms","start":"2026-05-08T05:18:49.379896Z","end":"2026-05-08T05:18:49.499052Z","steps":["trace[251391418] 'range keys from in-memory index tree' (duration: 118.794452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:02.131988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495897792264640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T05:19:02.632702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495897792264640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T05:19:03.022194Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.392027398s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T05:19:03.022516Z","caller":"traceutil/trace.go:171","msg":"trace[1410054029] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"1.391202932s","start":"2026-05-08T05:19:01.631297Z","end":"2026-05-08T05:19:03.0225Z","steps":["trace[1410054029] 'read index received' (duration: 1.390995329s)","trace[1410054029] 'applied index is now lower than readState.Index' (duration: 206.953µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:19:03.022576Z","caller":"traceutil/trace.go:171","msg":"trace[2041193393] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"1.392480856s","start":"2026-05-08T05:19:01.630087Z","end":"2026-05-08T05:19:03.022568Z","steps":["trace[2041193393] 'process raft request' (duration: 1.392255621s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.022692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07329233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:19:03.022721Z","caller":"traceutil/trace.go:171","msg":"trace[2003343189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1143; }","duration":"1.07332288s","start":"2026-05-08T05:19:01.949388Z","end":"2026-05-08T05:19:03.022711Z","steps":["trace[2003343189] 'agreement among raft nodes before linearized reading' (duration: 1.073271959s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.022738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:01.949342Z","time spent":"1.073392432s","remote":"127.0.0.1:53956","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-05-08T05:19:03.022746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310955724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T05:19:03.022777Z","caller":"traceutil/trace.go:171","msg":"trace[1169081366] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1143; }","duration":"1.310989954s","start":"2026-05-08T05:19:01.711778Z","end":"2026-05-08T05:19:03.022767Z","steps":["trace[1169081366] 'agreement among raft nodes before linearized reading' (duration: 1.310865812s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.022797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:01.711716Z","time spent":"1.311076536s","remote":"127.0.0.1:53972","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.210\" "} {"level":"warn","ts":"2026-05-08T05:19:03.022799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383875451s","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-05-08T05:19:03.022854Z","caller":"traceutil/trace.go:171","msg":"trace[1176421378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1143; }","duration":"1.383966034s","start":"2026-05-08T05:19:01.638874Z","end":"2026-05-08T05:19:03.02284Z","steps":["trace[1176421378] 'agreement among raft nodes before linearized reading' (duration: 1.38379677s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.02288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:01.638861Z","time spent":"1.384014704s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T05:19:03.023254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:01.630064Z","time spent":"1.392530386s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:19:03.023284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384602826s","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-05-08T05:19:03.023308Z","caller":"traceutil/trace.go:171","msg":"trace[1609186989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1143; }","duration":"1.384646087s","start":"2026-05-08T05:19:01.638654Z","end":"2026-05-08T05:19:03.0233Z","steps":["trace[1609186989] 'agreement among raft nodes before linearized reading' (duration: 1.383932653s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.023325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:01.638643Z","time spent":"1.384678637s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T05:19:03.023338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392044209s","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-05-08T05:19:03.023382Z","caller":"traceutil/trace.go:171","msg":"trace[1384736998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1143; }","duration":"1.39213792s","start":"2026-05-08T05:19:01.631238Z","end":"2026-05-08T05:19:03.023376Z","steps":["trace[1384736998] 'agreement among raft nodes before linearized reading' (duration: 1.391375765s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.023396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:01.631218Z","time spent":"1.392174961s","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T05:19:03.02348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200713746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T05:19:03.023496Z","caller":"traceutil/trace.go:171","msg":"trace[1618284381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1143; }","duration":"1.200758708s","start":"2026-05-08T05:19:01.822732Z","end":"2026-05-08T05:19:03.023491Z","steps":["trace[1618284381] 'agreement among raft nodes before linearized reading' (duration: 1.200710447s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.02351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:01.822718Z","time spent":"1.200789268s","remote":"127.0.0.1:54164","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-05-08T05:19:03.655605Z","caller":"traceutil/trace.go:171","msg":"trace[1059819425] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1189; }","duration":"631.554372ms","start":"2026-05-08T05:19:03.024029Z","end":"2026-05-08T05:19:03.655583Z","steps":["trace[1059819425] 'read index received' (duration: 60.192µs)","trace[1059819425] 'applied index is now lower than readState.Index' (duration: 631.49269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:19:03.655636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:03.023949Z","time spent":"631.681244ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-08T05:19:03.655728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.683934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:19:03.655762Z","caller":"traceutil/trace.go:171","msg":"trace[104037738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1143; }","duration":"631.740875ms","start":"2026-05-08T05:19:03.024007Z","end":"2026-05-08T05:19:03.655747Z","steps":["trace[104037738] 'agreement among raft nodes before linearized reading' (duration: 631.675174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.65579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:03.023995Z","time spent":"631.790056ms","remote":"127.0.0.1:53952","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-05-08T05:19:03.664219Z","caller":"traceutil/trace.go:171","msg":"trace[775784292] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"635.233981ms","start":"2026-05-08T05:19:03.028974Z","end":"2026-05-08T05:19:03.664208Z","steps":["trace[775784292] 'process raft request' (duration: 635.111269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.664293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:03.028964Z","time spent":"635.285082ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:19:03.66447Z","caller":"traceutil/trace.go:171","msg":"trace[1807007653] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"635.491867ms","start":"2026-05-08T05:19:03.028971Z","end":"2026-05-08T05:19:03.664463Z","steps":["trace[1807007653] 'process raft request' (duration: 635.211501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.664513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:03.028959Z","time spent":"635.534247ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:19:03.664535Z","caller":"traceutil/trace.go:171","msg":"trace[129354877] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"635.479107ms","start":"2026-05-08T05:19:03.029031Z","end":"2026-05-08T05:19:03.66451Z","steps":["trace[129354877] 'process raft request' (duration: 635.316583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.664577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.920285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:19:03.664639Z","caller":"traceutil/trace.go:171","msg":"trace[1264739037] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1148; }","duration":"138.023008ms","start":"2026-05-08T05:19:03.526603Z","end":"2026-05-08T05:19:03.664626Z","steps":["trace[1264739037] 'agreement among raft nodes before linearized reading' (duration: 137.895115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.664688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:03.029026Z","time spent":"635.573788ms","remote":"127.0.0.1:54164","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-05-08T05:19:03.665022Z","caller":"traceutil/trace.go:171","msg":"trace[18258759] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"635.036399ms","start":"2026-05-08T05:19:03.029961Z","end":"2026-05-08T05:19:03.664998Z","steps":["trace[18258759] 'process raft request' (duration: 634.457448ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:19:03.665074Z","caller":"traceutil/trace.go:171","msg":"trace[1710605776] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"236.870589ms","start":"2026-05-08T05:19:03.428144Z","end":"2026-05-08T05:19:03.665014Z","steps":["trace[1710605776] 'process raft request' (duration: 236.299458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:03.665206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:03.02994Z","time spent":"635.212132ms","remote":"127.0.0.1:54164","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-05-08T05:19:17.89882Z","caller":"traceutil/trace.go:171","msg":"trace[1815445637] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"106.340524ms","start":"2026-05-08T05:19:17.792451Z","end":"2026-05-08T05:19:17.898792Z","steps":["trace[1815445637] 'process raft request' (duration: 105.61955ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:19:18.889469Z","caller":"traceutil/trace.go:171","msg":"trace[1210290627] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"169.827643ms","start":"2026-05-08T05:19:18.719612Z","end":"2026-05-08T05:19:18.88944Z","steps":["trace[1210290627] 'read index received' (duration: 169.69466ms)","trace[1210290627] 'applied index is now lower than readState.Index' (duration: 131.363µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:19:18.889573Z","caller":"traceutil/trace.go:171","msg":"trace[236694658] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"223.062597ms","start":"2026-05-08T05:19:18.666423Z","end":"2026-05-08T05:19:18.889486Z","steps":["trace[236694658] 'process raft request' (duration: 222.870892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:18.889654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.025177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:19:18.88969Z","caller":"traceutil/trace.go:171","msg":"trace[1908159596] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1217; }","duration":"170.131759ms","start":"2026-05-08T05:19:18.719549Z","end":"2026-05-08T05:19:18.88968Z","steps":["trace[1908159596] 'agreement among raft nodes before linearized reading' (duration: 170.019017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:19.703134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.216689ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-08T05:19:19.70326Z","caller":"traceutil/trace.go:171","msg":"trace[426049724] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"601.359087ms","start":"2026-05-08T05:19:19.10188Z","end":"2026-05-08T05:19:19.703239Z","steps":["trace[426049724] 'read index received' (duration: 118.970784ms)","trace[426049724] 'applied index is now lower than readState.Index' (duration: 482.386553ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:19:19.703449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.562601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T05:19:19.703478Z","caller":"traceutil/trace.go:171","msg":"trace[1988569959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"601.641252ms","start":"2026-05-08T05:19:19.101826Z","end":"2026-05-08T05:19:19.703468Z","steps":["trace[1988569959] 'agreement among raft nodes before linearized reading' (duration: 601.465539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:19.703515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:19.101804Z","time spent":"601.702594ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T05:19:22.381656Z","caller":"traceutil/trace.go:171","msg":"trace[162207277] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"440.662858ms","start":"2026-05-08T05:19:21.940967Z","end":"2026-05-08T05:19:22.38163Z","steps":["trace[162207277] 'read index received' (duration: 440.361491ms)","trace[162207277] 'applied index is now lower than readState.Index' (duration: 299.987µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:19:22.381786Z","caller":"traceutil/trace.go:171","msg":"trace[1323535895] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"447.063051ms","start":"2026-05-08T05:19:21.934711Z","end":"2026-05-08T05:19:22.381774Z","steps":["trace[1323535895] 'process raft request' (duration: 446.679593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:22.381905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:21.934669Z","time spent":"447.153152ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:19:22.382215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.129907ms","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-05-08T05:19:22.382325Z","caller":"traceutil/trace.go:171","msg":"trace[1642986462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"441.275279ms","start":"2026-05-08T05:19:21.941024Z","end":"2026-05-08T05:19:22.382299Z","steps":["trace[1642986462] 'agreement among raft nodes before linearized reading' (duration: 440.995744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:22.382372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:21.941018Z","time spent":"441.34525ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T05:19:22.382413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.440992ms","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-05-08T05:19:22.382457Z","caller":"traceutil/trace.go:171","msg":"trace[1112434874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"441.501794ms","start":"2026-05-08T05:19:21.940941Z","end":"2026-05-08T05:19:22.382442Z","steps":["trace[1112434874] 'agreement among raft nodes before linearized reading' (duration: 441.340271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:22.382488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:21.94093Z","time spent":"441.552355ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T05:19:22.38258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.90987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:19:22.382603Z","caller":"traceutil/trace.go:171","msg":"trace[994360632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"432.952061ms","start":"2026-05-08T05:19:21.949643Z","end":"2026-05-08T05:19:22.382595Z","steps":["trace[994360632] 'agreement among raft nodes before linearized reading' (duration: 432.91603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:22.382628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:21.949624Z","time spent":"432.997422ms","remote":"127.0.0.1:53956","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-05-08T05:19:26.508722Z","caller":"traceutil/trace.go:171","msg":"trace[1295834712] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"107.539017ms","start":"2026-05-08T05:19:26.401153Z","end":"2026-05-08T05:19:26.508692Z","steps":["trace[1295834712] 'process raft request' (duration: 107.270292ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:19:28.065082Z","caller":"traceutil/trace.go:171","msg":"trace[1157196726] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"116.442766ms","start":"2026-05-08T05:19:27.948616Z","end":"2026-05-08T05:19:28.065059Z","steps":["trace[1157196726] 'read index received' (duration: 116.189041ms)","trace[1157196726] 'applied index is now lower than readState.Index' (duration: 252.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:19:28.065126Z","caller":"traceutil/trace.go:171","msg":"trace[2111835723] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"122.807178ms","start":"2026-05-08T05:19:27.942293Z","end":"2026-05-08T05:19:28.065101Z","steps":["trace[2111835723] 'process raft request' (duration: 122.621034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:28.065201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.562678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:19:28.065235Z","caller":"traceutil/trace.go:171","msg":"trace[1162208220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"116.63144ms","start":"2026-05-08T05:19:27.948594Z","end":"2026-05-08T05:19:28.065226Z","steps":["trace[1162208220] 'agreement among raft nodes before linearized reading' (duration: 116.559479ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:19:34.066027Z","caller":"traceutil/trace.go:171","msg":"trace[1727935383] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"169.171411ms","start":"2026-05-08T05:19:33.896814Z","end":"2026-05-08T05:19:34.065985Z","steps":["trace[1727935383] 'read index received' (duration: 169.027378ms)","trace[1727935383] 'applied index is now lower than readState.Index' (duration: 142.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:19:34.066116Z","caller":"traceutil/trace.go:171","msg":"trace[756226185] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"180.406805ms","start":"2026-05-08T05:19:33.885634Z","end":"2026-05-08T05:19:34.066041Z","steps":["trace[756226185] 'process raft request' (duration: 180.103429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:34.066377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.016727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T05:19:34.066402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.140258ms","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-05-08T05:19:34.066403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.547697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T05:19:34.066462Z","caller":"traceutil/trace.go:171","msg":"trace[816098104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"117.161251ms","start":"2026-05-08T05:19:33.949279Z","end":"2026-05-08T05:19:34.06644Z","steps":["trace[816098104] 'agreement among raft nodes before linearized reading' (duration: 117.035249ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:19:34.066474Z","caller":"traceutil/trace.go:171","msg":"trace[1494531857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"140.257199ms","start":"2026-05-08T05:19:33.926195Z","end":"2026-05-08T05:19:34.066452Z","steps":["trace[1494531857] 'agreement among raft nodes before linearized reading' (duration: 140.082906ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:19:34.066477Z","caller":"traceutil/trace.go:171","msg":"trace[572577499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1312; }","duration":"169.70324ms","start":"2026-05-08T05:19:33.896757Z","end":"2026-05-08T05:19:34.06646Z","steps":["trace[572577499] 'agreement among raft nodes before linearized reading' (duration: 169.408375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:34.738835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.727261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T05:19:34.739115Z","caller":"traceutil/trace.go:171","msg":"trace[511795696] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"665.877235ms","start":"2026-05-08T05:19:34.073219Z","end":"2026-05-08T05:19:34.739096Z","steps":["trace[511795696] 'process raft request' (duration: 665.810744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:34.739225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:34.073192Z","time spent":"665.990408ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:19:34.739355Z","caller":"traceutil/trace.go:171","msg":"trace[365785148] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"666.099599ms","start":"2026-05-08T05:19:34.073219Z","end":"2026-05-08T05:19:34.739318Z","steps":["trace[365785148] 'process raft request' (duration: 455.813537ms)","trace[365785148] 'compare' (duration: 209.625229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:19:34.739498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:34.073191Z","time spent":"666.244463ms","remote":"127.0.0.1:54164","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-05-08T05:19:34.878097Z","caller":"traceutil/trace.go:171","msg":"trace[617505398] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1367; }","duration":"321.402008ms","start":"2026-05-08T05:19:34.556653Z","end":"2026-05-08T05:19:34.878055Z","steps":["trace[617505398] 'read index received' (duration: 321.172744ms)","trace[617505398] 'applied index is now lower than readState.Index' (duration: 227.854µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:19:34.878143Z","caller":"traceutil/trace.go:171","msg":"trace[1593756095] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"804.84404ms","start":"2026-05-08T05:19:34.073267Z","end":"2026-05-08T05:19:34.878111Z","steps":["trace[1593756095] 'process raft request' (duration: 804.428593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:34.878466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.781685ms","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-05-08T05:19:34.878542Z","caller":"traceutil/trace.go:171","msg":"trace[1688867480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"321.927158ms","start":"2026-05-08T05:19:34.556595Z","end":"2026-05-08T05:19:34.878522Z","steps":["trace[1688867480] 'agreement among raft nodes before linearized reading' (duration: 321.685903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:34.878532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:34.073245Z","time spent":"805.190837ms","remote":"127.0.0.1:54164","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-05-08T05:19:34.878596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:19:34.556572Z","time spent":"322.01051ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T05:19:34.879889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.962278ms","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-05-08T05:19:34.87994Z","caller":"traceutil/trace.go:171","msg":"trace[650631713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1316; }","duration":"291.03748ms","start":"2026-05-08T05:19:34.588891Z","end":"2026-05-08T05:19:34.879928Z","steps":["trace[650631713] 'agreement among raft nodes before linearized reading' (duration: 290.860407ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:19:34.880362Z","caller":"traceutil/trace.go:171","msg":"trace[996900753] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"102.571932ms","start":"2026-05-08T05:19:34.777775Z","end":"2026-05-08T05:19:34.880347Z","steps":["trace[996900753] 'process raft request' (duration: 101.858409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:19:34.880513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.29663ms","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-05-08T05:19:34.880621Z","caller":"traceutil/trace.go:171","msg":"trace[776226708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1316; }","duration":"286.494014ms","start":"2026-05-08T05:19:34.594104Z","end":"2026-05-08T05:19:34.880598Z","steps":["trace[776226708] 'agreement among raft nodes before linearized reading' (duration: 286.038164ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:19:51.417382Z","caller":"traceutil/trace.go:171","msg":"trace[1112432564] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"138.374213ms","start":"2026-05-08T05:19:51.278981Z","end":"2026-05-08T05:19:51.417355Z","steps":["trace[1112432564] 'process raft request' (duration: 138.178759ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:20:02.341958Z","caller":"traceutil/trace.go:171","msg":"trace[1998979626] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"146.438051ms","start":"2026-05-08T05:20:02.19548Z","end":"2026-05-08T05:20:02.341918Z","steps":["trace[1998979626] 'process raft request' (duration: 98.874004ms)","trace[1998979626] 'compare' (duration: 47.327772ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:20:55.973469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.601442ms","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-05-08T05:20:55.9736Z","caller":"traceutil/trace.go:171","msg":"trace[34382484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1687; }","duration":"106.746694ms","start":"2026-05-08T05:20:55.866835Z","end":"2026-05-08T05:20:55.973581Z","steps":["trace[34382484] 'range keys from in-memory index tree' (duration: 106.405467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:20:55.974054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.899859ms","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-05-08T05:20:55.974287Z","caller":"traceutil/trace.go:171","msg":"trace[2077048877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1687; }","duration":"105.078072ms","start":"2026-05-08T05:20:55.869082Z","end":"2026-05-08T05:20:55.97416Z","steps":["trace[2077048877] 'range keys from in-memory index tree' (duration: 104.342269ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:21:00.153262Z","caller":"traceutil/trace.go:171","msg":"trace[1330365467] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"117.688933ms","start":"2026-05-08T05:21:00.035551Z","end":"2026-05-08T05:21:00.15324Z","steps":["trace[1330365467] 'process raft request' (duration: 117.560941ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:21:00.331233Z","caller":"traceutil/trace.go:171","msg":"trace[1128494016] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"295.517819ms","start":"2026-05-08T05:21:00.035557Z","end":"2026-05-08T05:21:00.331075Z","steps":["trace[1128494016] 'process raft request' (duration: 295.305295ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:21:00.331428Z","caller":"traceutil/trace.go:171","msg":"trace[611672702] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"295.228824ms","start":"2026-05-08T05:21:00.036183Z","end":"2026-05-08T05:21:00.331412Z","steps":["trace[611672702] 'process raft request' (duration: 294.971779ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:21:00.331432Z","caller":"traceutil/trace.go:171","msg":"trace[731936631] linearizableReadLoop","detail":"{readStateIndex:1778; appliedIndex:1774; }","duration":"291.022494ms","start":"2026-05-08T05:21:00.040386Z","end":"2026-05-08T05:21:00.331409Z","steps":["trace[731936631] 'read index received' (duration: 112.771921ms)","trace[731936631] 'applied index is now lower than readState.Index' (duration: 178.249283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:21:00.331579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.165716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T05:21:00.331645Z","caller":"traceutil/trace.go:171","msg":"trace[1655531759] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1708; }","duration":"291.270099ms","start":"2026-05-08T05:21:00.040353Z","end":"2026-05-08T05:21:00.331623Z","steps":["trace[1655531759] 'agreement among raft nodes before linearized reading' (duration: 291.165127ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:21:00.331657Z","caller":"traceutil/trace.go:171","msg":"trace[949963742] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"295.06465ms","start":"2026-05-08T05:21:00.036573Z","end":"2026-05-08T05:21:00.331637Z","steps":["trace[949963742] 'process raft request' (duration: 294.748194ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:21:00.816586Z","caller":"traceutil/trace.go:171","msg":"trace[258309679] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"130.068299ms","start":"2026-05-08T05:21:00.686491Z","end":"2026-05-08T05:21:00.81656Z","steps":["trace[258309679] 'process raft request' (duration: 127.379598ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:22:08.389781Z","caller":"traceutil/trace.go:171","msg":"trace[540446099] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"141.348621ms","start":"2026-05-08T05:22:08.248416Z","end":"2026-05-08T05:22:08.389765Z","steps":["trace[540446099] 'process raft request' (duration: 141.248229ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:22:53.497918Z","caller":"traceutil/trace.go:171","msg":"trace[1907014280] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"156.349226ms","start":"2026-05-08T05:22:53.341546Z","end":"2026-05-08T05:22:53.497895Z","steps":["trace[1907014280] 'process raft request' (duration: 156.205873ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:23:02.395303Z","caller":"traceutil/trace.go:171","msg":"trace[1246359388] linearizableReadLoop","detail":"{readStateIndex:2392; appliedIndex:2391; }","duration":"118.443908ms","start":"2026-05-08T05:23:02.276835Z","end":"2026-05-08T05:23:02.395279Z","steps":["trace[1246359388] 'read index received' (duration: 118.268955ms)","trace[1246359388] 'applied index is now lower than readState.Index' (duration: 173.813µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:23:02.39539Z","caller":"traceutil/trace.go:171","msg":"trace[200606961] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"124.331669ms","start":"2026-05-08T05:23:02.271027Z","end":"2026-05-08T05:23:02.395359Z","steps":["trace[200606961] 'process raft request' (duration: 124.091364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:23:02.395565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.705904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T05:23:02.395601Z","caller":"traceutil/trace.go:171","msg":"trace[609310978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2296; }","duration":"118.772925ms","start":"2026-05-08T05:23:02.276818Z","end":"2026-05-08T05:23:02.395591Z","steps":["trace[609310978] 'agreement among raft nodes before linearized reading' (duration: 118.56236ms)"],"step_count":1}