{"level":"warn","ts":"2026-06-03T21:48:28.402378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T21:48:28.402469Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-06-03T21:48:28.402548Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T21:48:28.402559Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-03T21:48:28.402575Z","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-06-03T21:48:28.403163Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-06-03T21:48:28.403253Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-06-03T21:48:28.407688Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.134939ms"} {"level":"info","ts":"2026-06-03T21:48:28.418261Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-06-03T21:48:28.41834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-03T21:48:28.418365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-06-03T21:48:28.418376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-03T21:48:28.418383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-06-03T21:48:28.418507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-06-03T21:48:28.427181Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-03T21:48:28.429677Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-03T21:48:28.431142Z","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-06-03T21:48:28.43363Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-03T21:48:28.43373Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-03T21:48:28.43388Z","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-06-03T21:48:28.433943Z","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-06-03T21:48:28.433956Z","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-06-03T21:48:28.437064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-06-03T21:48:28.437238Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-03T21:48:28.438963Z","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-06-03T21:48:28.439216Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-03T21:48:28.439249Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-03T21:48:28.439385Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-03T21:48:28.439402Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-03T21:48:28.919001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-03T21:48:28.919062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-03T21:48:28.919085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-06-03T21:48:28.919105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-06-03T21:48:28.919117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-03T21:48:28.919145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-06-03T21:48:28.919166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-03T21:48:28.920184Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:48:28.920933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T21:48:28.920928Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-06-03T21:48:28.921524Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:48:28.921658Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:48:28.921695Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T21:48:28.921978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T21:48:28.924134Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-06-03T21:48:28.924684Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-03T21:48:28.924759Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-03T21:48:28.925956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-03T21:48:40.551489Z","caller":"traceutil/trace.go:171","msg":"trace[1085676896] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"170.704418ms","start":"2026-06-03T21:48:40.380745Z","end":"2026-06-03T21:48:40.55145Z","steps":["trace[1085676896] 'process raft request' (duration: 170.440402ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:48:46.688897Z","caller":"traceutil/trace.go:171","msg":"trace[512736176] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"316.391131ms","start":"2026-06-03T21:48:46.37248Z","end":"2026-06-03T21:48:46.688871Z","steps":["trace[512736176] 'process raft request' (duration: 316.223247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:48:46.690122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:48:46.372455Z","time spent":"316.524284ms","remote":"127.0.0.1:37878","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-06-03T21:48:47.360911Z","caller":"traceutil/trace.go:171","msg":"trace[2017325955] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:383; }","duration":"901.381167ms","start":"2026-06-03T21:48:46.459505Z","end":"2026-06-03T21:48:47.360886Z","steps":["trace[2017325955] 'read index received' (duration: 229.178482ms)","trace[2017325955] 'applied index is now lower than readState.Index' (duration: 672.201665ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:48:47.361013Z","caller":"traceutil/trace.go:171","msg":"trace[896418964] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"988.123794ms","start":"2026-06-03T21:48:46.372844Z","end":"2026-06-03T21:48:47.360968Z","steps":["trace[896418964] 'process raft request' (duration: 987.884298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:48:47.361094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.559541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T21:48:47.361174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:48:46.372828Z","time spent":"988.255716ms","remote":"127.0.0.1:37878","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-06-03T21:48:47.361196Z","caller":"traceutil/trace.go:171","msg":"trace[2016836467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"901.716854ms","start":"2026-06-03T21:48:46.459466Z","end":"2026-06-03T21:48:47.361183Z","steps":["trace[2016836467] 'agreement among raft nodes before linearized reading' (duration: 901.56342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:48:47.361237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:48:46.459449Z","time spent":"901.778236ms","remote":"127.0.0.1:44390","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-06-03T21:48:47.363884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.869926ms","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-06-03T21:48:47.363947Z","caller":"traceutil/trace.go:171","msg":"trace[779311648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"788.942887ms","start":"2026-06-03T21:48:46.57499Z","end":"2026-06-03T21:48:47.363933Z","steps":["trace[779311648] 'agreement among raft nodes before linearized reading' (duration: 788.849655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:48:47.363983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:48:46.574985Z","time spent":"788.989638ms","remote":"127.0.0.1:37878","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-06-03T21:48:47.364165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.091035ms","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-06-03T21:48:47.364202Z","caller":"traceutil/trace.go:171","msg":"trace[600527785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"790.156727ms","start":"2026-06-03T21:48:46.574036Z","end":"2026-06-03T21:48:47.364193Z","steps":["trace[600527785] 'agreement among raft nodes before linearized reading' (duration: 790.099895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:48:47.36423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:48:46.574021Z","time spent":"790.202947ms","remote":"127.0.0.1:37878","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-06-03T21:49:04.886783Z","caller":"traceutil/trace.go:171","msg":"trace[1588993905] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"423.511367ms","start":"2026-06-03T21:49:04.463245Z","end":"2026-06-03T21:49:04.886757Z","steps":["trace[1588993905] 'read index received' (duration: 423.423945ms)","trace[1588993905] 'applied index is now lower than readState.Index' (duration: 86.352µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:04.886916Z","caller":"traceutil/trace.go:171","msg":"trace[1020519184] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"426.450649ms","start":"2026-06-03T21:49:04.460449Z","end":"2026-06-03T21:49:04.8869Z","steps":["trace[1020519184] 'process raft request' (duration: 426.132591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.887009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.460427Z","time spent":"426.517871ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:49:04.88705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.774343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:49:04.887139Z","caller":"traceutil/trace.go:171","msg":"trace[614591922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"423.907847ms","start":"2026-06-03T21:49:04.46321Z","end":"2026-06-03T21:49:04.887118Z","steps":["trace[614591922] 'agreement among raft nodes before linearized reading' (duration: 423.696852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.887198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.463199Z","time spent":"423.990829ms","remote":"127.0.0.1:44390","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-06-03T21:49:04.933248Z","caller":"traceutil/trace.go:171","msg":"trace[1978974733] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"468.577098ms","start":"2026-06-03T21:49:04.464657Z","end":"2026-06-03T21:49:04.933234Z","steps":["trace[1978974733] 'process raft request' (duration: 468.369033ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:04.933286Z","caller":"traceutil/trace.go:171","msg":"trace[1547033923] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"465.175395ms","start":"2026-06-03T21:49:04.468088Z","end":"2026-06-03T21:49:04.933263Z","steps":["trace[1547033923] 'process raft request' (duration: 465.064142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.933337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.464644Z","time spent":"468.63863ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:49:04.933361Z","caller":"traceutil/trace.go:171","msg":"trace[302141048] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"462.51087ms","start":"2026-06-03T21:49:04.470819Z","end":"2026-06-03T21:49:04.93333Z","steps":["trace[302141048] 'process raft request' (duration: 462.374846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.933398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.468073Z","time spent":"465.268657ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:49:04.933396Z","caller":"traceutil/trace.go:171","msg":"trace[1156357588] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"459.968758ms","start":"2026-06-03T21:49:04.473386Z","end":"2026-06-03T21:49:04.933355Z","steps":["trace[1156357588] 'process raft request' (duration: 459.844115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.933456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.470802Z","time spent":"462.609153ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:49:04.933486Z","caller":"traceutil/trace.go:171","msg":"trace[1701911439] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"458.510062ms","start":"2026-06-03T21:49:04.474967Z","end":"2026-06-03T21:49:04.933477Z","steps":["trace[1701911439] 'process raft request' (duration: 458.297807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.93352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.47496Z","time spent":"458.543763ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:49:04.933587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.473377Z","time spent":"460.093001ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:49:04.933859Z","caller":"traceutil/trace.go:171","msg":"trace[154498057] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"458.84737ms","start":"2026-06-03T21:49:04.475004Z","end":"2026-06-03T21:49:04.933851Z","steps":["trace[154498057] 'process raft request' (duration: 458.296737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.933898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.475Z","time spent":"458.883191ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:49:04.933958Z","caller":"traceutil/trace.go:171","msg":"trace[1075810314] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"454.939146ms","start":"2026-06-03T21:49:04.479013Z","end":"2026-06-03T21:49:04.933952Z","steps":["trace[1075810314] 'process raft request' (duration: 454.763631ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:04.933967Z","caller":"traceutil/trace.go:171","msg":"trace[711023492] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"453.170882ms","start":"2026-06-03T21:49:04.480785Z","end":"2026-06-03T21:49:04.933956Z","steps":["trace[711023492] 'process raft request' (duration: 453.037248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.933983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.478979Z","time spent":"454.990657ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:49:04.934023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.480776Z","time spent":"453.222763ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:49:04.934034Z","caller":"traceutil/trace.go:171","msg":"trace[1816794448] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"437.468668ms","start":"2026-06-03T21:49:04.49656Z","end":"2026-06-03T21:49:04.934029Z","steps":["trace[1816794448] 'process raft request' (duration: 437.322265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.934079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.49655Z","time spent":"437.498989ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:49:04.934158Z","caller":"traceutil/trace.go:171","msg":"trace[1624464406] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"409.452655ms","start":"2026-06-03T21:49:04.524678Z","end":"2026-06-03T21:49:04.934131Z","steps":["trace[1624464406] 'process raft request' (duration: 409.271081ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:04.934199Z","caller":"traceutil/trace.go:171","msg":"trace[1429572741] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"132.403652ms","start":"2026-06-03T21:49:04.801783Z","end":"2026-06-03T21:49:04.934186Z","steps":["trace[1429572741] 'process raft request' (duration: 132.32522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.934291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.52466Z","time spent":"409.563807ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:49:04.934332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.195093ms","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-06-03T21:49:04.934364Z","caller":"traceutil/trace.go:171","msg":"trace[580268110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"156.261654ms","start":"2026-06-03T21:49:04.778093Z","end":"2026-06-03T21:49:04.934355Z","steps":["trace[580268110] 'agreement among raft nodes before linearized reading' (duration: 156.191923ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:04.934375Z","caller":"traceutil/trace.go:171","msg":"trace[1883276999] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"300.023123ms","start":"2026-06-03T21:49:04.634322Z","end":"2026-06-03T21:49:04.934345Z","steps":["trace[1883276999] 'process raft request' (duration: 299.695935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:04.934472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:04.634296Z","time spent":"300.150827ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:49:05.545101Z","caller":"traceutil/trace.go:171","msg":"trace[437809805] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"327.670878ms","start":"2026-06-03T21:49:05.217413Z","end":"2026-06-03T21:49:05.545083Z","steps":["trace[437809805] 'process raft request' (duration: 327.230637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:05.545216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:05.217397Z","time spent":"327.762721ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:49:05.553656Z","caller":"traceutil/trace.go:171","msg":"trace[1154242511] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:551; }","duration":"112.865624ms","start":"2026-06-03T21:49:05.440769Z","end":"2026-06-03T21:49:05.553634Z","steps":["trace[1154242511] 'read index received' (duration: 103.897336ms)","trace[1154242511] 'applied index is now lower than readState.Index' (duration: 8.967388ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:05.553792Z","caller":"traceutil/trace.go:171","msg":"trace[866866991] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"312.725503ms","start":"2026-06-03T21:49:05.241029Z","end":"2026-06-03T21:49:05.553754Z","steps":["trace[866866991] 'process raft request' (duration: 312.335214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:05.55388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:05.241007Z","time spent":"312.814726ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:49:05.553969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.192822ms","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-06-03T21:49:05.554009Z","caller":"traceutil/trace.go:171","msg":"trace[54686652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"113.263134ms","start":"2026-06-03T21:49:05.440736Z","end":"2026-06-03T21:49:05.553999Z","steps":["trace[54686652] 'agreement among raft nodes before linearized reading' (duration: 113.10891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:05.554159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.71681ms","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-06-03T21:49:05.554215Z","caller":"traceutil/trace.go:171","msg":"trace[53633591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"112.785783ms","start":"2026-06-03T21:49:05.441414Z","end":"2026-06-03T21:49:05.5542Z","steps":["trace[53633591] 'agreement among raft nodes before linearized reading' (duration: 112.636639ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:05.554253Z","caller":"traceutil/trace.go:171","msg":"trace[1256874869] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"297.049491ms","start":"2026-06-03T21:49:05.257187Z","end":"2026-06-03T21:49:05.554237Z","steps":["trace[1256874869] 'process raft request' (duration: 296.306393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:05.554487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.722048ms","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-06-03T21:49:05.554513Z","caller":"traceutil/trace.go:171","msg":"trace[427763666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"109.775109ms","start":"2026-06-03T21:49:05.44473Z","end":"2026-06-03T21:49:05.554505Z","steps":["trace[427763666] 'agreement among raft nodes before linearized reading' (duration: 109.686547ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:06.33087Z","caller":"traceutil/trace.go:171","msg":"trace[1434375001] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"202.854322ms","start":"2026-06-03T21:49:06.127994Z","end":"2026-06-03T21:49:06.330848Z","steps":["trace[1434375001] 'process raft request' (duration: 155.127906ms)","trace[1434375001] 'compare' (duration: 47.51199ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:06.331196Z","caller":"traceutil/trace.go:171","msg":"trace[1106282077] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"126.864128ms","start":"2026-06-03T21:49:06.204295Z","end":"2026-06-03T21:49:06.331159Z","steps":["trace[1106282077] 'process raft request' (duration: 126.743515ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:06.526403Z","caller":"traceutil/trace.go:171","msg":"trace[1970275433] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"152.942162ms","start":"2026-06-03T21:49:06.37344Z","end":"2026-06-03T21:49:06.526383Z","steps":["trace[1970275433] 'read index received' (duration: 139.156436ms)","trace[1970275433] 'applied index is now lower than readState.Index' (duration: 13.784966ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:06.526479Z","caller":"traceutil/trace.go:171","msg":"trace[142614993] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"157.825212ms","start":"2026-06-03T21:49:06.36861Z","end":"2026-06-03T21:49:06.526435Z","steps":["trace[142614993] 'process raft request' (duration: 144.055386ms)","trace[142614993] 'compare' (duration: 13.633212ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:06.526571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.105846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-06-03T21:49:06.526612Z","caller":"traceutil/trace.go:171","msg":"trace[1020585044] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:555; }","duration":"153.22912ms","start":"2026-06-03T21:49:06.373368Z","end":"2026-06-03T21:49:06.526597Z","steps":["trace[1020585044] 'agreement among raft nodes before linearized reading' (duration: 153.108767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:06.685663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.69564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-06-03T21:49:06.68575Z","caller":"traceutil/trace.go:171","msg":"trace[1457816291] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:556; }","duration":"130.830313ms","start":"2026-06-03T21:49:06.5549Z","end":"2026-06-03T21:49:06.685731Z","steps":["trace[1457816291] 'agreement among raft nodes before linearized reading' (duration: 129.348238ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:06.694Z","caller":"traceutil/trace.go:171","msg":"trace[14484670] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"129.049871ms","start":"2026-06-03T21:49:06.554951Z","end":"2026-06-03T21:49:06.684001Z","steps":["trace[14484670] 'read index received' (duration: 122.584352ms)","trace[14484670] 'applied index is now lower than readState.Index' (duration: 6.463649ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:49:06.69367Z","caller":"traceutil/trace.go:171","msg":"trace[1554590965] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"153.971059ms","start":"2026-06-03T21:49:06.53964Z","end":"2026-06-03T21:49:06.693611Z","steps":["trace[1554590965] 'process raft request' (duration: 138.187673ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:06.694527Z","caller":"traceutil/trace.go:171","msg":"trace[812900289] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"125.638247ms","start":"2026-06-03T21:49:06.568862Z","end":"2026-06-03T21:49:06.6945Z","steps":["trace[812900289] 'process raft request' (duration: 125.410881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:06.96518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.274902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-06-03T21:49:06.965255Z","caller":"traceutil/trace.go:171","msg":"trace[649667999] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:558; }","duration":"178.382005ms","start":"2026-06-03T21:49:06.786854Z","end":"2026-06-03T21:49:06.965236Z","steps":["trace[649667999] 'range keys from in-memory index tree' (duration: 178.097388ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:06.973041Z","caller":"traceutil/trace.go:171","msg":"trace[626353215] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"136.361179ms","start":"2026-06-03T21:49:06.836658Z","end":"2026-06-03T21:49:06.973019Z","steps":["trace[626353215] 'process raft request' (duration: 136.039281ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:11.406267Z","caller":"traceutil/trace.go:171","msg":"trace[1101801597] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"163.655814ms","start":"2026-06-03T21:49:11.242583Z","end":"2026-06-03T21:49:11.406239Z","steps":["trace[1101801597] 'process raft request' (duration: 163.001998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:11.727527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.420879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:49:11.727643Z","caller":"traceutil/trace.go:171","msg":"trace[1386844556] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"267.548471ms","start":"2026-06-03T21:49:11.460076Z","end":"2026-06-03T21:49:11.727625Z","steps":["trace[1386844556] 'read index received' (duration: 153.810814ms)","trace[1386844556] 'applied index is now lower than readState.Index' (duration: 113.736167ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:11.727742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.675625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:49:11.727773Z","caller":"traceutil/trace.go:171","msg":"trace[1334685903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"267.746616ms","start":"2026-06-03T21:49:11.460018Z","end":"2026-06-03T21:49:11.727764Z","steps":["trace[1334685903] 'agreement among raft nodes before linearized reading' (duration: 267.685925ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:11.727733Z","caller":"traceutil/trace.go:171","msg":"trace[1432635262] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"373.65714ms","start":"2026-06-03T21:49:11.354051Z","end":"2026-06-03T21:49:11.727708Z","steps":["trace[1432635262] 'process raft request' (duration: 259.717138ms)","trace[1432635262] 'compare' (duration: 113.140743ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:11.727967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:11.354008Z","time spent":"373.872025ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:49:11.750134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.437217ms","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-06-03T21:49:11.750123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.388056ms","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-06-03T21:49:11.750112Z","caller":"traceutil/trace.go:171","msg":"trace[1331330848] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"289.809494ms","start":"2026-06-03T21:49:11.460263Z","end":"2026-06-03T21:49:11.750072Z","steps":["trace[1331330848] 'process raft request' (duration: 289.472056ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:11.75021Z","caller":"traceutil/trace.go:171","msg":"trace[1106700342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"167.53998ms","start":"2026-06-03T21:49:11.582657Z","end":"2026-06-03T21:49:11.750197Z","steps":["trace[1106700342] 'agreement among raft nodes before linearized reading' (duration: 167.225072ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:11.750235Z","caller":"traceutil/trace.go:171","msg":"trace[459931939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"167.5557ms","start":"2026-06-03T21:49:11.582656Z","end":"2026-06-03T21:49:11.750211Z","steps":["trace[459931939] 'agreement among raft nodes before linearized reading' (duration: 167.214442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:11.750463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.22066ms","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-06-03T21:49:11.750502Z","caller":"traceutil/trace.go:171","msg":"trace[1531527685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"169.294482ms","start":"2026-06-03T21:49:11.581198Z","end":"2026-06-03T21:49:11.750493Z","steps":["trace[1531527685] 'agreement among raft nodes before linearized reading' (duration: 169.191149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:12.961627Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393798677464892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T21:49:13.000201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12087648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-03T21:49:13.000269Z","caller":"traceutil/trace.go:171","msg":"trace[566081043] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:590; }","duration":"1.121003203s","start":"2026-06-03T21:49:11.879245Z","end":"2026-06-03T21:49:13.000248Z","steps":["trace[566081043] 'range keys from in-memory index tree' (duration: 1.120713406s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:13.000308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:11.87921Z","time spent":"1.121089095s","remote":"127.0.0.1:37622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"info","ts":"2026-06-03T21:49:13.052459Z","caller":"traceutil/trace.go:171","msg":"trace[1175380528] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"591.791045ms","start":"2026-06-03T21:49:12.460646Z","end":"2026-06-03T21:49:13.052437Z","steps":["trace[1175380528] 'read index received' (duration: 591.084018ms)","trace[1175380528] 'applied index is now lower than readState.Index' (duration: 705.887µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:13.052604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.959759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:49:13.052636Z","caller":"traceutil/trace.go:171","msg":"trace[1655495044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"592.047201ms","start":"2026-06-03T21:49:12.460577Z","end":"2026-06-03T21:49:13.052624Z","steps":["trace[1655495044] 'agreement among raft nodes before linearized reading' (duration: 591.964479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:13.052665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:12.460541Z","time spent":"592.117463ms","remote":"127.0.0.1:44390","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-06-03T21:49:13.05279Z","caller":"traceutil/trace.go:171","msg":"trace[1854888080] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"821.150874ms","start":"2026-06-03T21:49:12.231588Z","end":"2026-06-03T21:49:13.052738Z","steps":["trace[1854888080] 'process raft request' (duration: 820.272923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:13.052983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:12.230881Z","time spent":"821.997844ms","remote":"127.0.0.1:37662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:49:19.902064Z","caller":"traceutil/trace.go:171","msg":"trace[72651816] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"110.772484ms","start":"2026-06-03T21:49:19.791267Z","end":"2026-06-03T21:49:19.902039Z","steps":["trace[72651816] 'process raft request' (duration: 104.998893ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:49:37.533725Z","caller":"traceutil/trace.go:171","msg":"trace[2015863536] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"498.430336ms","start":"2026-06-03T21:49:37.035257Z","end":"2026-06-03T21:49:37.533687Z","steps":["trace[2015863536] 'read index received' (duration: 478.543311ms)","trace[2015863536] 'applied index is now lower than readState.Index' (duration: 19.886085ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:49:37.533949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.35302ms","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-06-03T21:49:37.533977Z","caller":"traceutil/trace.go:171","msg":"trace[1853837345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"329.437162ms","start":"2026-06-03T21:49:37.204534Z","end":"2026-06-03T21:49:37.533971Z","steps":["trace[1853837345] 'agreement among raft nodes before linearized reading' (duration: 329.293878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:37.53397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.695903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xf98z\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-03T21:49:37.534048Z","caller":"traceutil/trace.go:171","msg":"trace[359126829] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xf98z; range_end:; response_count:1; response_revision:780; }","duration":"498.822236ms","start":"2026-06-03T21:49:37.03521Z","end":"2026-06-03T21:49:37.534032Z","steps":["trace[359126829] 'agreement among raft nodes before linearized reading' (duration: 498.608561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:49:37.534081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:37.035191Z","time spent":"498.883867ms","remote":"127.0.0.1:37760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xf98z\" "} {"level":"warn","ts":"2026-06-03T21:49:37.534002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:49:37.204516Z","time spent":"329.480524ms","remote":"127.0.0.1:37878","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-06-03T21:49:37.533966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.720476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:49:37.534218Z","caller":"traceutil/trace.go:171","msg":"trace[1146853586] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:780; }","duration":"241.011423ms","start":"2026-06-03T21:49:37.293172Z","end":"2026-06-03T21:49:37.534183Z","steps":["trace[1146853586] 'agreement among raft nodes before linearized reading' (duration: 240.717326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:14.692957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.454149ms","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-06-03T21:50:14.693105Z","caller":"traceutil/trace.go:171","msg":"trace[148046938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"326.650434ms","start":"2026-06-03T21:50:14.366429Z","end":"2026-06-03T21:50:14.69308Z","steps":["trace[148046938] 'range keys from in-memory index tree' (duration: 326.294266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:14.693123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.000977ms","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-06-03T21:50:14.693154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.366411Z","time spent":"326.729696ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-03T21:50:14.693162Z","caller":"traceutil/trace.go:171","msg":"trace[1396142402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:997; }","duration":"321.057549ms","start":"2026-06-03T21:50:14.372094Z","end":"2026-06-03T21:50:14.693151Z","steps":["trace[1396142402] 'range keys from in-memory index tree' (duration: 320.889395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:14.693288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.603858ms","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-06-03T21:50:14.693322Z","caller":"traceutil/trace.go:171","msg":"trace[1262030823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:997; }","duration":"344.668219ms","start":"2026-06-03T21:50:14.348641Z","end":"2026-06-03T21:50:14.693309Z","steps":["trace[1262030823] 'range keys from in-memory index tree' (duration: 344.481675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:14.693347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.348622Z","time spent":"344.717631ms","remote":"127.0.0.1:37878","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-06-03T21:50:14.693475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.575685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:14.69351Z","caller":"traceutil/trace.go:171","msg":"trace[23424066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"233.659787ms","start":"2026-06-03T21:50:14.459838Z","end":"2026-06-03T21:50:14.693498Z","steps":["trace[23424066] 'range keys from in-memory index tree' (duration: 233.512893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:14.6932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.372083Z","time spent":"321.10791ms","remote":"127.0.0.1:37878","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-06-03T21:50:15.007266Z","caller":"traceutil/trace.go:171","msg":"trace[1811777851] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"312.565023ms","start":"2026-06-03T21:50:14.694643Z","end":"2026-06-03T21:50:15.007208Z","steps":["trace[1811777851] 'read index received' (duration: 312.382009ms)","trace[1811777851] 'applied index is now lower than readState.Index' (duration: 181.814µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:15.007439Z","caller":"traceutil/trace.go:171","msg":"trace[659192395] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"458.184812ms","start":"2026-06-03T21:50:14.549231Z","end":"2026-06-03T21:50:15.007416Z","steps":["trace[659192395] 'process raft request' (duration: 457.809303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:15.007627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.549216Z","time spent":"458.265905ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:50:15.007635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.963134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:15.008333Z","caller":"traceutil/trace.go:171","msg":"trace[734567301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"313.070566ms","start":"2026-06-03T21:50:14.694622Z","end":"2026-06-03T21:50:15.007693Z","steps":["trace[734567301] 'agreement among raft nodes before linearized reading' (duration: 312.919642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:15.008391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.694597Z","time spent":"313.782543ms","remote":"127.0.0.1:44404","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-06-03T21:50:15.125251Z","caller":"traceutil/trace.go:171","msg":"trace[177010747] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"428.661379ms","start":"2026-06-03T21:50:14.696565Z","end":"2026-06-03T21:50:15.125226Z","steps":["trace[177010747] 'process raft request' (duration: 428.514206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:15.125397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.69655Z","time spent":"428.768331ms","remote":"127.0.0.1:37878","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-06-03T21:50:15.125656Z","caller":"traceutil/trace.go:171","msg":"trace[1246020664] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"428.223729ms","start":"2026-06-03T21:50:14.697418Z","end":"2026-06-03T21:50:15.125642Z","steps":["trace[1246020664] 'process raft request' (duration: 427.777378ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:15.125759Z","caller":"traceutil/trace.go:171","msg":"trace[604576017] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1038; }","duration":"118.3727ms","start":"2026-06-03T21:50:15.007361Z","end":"2026-06-03T21:50:15.125734Z","steps":["trace[604576017] 'read index received' (duration: 117.658263ms)","trace[604576017] 'applied index is now lower than readState.Index' (duration: 713.257µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:50:15.125736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.697404Z","time spent":"428.29013ms","remote":"127.0.0.1:37878","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-06-03T21:50:15.125863Z","caller":"traceutil/trace.go:171","msg":"trace[777343007] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"427.660625ms","start":"2026-06-03T21:50:14.698183Z","end":"2026-06-03T21:50:15.125844Z","steps":["trace[777343007] 'process raft request' (duration: 427.424479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:15.126033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.944145ms","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-06-03T21:50:15.126074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.698156Z","time spent":"427.85003ms","remote":"127.0.0.1:37878","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-06-03T21:50:15.126084Z","caller":"traceutil/trace.go:171","msg":"trace[1537829604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"321.043667ms","start":"2026-06-03T21:50:14.805027Z","end":"2026-06-03T21:50:15.126071Z","steps":["trace[1537829604] 'agreement among raft nodes before linearized reading' (duration: 320.804452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:15.126139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:14.805003Z","time spent":"321.12491ms","remote":"127.0.0.1:37878","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-06-03T21:50:38.460584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.020381ms","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-06-03T21:50:38.460719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.978923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:38.460709Z","caller":"traceutil/trace.go:171","msg":"trace[1014158080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1075; }","duration":"437.180764ms","start":"2026-06-03T21:50:38.023506Z","end":"2026-06-03T21:50:38.460687Z","steps":["trace[1014158080] 'range keys from in-memory index tree' (duration: 436.834767ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:38.460756Z","caller":"traceutil/trace.go:171","msg":"trace[540808297] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1075; }","duration":"445.074935ms","start":"2026-06-03T21:50:38.015667Z","end":"2026-06-03T21:50:38.460742Z","steps":["trace[540808297] 'count revisions from in-memory index tree' (duration: 444.886681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:38.460775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:38.023495Z","time spent":"437.266677ms","remote":"127.0.0.1:37878","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-06-03T21:50:38.46079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:38.015641Z","time spent":"445.139437ms","remote":"127.0.0.1:34250","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":"warn","ts":"2026-06-03T21:50:38.460616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.348041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T21:50:38.460883Z","caller":"traceutil/trace.go:171","msg":"trace[1284457352] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1075; }","duration":"709.631878ms","start":"2026-06-03T21:50:37.751206Z","end":"2026-06-03T21:50:38.460838Z","steps":["trace[1284457352] 'count revisions from in-memory index tree' (duration: 709.135237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:38.460994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:37.751185Z","time spent":"709.768391ms","remote":"127.0.0.1:38094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-03T21:50:38.460614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000451896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:38.461101Z","caller":"traceutil/trace.go:171","msg":"trace[1828676328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"1.000975519s","start":"2026-06-03T21:50:37.460113Z","end":"2026-06-03T21:50:38.461088Z","steps":["trace[1828676328] 'range keys from in-memory index tree' (duration: 1.000337513s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:38.461145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:37.460094Z","time spent":"1.00103899s","remote":"127.0.0.1:44390","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-06-03T21:50:39.834566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393798677465970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T21:50:40.177728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.610522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:50:40.177848Z","caller":"traceutil/trace.go:171","msg":"trace[322299500] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"844.226291ms","start":"2026-06-03T21:50:39.333601Z","end":"2026-06-03T21:50:40.177827Z","steps":["trace[322299500] 'read index received' (duration: 726.379853ms)","trace[322299500] 'applied index is now lower than readState.Index' (duration: 117.844518ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:40.177915Z","caller":"traceutil/trace.go:171","msg":"trace[1379828680] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"854.755915ms","start":"2026-06-03T21:50:39.323123Z","end":"2026-06-03T21:50:40.177879Z","steps":["trace[1379828680] 'process raft request' (duration: 736.909538ms)","trace[1379828680] 'compare' (duration: 117.451848ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:50:40.178023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:39.32311Z","time spent":"854.856547ms","remote":"127.0.0.1:37878","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-06-03T21:50:40.178071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.457787ms","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-06-03T21:50:40.178117Z","caller":"traceutil/trace.go:171","msg":"trace[403059370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"844.524119ms","start":"2026-06-03T21:50:39.333579Z","end":"2026-06-03T21:50:40.178103Z","steps":["trace[403059370] 'agreement among raft nodes before linearized reading' (duration: 844.348155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:40.178157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:39.333569Z","time spent":"844.57743ms","remote":"127.0.0.1:37878","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-06-03T21:50:40.178296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.139856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:40.178341Z","caller":"traceutil/trace.go:171","msg":"trace[2031436843] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1079; }","duration":"531.187487ms","start":"2026-06-03T21:50:39.64714Z","end":"2026-06-03T21:50:40.178327Z","steps":["trace[2031436843] 'agreement among raft nodes before linearized reading' (duration: 531.117385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:40.178367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.150128ms","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-06-03T21:50:40.178429Z","caller":"traceutil/trace.go:171","msg":"trace[1627391512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"844.23616ms","start":"2026-06-03T21:50:39.334177Z","end":"2026-06-03T21:50:40.178413Z","steps":["trace[1627391512] 'agreement among raft nodes before linearized reading' (duration: 844.069056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:40.178468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:39.33417Z","time spent":"844.289582ms","remote":"127.0.0.1:37878","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-06-03T21:50:40.178617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.429328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:40.178655Z","caller":"traceutil/trace.go:171","msg":"trace[368954111] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1079; }","duration":"787.554021ms","start":"2026-06-03T21:50:39.391091Z","end":"2026-06-03T21:50:40.178645Z","steps":["trace[368954111] 'agreement among raft nodes before linearized reading' (duration: 787.454698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:40.178719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:39.39107Z","time spent":"787.638543ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-03T21:50:40.178388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:39.647091Z","time spent":"531.283299ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-06-03T21:50:41.305685Z","caller":"traceutil/trace.go:171","msg":"trace[2064237022] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"198.307701ms","start":"2026-06-03T21:50:41.107351Z","end":"2026-06-03T21:50:41.305659Z","steps":["trace[2064237022] 'process raft request' (duration: 198.142137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:42.118493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.851686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:50:42.118979Z","caller":"traceutil/trace.go:171","msg":"trace[138489218] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"204.312877ms","start":"2026-06-03T21:50:41.914627Z","end":"2026-06-03T21:50:42.118939Z","steps":["trace[138489218] 'process raft request' (duration: 64.933029ms)","trace[138489218] 'compare' (duration: 138.721313ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:42.395566Z","caller":"traceutil/trace.go:171","msg":"trace[1688834924] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"169.940506ms","start":"2026-06-03T21:50:42.225601Z","end":"2026-06-03T21:50:42.395541Z","steps":["trace[1688834924] 'read index received' (duration: 169.787382ms)","trace[1688834924] 'applied index is now lower than readState.Index' (duration: 152.124µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:42.395619Z","caller":"traceutil/trace.go:171","msg":"trace[22700835] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"210.136489ms","start":"2026-06-03T21:50:42.185453Z","end":"2026-06-03T21:50:42.395589Z","steps":["trace[22700835] 'process raft request' (duration: 209.907812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:42.395973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.330397ms","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-06-03T21:50:42.396028Z","caller":"traceutil/trace.go:171","msg":"trace[362318627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"170.447839ms","start":"2026-06-03T21:50:42.225569Z","end":"2026-06-03T21:50:42.396017Z","steps":["trace[362318627] 'agreement among raft nodes before linearized reading' (duration: 170.099641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:42.396236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.510112ms","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-06-03T21:50:42.396317Z","caller":"traceutil/trace.go:171","msg":"trace[102816385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1086; }","duration":"156.654336ms","start":"2026-06-03T21:50:42.239642Z","end":"2026-06-03T21:50:42.396297Z","steps":["trace[102816385] 'agreement among raft nodes before linearized reading' (duration: 156.065302ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:43.439033Z","caller":"traceutil/trace.go:171","msg":"trace[1839907084] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"128.114586ms","start":"2026-06-03T21:50:43.310894Z","end":"2026-06-03T21:50:43.439008Z","steps":["trace[1839907084] 'process raft request' (duration: 127.85958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:57.36714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.252169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:57.367257Z","caller":"traceutil/trace.go:171","msg":"trace[1788066762] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1187; }","duration":"272.419163ms","start":"2026-06-03T21:50:57.094811Z","end":"2026-06-03T21:50:57.36723Z","steps":["trace[1788066762] 'count revisions from in-memory index tree' (duration: 272.138936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:57.369301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.52229ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-06-03T21:50:59.002004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393798677466222,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T21:50:59.04275Z","caller":"traceutil/trace.go:171","msg":"trace[1253940395] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"541.166037ms","start":"2026-06-03T21:50:58.501562Z","end":"2026-06-03T21:50:59.042728Z","steps":["trace[1253940395] 'read index received' (duration: 540.995293ms)","trace[1253940395] 'applied index is now lower than readState.Index' (duration: 169.844µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:50:59.04285Z","caller":"traceutil/trace.go:171","msg":"trace[39167391] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"580.030227ms","start":"2026-06-03T21:50:58.462787Z","end":"2026-06-03T21:50:59.042817Z","steps":["trace[39167391] 'process raft request' (duration: 579.781041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.042899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.606565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:50:59.042936Z","caller":"traceutil/trace.go:171","msg":"trace[692205755] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1190; }","duration":"526.676438ms","start":"2026-06-03T21:50:58.516248Z","end":"2026-06-03T21:50:59.042925Z","steps":["trace[692205755] 'agreement among raft nodes before linearized reading' (duration: 526.594006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.042945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.371023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-03T21:50:59.042972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:58.516242Z","time spent":"526.721189ms","remote":"127.0.0.1:37804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-06-03T21:50:59.043001Z","caller":"traceutil/trace.go:171","msg":"trace[815385393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1190; }","duration":"541.472834ms","start":"2026-06-03T21:50:58.501515Z","end":"2026-06-03T21:50:59.042987Z","steps":["trace[815385393] 'agreement among raft nodes before linearized reading' (duration: 541.30621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.043028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:58.501496Z","time spent":"541.526946ms","remote":"127.0.0.1:37878","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-06-03T21:50:59.04304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:58.462778Z","time spent":"580.14307ms","remote":"127.0.0.1:37878","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-06-03T21:50:59.04337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.806171ms","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-06-03T21:50:59.043411Z","caller":"traceutil/trace.go:171","msg":"trace[325863302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1190; }","duration":"514.874732ms","start":"2026-06-03T21:50:58.528527Z","end":"2026-06-03T21:50:59.043401Z","steps":["trace[325863302] 'agreement among raft nodes before linearized reading' (duration: 514.587755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.043446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:58.528522Z","time spent":"514.918844ms","remote":"127.0.0.1:37878","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-06-03T21:50:59.043689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.418981ms","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-06-03T21:50:59.043758Z","caller":"traceutil/trace.go:171","msg":"trace[480336467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1190; }","duration":"209.534303ms","start":"2026-06-03T21:50:58.834208Z","end":"2026-06-03T21:50:59.043742Z","steps":["trace[480336467] 'agreement among raft nodes before linearized reading' (duration: 208.874318ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:59.44862Z","caller":"traceutil/trace.go:171","msg":"trace[1437707985] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"400.873597ms","start":"2026-06-03T21:50:59.047729Z","end":"2026-06-03T21:50:59.448603Z","steps":["trace[1437707985] 'process raft request' (duration: 400.847057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.44874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:59.047703Z","time spent":"400.98001ms","remote":"127.0.0.1:37878","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-06-03T21:50:59.448887Z","caller":"traceutil/trace.go:171","msg":"trace[687625702] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"401.252896ms","start":"2026-06-03T21:50:59.047625Z","end":"2026-06-03T21:50:59.448878Z","steps":["trace[687625702] 'process raft request' (duration: 400.885887ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:50:59.448877Z","caller":"traceutil/trace.go:171","msg":"trace[1482044516] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"401.740558ms","start":"2026-06-03T21:50:59.047101Z","end":"2026-06-03T21:50:59.448842Z","steps":["trace[1482044516] 'process raft request' (duration: 396.334187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:50:59.448932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:59.047609Z","time spent":"401.301188ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:50:59.449062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:50:59.047086Z","time spent":"401.877951ms","remote":"127.0.0.1:37878","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-06-03T21:51:13.573365Z","caller":"traceutil/trace.go:171","msg":"trace[276863878] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"114.104988ms","start":"2026-06-03T21:51:13.459242Z","end":"2026-06-03T21:51:13.573347Z","steps":["trace[276863878] 'read index received' (duration: 113.973845ms)","trace[276863878] 'applied index is now lower than readState.Index' (duration: 130.153µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:51:13.573481Z","caller":"traceutil/trace.go:171","msg":"trace[2073470575] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"115.42036ms","start":"2026-06-03T21:51:13.458023Z","end":"2026-06-03T21:51:13.573444Z","steps":["trace[2073470575] 'process raft request' (duration: 115.191574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:13.573534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.277443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:13.573596Z","caller":"traceutil/trace.go:171","msg":"trace[1103543125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"114.395486ms","start":"2026-06-03T21:51:13.459185Z","end":"2026-06-03T21:51:13.573581Z","steps":["trace[1103543125] 'agreement among raft nodes before linearized reading' (duration: 114.271753ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:13.832919Z","caller":"traceutil/trace.go:171","msg":"trace[879058840] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"120.037612ms","start":"2026-06-03T21:51:13.712861Z","end":"2026-06-03T21:51:13.832898Z","steps":["trace[879058840] 'process raft request' (duration: 119.914709ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:15.441153Z","caller":"traceutil/trace.go:171","msg":"trace[1048832123] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"323.168195ms","start":"2026-06-03T21:51:15.11796Z","end":"2026-06-03T21:51:15.441129Z","steps":["trace[1048832123] 'process raft request' (duration: 322.995901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:15.441415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:15.117936Z","time spent":"323.286128ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:16.144111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.778844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:16.144296Z","caller":"traceutil/trace.go:171","msg":"trace[862276270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"687.01183ms","start":"2026-06-03T21:51:15.457263Z","end":"2026-06-03T21:51:16.144275Z","steps":["trace[862276270] 'range keys from in-memory index tree' (duration: 686.691102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:16.144391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:15.457248Z","time spent":"687.128012ms","remote":"127.0.0.1:44404","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-06-03T21:51:16.144466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.998089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:51:16.14513Z","caller":"traceutil/trace.go:171","msg":"trace[1859004023] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"563.139045ms","start":"2026-06-03T21:51:15.581977Z","end":"2026-06-03T21:51:16.145116Z","steps":["trace[1859004023] 'process raft request' (duration: 193.413089ms)","trace[1859004023] 'compare' (duration: 368.886566ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:16.145218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:15.581951Z","time spent":"563.228167ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:16.14509Z","caller":"traceutil/trace.go:171","msg":"trace[1631277377] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"558.192086ms","start":"2026-06-03T21:51:15.586882Z","end":"2026-06-03T21:51:16.145074Z","steps":["trace[1631277377] 'read index received' (duration: 188.446719ms)","trace[1631277377] 'applied index is now lower than readState.Index' (duration: 369.743977ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:16.145563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.683197ms","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-06-03T21:51:16.145782Z","caller":"traceutil/trace.go:171","msg":"trace[985674523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"558.911162ms","start":"2026-06-03T21:51:15.586831Z","end":"2026-06-03T21:51:16.145742Z","steps":["trace[985674523] 'agreement among raft nodes before linearized reading' (duration: 558.630086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:16.145867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:15.58681Z","time spent":"559.017424ms","remote":"127.0.0.1:37878","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-06-03T21:51:16.145893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.968579ms","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-06-03T21:51:16.145933Z","caller":"traceutil/trace.go:171","msg":"trace[1745068350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"558.022061ms","start":"2026-06-03T21:51:15.5879Z","end":"2026-06-03T21:51:16.145922Z","steps":["trace[1745068350] 'agreement among raft nodes before linearized reading' (duration: 557.918958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:16.145977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:15.587865Z","time spent":"558.101513ms","remote":"127.0.0.1:37878","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-06-03T21:51:16.146254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.586724ms","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-06-03T21:51:16.146296Z","caller":"traceutil/trace.go:171","msg":"trace[1030231693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"558.670896ms","start":"2026-06-03T21:51:15.587614Z","end":"2026-06-03T21:51:16.146285Z","steps":["trace[1030231693] 'agreement among raft nodes before linearized reading' (duration: 558.561894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:16.146325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:15.58759Z","time spent":"558.727148ms","remote":"127.0.0.1:37878","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-06-03T21:51:16.146518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.911833ms","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-06-03T21:51:16.146556Z","caller":"traceutil/trace.go:171","msg":"trace[1141574074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"558.980754ms","start":"2026-06-03T21:51:15.587567Z","end":"2026-06-03T21:51:16.146548Z","steps":["trace[1141574074] 'agreement among raft nodes before linearized reading' (duration: 558.208765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:16.146582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:15.587554Z","time spent":"559.022885ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-03T21:51:16.274814Z","caller":"traceutil/trace.go:171","msg":"trace[1164152518] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"124.055588ms","start":"2026-06-03T21:51:16.150734Z","end":"2026-06-03T21:51:16.274789Z","steps":["trace[1164152518] 'process raft request' (duration: 108.417212ms)","trace[1164152518] 'compare' (duration: 15.501542ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:51:16.275064Z","caller":"traceutil/trace.go:171","msg":"trace[426423081] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"123.792122ms","start":"2026-06-03T21:51:16.151264Z","end":"2026-06-03T21:51:16.275056Z","steps":["trace[426423081] 'process raft request' (duration: 123.497905ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:16.275371Z","caller":"traceutil/trace.go:171","msg":"trace[1034979335] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"122.303265ms","start":"2026-06-03T21:51:16.153059Z","end":"2026-06-03T21:51:16.275362Z","steps":["trace[1034979335] 'process raft request' (duration: 121.896096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:17.843592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.671588ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-03T21:51:17.843806Z","caller":"traceutil/trace.go:171","msg":"trace[988460273] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1323; }","duration":"398.276253ms","start":"2026-06-03T21:51:17.445514Z","end":"2026-06-03T21:51:17.84379Z","steps":["trace[988460273] 'read index received' (duration: 219.955508ms)","trace[988460273] 'applied index is now lower than readState.Index' (duration: 178.319615ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:51:17.844088Z","caller":"traceutil/trace.go:171","msg":"trace[629804251] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"447.99385ms","start":"2026-06-03T21:51:17.396056Z","end":"2026-06-03T21:51:17.84405Z","steps":["trace[629804251] 'process raft request' (duration: 447.624331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:17.844112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.593251ms","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-06-03T21:51:17.844111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.717797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T21:51:17.844123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.456547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:17.844176Z","caller":"traceutil/trace.go:171","msg":"trace[2070216884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"398.688333ms","start":"2026-06-03T21:51:17.44547Z","end":"2026-06-03T21:51:17.844158Z","steps":["trace[2070216884] 'agreement among raft nodes before linearized reading' (duration: 398.473128ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:17.844179Z","caller":"traceutil/trace.go:171","msg":"trace[1652068715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"384.831779ms","start":"2026-06-03T21:51:17.459333Z","end":"2026-06-03T21:51:17.844165Z","steps":["trace[1652068715] 'agreement among raft nodes before linearized reading' (duration: 384.720546ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:17.844185Z","caller":"traceutil/trace.go:171","msg":"trace[889791914] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1270; }","duration":"168.56218ms","start":"2026-06-03T21:51:17.675606Z","end":"2026-06-03T21:51:17.844169Z","steps":["trace[889791914] 'agreement among raft nodes before linearized reading' (duration: 168.467548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:17.84422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:17.459315Z","time spent":"384.895131ms","remote":"127.0.0.1:44390","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-06-03T21:51:17.84422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:17.44545Z","time spent":"398.760405ms","remote":"127.0.0.1:37878","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-06-03T21:51:17.844215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:17.396036Z","time spent":"448.112152ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:18.281449Z","caller":"traceutil/trace.go:171","msg":"trace[522772235] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"176.618314ms","start":"2026-06-03T21:51:18.104809Z","end":"2026-06-03T21:51:18.281428Z","steps":["trace[522772235] 'process raft request' (duration: 176.489731ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:18.37208Z","caller":"traceutil/trace.go:171","msg":"trace[393663612] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"185.715813ms","start":"2026-06-03T21:51:18.18634Z","end":"2026-06-03T21:51:18.372055Z","steps":["trace[393663612] 'read index received' (duration: 95.396158ms)","trace[393663612] 'applied index is now lower than readState.Index' (duration: 90.318725ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:18.372598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.518635ms","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-06-03T21:51:18.372645Z","caller":"traceutil/trace.go:171","msg":"trace[126481438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"184.579396ms","start":"2026-06-03T21:51:18.188055Z","end":"2026-06-03T21:51:18.372634Z","steps":["trace[126481438] 'agreement among raft nodes before linearized reading' (duration: 184.386531ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:18.372886Z","caller":"traceutil/trace.go:171","msg":"trace[1922522470] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"221.848713ms","start":"2026-06-03T21:51:18.151013Z","end":"2026-06-03T21:51:18.372862Z","steps":["trace[1922522470] 'process raft request' (duration: 220.906741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:18.373002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.663796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:18.37304Z","caller":"traceutil/trace.go:171","msg":"trace[1533923688] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1273; }","duration":"186.723887ms","start":"2026-06-03T21:51:18.186306Z","end":"2026-06-03T21:51:18.37303Z","steps":["trace[1533923688] 'agreement among raft nodes before linearized reading' (duration: 186.676776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:18.648811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.332054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:51:18.648926Z","caller":"traceutil/trace.go:171","msg":"trace[1991242538] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"270.488556ms","start":"2026-06-03T21:51:18.378414Z","end":"2026-06-03T21:51:18.648903Z","steps":["trace[1991242538] 'process raft request' (duration: 148.978069ms)","trace[1991242538] 'compare' (duration: 121.031136ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:51:18.689211Z","caller":"traceutil/trace.go:171","msg":"trace[1095969591] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1328; }","duration":"228.606016ms","start":"2026-06-03T21:51:18.460585Z","end":"2026-06-03T21:51:18.689191Z","steps":["trace[1095969591] 'read index received' (duration: 66.819909ms)","trace[1095969591] 'applied index is now lower than readState.Index' (duration: 161.785307ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T21:51:18.689346Z","caller":"traceutil/trace.go:171","msg":"trace[1502850085] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"310.066389ms","start":"2026-06-03T21:51:18.379261Z","end":"2026-06-03T21:51:18.689328Z","steps":["trace[1502850085] 'process raft request' (duration: 309.863304ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:18.689404Z","caller":"traceutil/trace.go:171","msg":"trace[439364568] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"311.39136ms","start":"2026-06-03T21:51:18.377981Z","end":"2026-06-03T21:51:18.689373Z","steps":["trace[439364568] 'process raft request' (duration: 311.114374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:18.689464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:18.379243Z","time spent":"310.151551ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T21:51:18.68956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:18.377958Z","time spent":"311.522454ms","remote":"127.0.0.1:37878","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-06-03T21:51:18.689632Z","caller":"traceutil/trace.go:171","msg":"trace[28716011] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"309.173707ms","start":"2026-06-03T21:51:18.380443Z","end":"2026-06-03T21:51:18.689617Z","steps":["trace[28716011] 'process raft request' (duration: 308.713486ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:18.689704Z","caller":"traceutil/trace.go:171","msg":"trace[1112981627] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"311.280108ms","start":"2026-06-03T21:51:18.378411Z","end":"2026-06-03T21:51:18.689691Z","steps":["trace[1112981627] 'process raft request' (duration: 310.581451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:18.689722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:18.380436Z","time spent":"309.236598ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:18.689765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:18.378383Z","time spent":"311.35293ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:18.689863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.270641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:18.68994Z","caller":"traceutil/trace.go:171","msg":"trace[204419634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"229.389614ms","start":"2026-06-03T21:51:18.460533Z","end":"2026-06-03T21:51:18.689922Z","steps":["trace[204419634] 'agreement among raft nodes before linearized reading' (duration: 229.275551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:18.9902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.724961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T21:51:18.990704Z","caller":"traceutil/trace.go:171","msg":"trace[390725502] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"336.599477ms","start":"2026-06-03T21:51:18.654066Z","end":"2026-06-03T21:51:18.990665Z","steps":["trace[390725502] 'process raft request' (duration: 146.308103ms)","trace[390725502] 'compare' (duration: 189.601188ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:18.99088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:18.654045Z","time spent":"336.750681ms","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T21:51:18.99084Z","caller":"traceutil/trace.go:171","msg":"trace[1456501741] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"301.481713ms","start":"2026-06-03T21:51:18.68929Z","end":"2026-06-03T21:51:18.990771Z","steps":["trace[1456501741] 'read index received' (duration: 111.097767ms)","trace[1456501741] 'applied index is now lower than readState.Index' (duration: 190.379716ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:18.99107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.914319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:18.991149Z","caller":"traceutil/trace.go:171","msg":"trace[216031073] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1280; }","duration":"350.060932ms","start":"2026-06-03T21:51:18.64107Z","end":"2026-06-03T21:51:18.991131Z","steps":["trace[216031073] 'agreement among raft nodes before linearized reading' (duration: 349.851897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:18.991224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.485292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"warn","ts":"2026-06-03T21:51:18.991244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:18.641052Z","time spent":"350.173815ms","remote":"127.0.0.1:47734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-06-03T21:51:18.991285Z","caller":"traceutil/trace.go:171","msg":"trace[639480587] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1280; }","duration":"296.602205ms","start":"2026-06-03T21:51:18.694668Z","end":"2026-06-03T21:51:18.99127Z","steps":["trace[639480587] 'agreement among raft nodes before linearized reading' (duration: 296.41412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:18.991336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.060318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:18.991433Z","caller":"traceutil/trace.go:171","msg":"trace[1828701936] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1280; }","duration":"256.307284ms","start":"2026-06-03T21:51:18.735102Z","end":"2026-06-03T21:51:18.99141Z","steps":["trace[1828701936] 'agreement among raft nodes before linearized reading' (duration: 256.000517ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:18.990749Z","caller":"traceutil/trace.go:171","msg":"trace[1147633631] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"293.631713ms","start":"2026-06-03T21:51:18.697096Z","end":"2026-06-03T21:51:18.990727Z","steps":["trace[1147633631] 'process raft request' (duration: 293.559371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:19.331123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.863624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T21:51:19.331178Z","caller":"traceutil/trace.go:171","msg":"trace[827592343] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1282; }","duration":"155.967086ms","start":"2026-06-03T21:51:19.175199Z","end":"2026-06-03T21:51:19.331166Z","steps":["trace[827592343] 'count revisions from in-memory index tree' (duration: 155.770462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:28.775195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.727838ms","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-06-03T21:51:28.77529Z","caller":"traceutil/trace.go:171","msg":"trace[695111386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1345; }","duration":"795.834501ms","start":"2026-06-03T21:51:27.979433Z","end":"2026-06-03T21:51:28.775268Z","steps":["trace[695111386] 'range keys from in-memory index tree' (duration: 795.46205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:28.775462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091409241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-03T21:51:28.775579Z","caller":"traceutil/trace.go:171","msg":"trace[1613005329] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1345; }","duration":"1.091584645s","start":"2026-06-03T21:51:27.683967Z","end":"2026-06-03T21:51:28.775552Z","steps":["trace[1613005329] 'range keys from in-memory index tree' (duration: 1.091182455s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:28.775638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.683944Z","time spent":"1.091683457s","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-03T21:51:28.775556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:27.979368Z","time spent":"795.957333ms","remote":"127.0.0.1:37878","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-06-03T21:51:28.778308Z","caller":"traceutil/trace.go:171","msg":"trace[2085214304] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"683.705798ms","start":"2026-06-03T21:51:28.094567Z","end":"2026-06-03T21:51:28.778273Z","steps":["trace[2085214304] 'process raft request' (duration: 683.41263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:28.778498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.09454Z","time spent":"683.855632ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T21:51:28.847068Z","caller":"traceutil/trace.go:171","msg":"trace[111850702] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"386.419238ms","start":"2026-06-03T21:51:28.460625Z","end":"2026-06-03T21:51:28.847044Z","steps":["trace[111850702] 'read index received' (duration: 317.868228ms)","trace[111850702] 'applied index is now lower than readState.Index' (duration: 68.54993ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:28.847221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.583542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:28.847221Z","caller":"traceutil/trace.go:171","msg":"trace[25383249] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"435.594292ms","start":"2026-06-03T21:51:28.411597Z","end":"2026-06-03T21:51:28.847191Z","steps":["trace[25383249] 'process raft request' (duration: 435.335006ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:28.84727Z","caller":"traceutil/trace.go:171","msg":"trace[1782879055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"386.645303ms","start":"2026-06-03T21:51:28.46061Z","end":"2026-06-03T21:51:28.847256Z","steps":["trace[1782879055] 'agreement among raft nodes before linearized reading' (duration: 386.558821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:28.847306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.460563Z","time spent":"386.736835ms","remote":"127.0.0.1:44390","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-06-03T21:51:28.847385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.411572Z","time spent":"435.725225ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:29.348116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393798677466550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T21:51:29.84872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393798677466550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T21:51:29.986532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.139516829s","expected-duration":"1s"} {"level":"info","ts":"2026-06-03T21:51:29.98709Z","caller":"traceutil/trace.go:171","msg":"trace[965280772] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1404; }","duration":"1.139937139s","start":"2026-06-03T21:51:28.847131Z","end":"2026-06-03T21:51:29.987068Z","steps":["trace[965280772] 'read index received' (duration: 1.13957022s)","trace[965280772] 'applied index is now lower than readState.Index' (duration: 365.969µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T21:51:29.987384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251514667s","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-06-03T21:51:29.987437Z","caller":"traceutil/trace.go:171","msg":"trace[692146668] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"1.251612749s","start":"2026-06-03T21:51:28.735804Z","end":"2026-06-03T21:51:29.987417Z","steps":["trace[692146668] 'agreement among raft nodes before linearized reading' (duration: 1.251401194s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.987448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29570244s","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-06-03T21:51:29.987477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.735759Z","time spent":"1.251710001s","remote":"127.0.0.1:37878","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":"info","ts":"2026-06-03T21:51:29.987489Z","caller":"traceutil/trace.go:171","msg":"trace[1396187959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"1.296170402s","start":"2026-06-03T21:51:28.691305Z","end":"2026-06-03T21:51:29.987475Z","steps":["trace[1396187959] 'agreement among raft nodes before linearized reading' (duration: 1.296013498s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.987524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.691292Z","time spent":"1.296224693s","remote":"127.0.0.1:37878","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-06-03T21:51:29.987675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392577204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-03T21:51:29.987701Z","caller":"traceutil/trace.go:171","msg":"trace[1576566658] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"1.392637376s","start":"2026-06-03T21:51:28.595054Z","end":"2026-06-03T21:51:29.987691Z","steps":["trace[1576566658] 'agreement among raft nodes before linearized reading' (duration: 1.392556464s)"],"step_count":1} {"level":"info","ts":"2026-06-03T21:51:29.98772Z","caller":"traceutil/trace.go:171","msg":"trace[1066952996] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"1.206673015s","start":"2026-06-03T21:51:28.781034Z","end":"2026-06-03T21:51:29.987707Z","steps":["trace[1066952996] 'process raft request' (duration: 1.205884906s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.987769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138952614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T21:51:29.987794Z","caller":"traceutil/trace.go:171","msg":"trace[1520501443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"1.139010276s","start":"2026-06-03T21:51:28.848777Z","end":"2026-06-03T21:51:29.987787Z","steps":["trace[1520501443] 'agreement among raft nodes before linearized reading' (duration: 1.138975025s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.987816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.848764Z","time spent":"1.139048307s","remote":"127.0.0.1:44404","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-06-03T21:51:29.987726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.595032Z","time spent":"1.392688426s","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-03T21:51:29.987967Z","caller":"traceutil/trace.go:171","msg":"trace[1620138404] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"1.205713103s","start":"2026-06-03T21:51:28.782243Z","end":"2026-06-03T21:51:29.987956Z","steps":["trace[1620138404] 'process raft request' (duration: 1.204787231s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.988029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.658288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-03T21:51:29.98803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.782235Z","time spent":"1.205764414s","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T21:51:29.98782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.781014Z","time spent":"1.206735047s","remote":"127.0.0.1:37878","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-06-03T21:51:29.988057Z","caller":"traceutil/trace.go:171","msg":"trace[1666206367] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1349; }","duration":"304.74168ms","start":"2026-06-03T21:51:29.683307Z","end":"2026-06-03T21:51:29.988049Z","steps":["trace[1666206367] 'agreement among raft nodes before linearized reading' (duration: 304.688729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.988086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.68328Z","time spent":"304.797881ms","remote":"127.0.0.1:37774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-06-03T21:51:29.988164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246571817s","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-06-03T21:51:29.988205Z","caller":"traceutil/trace.go:171","msg":"trace[755395581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"1.246668891s","start":"2026-06-03T21:51:28.741524Z","end":"2026-06-03T21:51:29.988193Z","steps":["trace[755395581] 'agreement among raft nodes before linearized reading' (duration: 1.246548777s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.988236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.741499Z","time spent":"1.246730392s","remote":"127.0.0.1:37878","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-06-03T21:51:29.988353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246976328s","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-06-03T21:51:29.988387Z","caller":"traceutil/trace.go:171","msg":"trace[44494213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"1.247025509s","start":"2026-06-03T21:51:28.741352Z","end":"2026-06-03T21:51:29.988378Z","steps":["trace[44494213] 'agreement among raft nodes before linearized reading' (duration: 1.246927597s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.988413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:28.741342Z","time spent":"1.24706591s","remote":"127.0.0.1:37878","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-06-03T21:51:29.98837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.498797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T21:51:29.988482Z","caller":"traceutil/trace.go:171","msg":"trace[1904060818] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1349; }","duration":"926.710283ms","start":"2026-06-03T21:51:29.061745Z","end":"2026-06-03T21:51:29.988455Z","steps":["trace[1904060818] 'agreement among raft nodes before linearized reading' (duration: 926.345703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.988573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.061712Z","time spent":"926.847236ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-06-03T21:51:29.988556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.293675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-06-03T21:51:29.988647Z","caller":"traceutil/trace.go:171","msg":"trace[1354585866] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"956.440469ms","start":"2026-06-03T21:51:29.032173Z","end":"2026-06-03T21:51:29.988614Z","steps":["trace[1354585866] 'agreement among raft nodes before linearized reading' (duration: 956.178112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T21:51:29.988691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T21:51:29.032004Z","time spent":"956.678394ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "}