{"level":"warn","ts":"2026-05-12T20:01:08.107343Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T20:01:08.107443Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-12T20:01:08.107512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T20:01:08.107521Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-05-12T20:01:08.107543Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T20:01:08.108403Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-05-12T20:01:08.108485Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-12T20:01:08.116507Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.826957ms"} {"level":"info","ts":"2026-05-12T20:01:08.139788Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-05-12T20:01:08.139892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T20:01:08.139919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-05-12T20:01:08.139932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T20:01:08.139946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-05-12T20:01:08.139991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-05-12T20:01:08.158427Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T20:01:08.159871Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T20:01:08.16872Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-12T20:01:08.172336Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T20:01:08.175653Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T20:01:08.17596Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T20:01:08.175971Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T20:01:08.175568Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T20:01:08.178954Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T20:01:08.179069Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-05-12T20:01:08.179141Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-05-12T20:01:08.179295Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T20:01:08.179344Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T20:01:08.179479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-05-12T20:01:08.179632Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-05-12T20:01:09.041063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T20:01:09.041127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T20:01:09.041174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-05-12T20:01:09.041195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-05-12T20:01:09.041207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-05-12T20:01:09.041223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-05-12T20:01:09.041237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-05-12T20:01:09.042178Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T20:01:09.043297Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T20:01:09.043594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T20:01:09.043664Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T20:01:09.043799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T20:01:09.043832Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T20:01:09.044158Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T20:01:09.044521Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T20:01:09.044576Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T20:01:09.052448Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-05-12T20:01:09.054055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-12T20:01:39.23674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.514889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:01:39.236838Z","caller":"traceutil/trace.go:171","msg":"trace[1994359837] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"240.383349ms","start":"2026-05-12T20:01:38.996442Z","end":"2026-05-12T20:01:39.236826Z","steps":["trace[1994359837] 'read index received' (duration: 126.240302ms)","trace[1994359837] 'applied index is now lower than readState.Index' (duration: 114.142107ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:01:39.236878Z","caller":"traceutil/trace.go:171","msg":"trace[1876810055] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"360.08653ms","start":"2026-05-12T20:01:38.876765Z","end":"2026-05-12T20:01:39.236851Z","steps":["trace[1876810055] 'process raft request' (duration: 245.994084ms)","trace[1876810055] 'compare' (duration: 113.417658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:01:39.236926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.489642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T20:01:39.236946Z","caller":"traceutil/trace.go:171","msg":"trace[1303787891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"240.556144ms","start":"2026-05-12T20:01:38.996384Z","end":"2026-05-12T20:01:39.23694Z","steps":["trace[1303787891] 'agreement among raft nodes before linearized reading' (duration: 240.512052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.236992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:38.876738Z","time spent":"360.195063ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:01:39.807462Z","caller":"traceutil/trace.go:171","msg":"trace[1175174652] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"397.733673ms","start":"2026-05-12T20:01:39.409711Z","end":"2026-05-12T20:01:39.807445Z","steps":["trace[1175174652] 'process raft request' (duration: 397.660031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.807553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.40969Z","time spent":"397.822637ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:01:39.816404Z","caller":"traceutil/trace.go:171","msg":"trace[1195545197] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"398.868176ms","start":"2026-05-12T20:01:39.417494Z","end":"2026-05-12T20:01:39.816363Z","steps":["trace[1195545197] 'process raft request' (duration: 398.61903ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:39.816563Z","caller":"traceutil/trace.go:171","msg":"trace[1784150954] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"393.792315ms","start":"2026-05-12T20:01:39.422678Z","end":"2026-05-12T20:01:39.81647Z","steps":["trace[1784150954] 'process raft request' (duration: 393.60285ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:39.816673Z","caller":"traceutil/trace.go:171","msg":"trace[348963200] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"393.012242ms","start":"2026-05-12T20:01:39.423623Z","end":"2026-05-12T20:01:39.816636Z","steps":["trace[348963200] 'process raft request' (duration: 392.827018ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:39.816636Z","caller":"traceutil/trace.go:171","msg":"trace[897999695] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"393.873298ms","start":"2026-05-12T20:01:39.422687Z","end":"2026-05-12T20:01:39.81656Z","steps":["trace[897999695] 'process raft request' (duration: 393.710703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.816573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.417476Z","time spent":"399.02144ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:01:39.816784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.423615Z","time spent":"393.118436ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:01:39.816824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.422677Z","time spent":"394.068912ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:01:39.816804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.422665Z","time spent":"394.010591ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:01:39.817152Z","caller":"traceutil/trace.go:171","msg":"trace[1048990527] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"393.476707ms","start":"2026-05-12T20:01:39.423659Z","end":"2026-05-12T20:01:39.817135Z","steps":["trace[1048990527] 'process raft request' (duration: 392.846529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.817444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.423655Z","time spent":"393.760455ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:01:39.817157Z","caller":"traceutil/trace.go:171","msg":"trace[1614837086] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"398.895916ms","start":"2026-05-12T20:01:39.417515Z","end":"2026-05-12T20:01:39.816411Z","steps":["trace[1614837086] 'process raft request' (duration: 398.712172ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:39.817286Z","caller":"traceutil/trace.go:171","msg":"trace[82381540] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"378.047048ms","start":"2026-05-12T20:01:39.439225Z","end":"2026-05-12T20:01:39.817272Z","steps":["trace[82381540] 'process raft request' (duration: 377.919205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.817614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.439214Z","time spent":"378.373257ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:01:39.817236Z","caller":"traceutil/trace.go:171","msg":"trace[2029287051] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"386.751609ms","start":"2026-05-12T20:01:39.430464Z","end":"2026-05-12T20:01:39.817216Z","steps":["trace[2029287051] 'process raft request' (duration: 386.626946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.817698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.430449Z","time spent":"387.230103ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:01:39.817746Z","caller":"traceutil/trace.go:171","msg":"trace[286249366] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"373.268466ms","start":"2026-05-12T20:01:39.44447Z","end":"2026-05-12T20:01:39.817739Z","steps":["trace[286249366] 'process raft request' (duration: 372.728741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.817778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.444462Z","time spent":"373.302806ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:01:39.817956Z","caller":"traceutil/trace.go:171","msg":"trace[607703989] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"355.212806ms","start":"2026-05-12T20:01:39.462737Z","end":"2026-05-12T20:01:39.81795Z","steps":["trace[607703989] 'process raft request' (duration: 354.742942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.817991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.462716Z","time spent":"355.258376ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:01:39.818212Z","caller":"traceutil/trace.go:171","msg":"trace[1262262045] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"248.904575ms","start":"2026-05-12T20:01:39.569301Z","end":"2026-05-12T20:01:39.818205Z","steps":["trace[1262262045] 'process raft request' (duration: 248.676039ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:39.818223Z","caller":"traceutil/trace.go:171","msg":"trace[396204316] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:528; }","duration":"384.296301ms","start":"2026-05-12T20:01:39.433909Z","end":"2026-05-12T20:01:39.818205Z","steps":["trace[396204316] 'read index received' (duration: 373.823031ms)","trace[396204316] 'applied index is now lower than readState.Index' (duration: 10.47226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:01:39.818361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.445865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:01:39.818389Z","caller":"traceutil/trace.go:171","msg":"trace[1940603813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"384.499898ms","start":"2026-05-12T20:01:39.433882Z","end":"2026-05-12T20:01:39.818382Z","steps":["trace[1940603813] 'agreement among raft nodes before linearized reading' (duration: 384.389855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:39.818408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.43387Z","time spent":"384.533718ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:01:39.81837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.417505Z","time spent":"400.008998ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:01:39.823859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.53425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T20:01:39.823929Z","caller":"traceutil/trace.go:171","msg":"trace[650192855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"241.710845ms","start":"2026-05-12T20:01:39.582187Z","end":"2026-05-12T20:01:39.823898Z","steps":["trace[650192855] 'agreement among raft nodes before linearized reading' (duration: 241.602242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:46.317537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.49412ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:01:46.31768Z","caller":"traceutil/trace.go:171","msg":"trace[468159632] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:620; }","duration":"551.655585ms","start":"2026-05-12T20:01:45.766003Z","end":"2026-05-12T20:01:46.317659Z","steps":["trace[468159632] 'range keys from in-memory index tree' (duration: 551.47493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:46.318018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.787943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T20:01:46.3181Z","caller":"traceutil/trace.go:171","msg":"trace[1682252248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"456.924487ms","start":"2026-05-12T20:01:45.861157Z","end":"2026-05-12T20:01:46.318081Z","steps":["trace[1682252248] 'range keys from in-memory index tree' (duration: 456.311189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:46.318131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:45.861134Z","time spent":"456.989157ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T20:01:46.35534Z","caller":"traceutil/trace.go:171","msg":"trace[994886542] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"335.391285ms","start":"2026-05-12T20:01:46.019931Z","end":"2026-05-12T20:01:46.355322Z","steps":["trace[994886542] 'read index received' (duration: 334.803459ms)","trace[994886542] 'applied index is now lower than readState.Index' (duration: 587.156µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:01:46.355484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.535169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-758pj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-12T20:01:46.355509Z","caller":"traceutil/trace.go:171","msg":"trace[1658267130] range","detail":"{range_begin:/registry/pods/kube-system/cilium-758pj; range_end:; response_count:1; response_revision:621; }","duration":"335.653632ms","start":"2026-05-12T20:01:46.019847Z","end":"2026-05-12T20:01:46.3555Z","steps":["trace[1658267130] 'agreement among raft nodes before linearized reading' (duration: 335.542449ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:46.355492Z","caller":"traceutil/trace.go:171","msg":"trace[1299512811] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"342.507202ms","start":"2026-05-12T20:01:46.012944Z","end":"2026-05-12T20:01:46.355451Z","steps":["trace[1299512811] 'process raft request' (duration: 341.902145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:46.355533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:46.019831Z","time spent":"335.696694ms","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-758pj\" "} {"level":"warn","ts":"2026-05-12T20:01:46.355662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:46.012911Z","time spent":"342.654576ms","remote":"127.0.0.1:33598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:01:46.93925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020364893860120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:01:47.320961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.340649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:01:47.32106Z","caller":"traceutil/trace.go:171","msg":"trace[1909656243] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"945.703357ms","start":"2026-05-12T20:01:46.375339Z","end":"2026-05-12T20:01:47.321043Z","steps":["trace[1909656243] 'process raft request' (duration: 595.205143ms)","trace[1909656243] 'compare' (duration: 350.212746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:01:47.321117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:46.375323Z","time spent":"945.768758ms","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:01:47.36274Z","caller":"traceutil/trace.go:171","msg":"trace[149744973] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"924.455067ms","start":"2026-05-12T20:01:46.438235Z","end":"2026-05-12T20:01:47.36269Z","steps":["trace[149744973] 'read index received' (duration: 532.310408ms)","trace[149744973] 'applied index is now lower than readState.Index' (duration: 392.143849ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:01:47.362854Z","caller":"traceutil/trace.go:171","msg":"trace[1335321860] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"947.939137ms","start":"2026-05-12T20:01:46.414902Z","end":"2026-05-12T20:01:47.362841Z","steps":["trace[1335321860] 'process raft request' (duration: 947.675889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:47.363022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.779845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T20:01:47.363051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:46.414876Z","time spent":"948.0206ms","remote":"127.0.0.1:36866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:01:47.363118Z","caller":"traceutil/trace.go:171","msg":"trace[1002712681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"924.888809ms","start":"2026-05-12T20:01:46.438182Z","end":"2026-05-12T20:01:47.363071Z","steps":["trace[1002712681] 'agreement among raft nodes before linearized reading' (duration: 924.705583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:47.363164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:46.43816Z","time spent":"924.993311ms","remote":"127.0.0.1:33488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:01:47.387176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.091846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:01:47.387273Z","caller":"traceutil/trace.go:171","msg":"trace[980856471] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:624; }","duration":"100.24419ms","start":"2026-05-12T20:01:47.287007Z","end":"2026-05-12T20:01:47.387251Z","steps":["trace[980856471] 'agreement among raft nodes before linearized reading' (duration: 100.108256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:47.387564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.382489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-12T20:01:47.387622Z","caller":"traceutil/trace.go:171","msg":"trace[295167065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"111.460202ms","start":"2026-05-12T20:01:47.276147Z","end":"2026-05-12T20:01:47.387607Z","steps":["trace[295167065] 'agreement among raft nodes before linearized reading' (duration: 111.291137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:47.387973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.988567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T20:01:47.388052Z","caller":"traceutil/trace.go:171","msg":"trace[2139561362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"121.10293ms","start":"2026-05-12T20:01:47.266933Z","end":"2026-05-12T20:01:47.388036Z","steps":["trace[2139561362] 'agreement among raft nodes before linearized reading' (duration: 120.917495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:47.388263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.130714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T20:01:47.388298Z","caller":"traceutil/trace.go:171","msg":"trace[831719912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"496.214167ms","start":"2026-05-12T20:01:46.892075Z","end":"2026-05-12T20:01:47.388289Z","steps":["trace[831719912] 'agreement among raft nodes before linearized reading' (duration: 496.127254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:47.388322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:46.892055Z","time spent":"496.260148ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T20:02:19.288588Z","caller":"traceutil/trace.go:171","msg":"trace[2045138890] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"101.943366ms","start":"2026-05-12T20:02:19.186615Z","end":"2026-05-12T20:02:19.288559Z","steps":["trace[2045138890] 'read index received' (duration: 101.72965ms)","trace[2045138890] 'applied index is now lower than readState.Index' (duration: 211.756µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:19.288644Z","caller":"traceutil/trace.go:171","msg":"trace[401595316] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"106.369554ms","start":"2026-05-12T20:02:19.182251Z","end":"2026-05-12T20:02:19.28862Z","steps":["trace[401595316] 'process raft request' (duration: 106.150127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:19.288845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.214454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-jpht8\" ","response":"range_response_count:1 size:5071"} {"level":"info","ts":"2026-05-12T20:02:19.289781Z","caller":"traceutil/trace.go:171","msg":"trace[993971838] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-jpht8; range_end:; response_count:1; response_revision:937; }","duration":"103.148432ms","start":"2026-05-12T20:02:19.186598Z","end":"2026-05-12T20:02:19.289746Z","steps":["trace[993971838] 'agreement among raft nodes before linearized reading' (duration: 102.087331ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:34.821302Z","caller":"traceutil/trace.go:171","msg":"trace[297455825] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"156.003794ms","start":"2026-05-12T20:02:34.665274Z","end":"2026-05-12T20:02:34.821277Z","steps":["trace[297455825] 'process raft request' (duration: 155.853999ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:38.945249Z","caller":"traceutil/trace.go:171","msg":"trace[945234334] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"106.539988ms","start":"2026-05-12T20:02:38.838681Z","end":"2026-05-12T20:02:38.945221Z","steps":["trace[945234334] 'process raft request' (duration: 106.358224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:39.619094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.719342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:02:39.619266Z","caller":"traceutil/trace.go:171","msg":"trace[297703156] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1043; }","duration":"182.95997ms","start":"2026-05-12T20:02:39.436294Z","end":"2026-05-12T20:02:39.619254Z","steps":["trace[297703156] 'read index received' (duration: 130.240552ms)","trace[297703156] 'applied index is now lower than readState.Index' (duration: 52.718758ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:39.619568Z","caller":"traceutil/trace.go:171","msg":"trace[622611348] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"258.065283ms","start":"2026-05-12T20:02:39.36149Z","end":"2026-05-12T20:02:39.619555Z","steps":["trace[622611348] 'process raft request' (duration: 257.685312ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:39.619586Z","caller":"traceutil/trace.go:171","msg":"trace[1130433841] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"670.921045ms","start":"2026-05-12T20:02:38.948634Z","end":"2026-05-12T20:02:39.619555Z","steps":["trace[1130433841] 'process raft request' (duration: 310.655108ms)","trace[1130433841] 'compare' (duration: 359.371662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:39.619752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:38.948613Z","time spent":"671.059059ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:02:39.61985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.561418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:39.619881Z","caller":"traceutil/trace.go:171","msg":"trace[705656025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"183.619829ms","start":"2026-05-12T20:02:39.436252Z","end":"2026-05-12T20:02:39.619872Z","steps":["trace[705656025] 'agreement among raft nodes before linearized reading' (duration: 183.573828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:40.204041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020364893860965,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T20:02:40.255664Z","caller":"traceutil/trace.go:171","msg":"trace[1766574296] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"552.411561ms","start":"2026-05-12T20:02:39.703225Z","end":"2026-05-12T20:02:40.255636Z","steps":["trace[1766574296] 'read index received' (duration: 546.979556ms)","trace[1766574296] 'applied index is now lower than readState.Index' (duration: 5.430695ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:40.256429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.665601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T20:02:40.256509Z","caller":"traceutil/trace.go:171","msg":"trace[1264153167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"550.754924ms","start":"2026-05-12T20:02:39.705737Z","end":"2026-05-12T20:02:40.256492Z","steps":["trace[1264153167] 'agreement among raft nodes before linearized reading' (duration: 550.559888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:40.256551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:39.705715Z","time spent":"550.826726ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T20:02:40.256582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.820163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T20:02:40.25663Z","caller":"traceutil/trace.go:171","msg":"trace[695407794] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1010; }","duration":"418.917755ms","start":"2026-05-12T20:02:39.8377Z","end":"2026-05-12T20:02:40.256617Z","steps":["trace[695407794] 'agreement among raft nodes before linearized reading' (duration: 418.811032ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:40.256664Z","caller":"traceutil/trace.go:171","msg":"trace[1913728284] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"598.933211ms","start":"2026-05-12T20:02:39.657695Z","end":"2026-05-12T20:02:40.256628Z","steps":["trace[1913728284] 'process raft request' (duration: 592.674221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:40.256674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:39.837682Z","time spent":"418.981388ms","remote":"127.0.0.1:37058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:02:40.256704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.463622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T20:02:40.256793Z","caller":"traceutil/trace.go:171","msg":"trace[1610832298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1010; }","duration":"553.588896ms","start":"2026-05-12T20:02:39.703174Z","end":"2026-05-12T20:02:40.256763Z","steps":["trace[1610832298] 'agreement among raft nodes before linearized reading' (duration: 553.336088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:40.256798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:39.657677Z","time spent":"599.050655ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:02:40.256847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:39.703154Z","time spent":"553.679938ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T20:02:59.671109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.895487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:59.671208Z","caller":"traceutil/trace.go:171","msg":"trace[483167112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"234.060891ms","start":"2026-05-12T20:02:59.437128Z","end":"2026-05-12T20:02:59.671188Z","steps":["trace[483167112] 'range keys from in-memory index tree' (duration: 233.807194ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:00.199493Z","caller":"traceutil/trace.go:171","msg":"trace[1224723516] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"403.890573ms","start":"2026-05-12T20:02:59.795581Z","end":"2026-05-12T20:03:00.199472Z","steps":["trace[1224723516] 'read index received' (duration: 403.691698ms)","trace[1224723516] 'applied index is now lower than readState.Index' (duration: 198.175µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:00.199659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.060078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T20:03:00.199659Z","caller":"traceutil/trace.go:171","msg":"trace[1139035843] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"487.020769ms","start":"2026-05-12T20:02:59.712571Z","end":"2026-05-12T20:03:00.199591Z","steps":["trace[1139035843] 'process raft request' (duration: 486.774201ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:00.199688Z","caller":"traceutil/trace.go:171","msg":"trace[868716687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"404.13433ms","start":"2026-05-12T20:02:59.795547Z","end":"2026-05-12T20:03:00.199681Z","steps":["trace[868716687] 'agreement among raft nodes before linearized reading' (duration: 404.011657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:00.199719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:59.795522Z","time spent":"404.190552ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T20:03:00.199806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:59.712549Z","time spent":"487.183443ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:01.166085Z","caller":"traceutil/trace.go:171","msg":"trace[1079729584] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"111.930374ms","start":"2026-05-12T20:03:01.054114Z","end":"2026-05-12T20:03:01.166044Z","steps":["trace[1079729584] 'process raft request' (duration: 111.740798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:01.80135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:01.801451Z","caller":"traceutil/trace.go:171","msg":"trace[834921470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"365.373423ms","start":"2026-05-12T20:03:01.436035Z","end":"2026-05-12T20:03:01.801409Z","steps":["trace[834921470] 'range keys from in-memory index tree' (duration: 365.168518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:01.801493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:01.436018Z","time spent":"365.465397ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T20:03:02.268201Z","caller":"traceutil/trace.go:171","msg":"trace[488555495] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"114.691753ms","start":"2026-05-12T20:03:02.153484Z","end":"2026-05-12T20:03:02.268176Z","steps":["trace[488555495] 'read index received' (duration: 110.198184ms)","trace[488555495] 'applied index is now lower than readState.Index' (duration: 4.492489ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:02.26821Z","caller":"traceutil/trace.go:171","msg":"trace[898701019] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"125.610377ms","start":"2026-05-12T20:03:02.142571Z","end":"2026-05-12T20:03:02.268182Z","steps":["trace[898701019] 'process raft request' (duration: 121.117579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:02.26839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.878849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:02.268476Z","caller":"traceutil/trace.go:171","msg":"trace[1787361695] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1080; }","duration":"115.015822ms","start":"2026-05-12T20:03:02.153444Z","end":"2026-05-12T20:03:02.26846Z","steps":["trace[1787361695] 'agreement among raft nodes before linearized reading' (duration: 114.817907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:02.759362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.291299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T20:03:02.759476Z","caller":"traceutil/trace.go:171","msg":"trace[1780430985] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1085; }","duration":"209.463543ms","start":"2026-05-12T20:03:02.549989Z","end":"2026-05-12T20:03:02.759452Z","steps":["trace[1780430985] 'count revisions from in-memory index tree' (duration: 209.170795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:02.759435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.752922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T20:03:02.759604Z","caller":"traceutil/trace.go:171","msg":"trace[1213462351] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1085; }","duration":"260.274177ms","start":"2026-05-12T20:03:02.499311Z","end":"2026-05-12T20:03:02.759585Z","steps":["trace[1213462351] 'count revisions from in-memory index tree' (duration: 259.662579ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:02.772766Z","caller":"traceutil/trace.go:171","msg":"trace[1726295622] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"190.289199ms","start":"2026-05-12T20:03:02.582453Z","end":"2026-05-12T20:03:02.772742Z","steps":["trace[1726295622] 'process raft request' (duration: 190.158996ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:04.572864Z","caller":"traceutil/trace.go:171","msg":"trace[1137656641] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"101.413441ms","start":"2026-05-12T20:03:04.471427Z","end":"2026-05-12T20:03:04.57284Z","steps":["trace[1137656641] 'process raft request' (duration: 101.248076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:17.229217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.788101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:03:17.229339Z","caller":"traceutil/trace.go:171","msg":"trace[364850565] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"647.789615ms","start":"2026-05-12T20:03:16.581529Z","end":"2026-05-12T20:03:17.229319Z","steps":["trace[364850565] 'read index received' (duration: 360.786439ms)","trace[364850565] 'applied index is now lower than readState.Index' (duration: 287.001716ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:17.229598Z","caller":"traceutil/trace.go:171","msg":"trace[2086862343] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"741.97716ms","start":"2026-05-12T20:03:16.487601Z","end":"2026-05-12T20:03:17.229578Z","steps":["trace[2086862343] 'process raft request' (duration: 454.743937ms)","trace[2086862343] 'compare' (duration: 286.693838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:17.229692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:16.487579Z","time spent":"742.063233ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:17.229838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.865963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T20:03:17.229863Z","caller":"traceutil/trace.go:171","msg":"trace[106338276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1181; }","duration":"600.914583ms","start":"2026-05-12T20:03:16.628941Z","end":"2026-05-12T20:03:17.229856Z","steps":["trace[106338276] 'agreement among raft nodes before linearized reading' (duration: 600.826131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:17.229879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:16.628928Z","time spent":"600.948344ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T20:03:17.229923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.389441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T20:03:17.229985Z","caller":"traceutil/trace.go:171","msg":"trace[789015268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1181; }","duration":"648.478665ms","start":"2026-05-12T20:03:16.581489Z","end":"2026-05-12T20:03:17.229967Z","steps":["trace[789015268] 'agreement among raft nodes before linearized reading' (duration: 648.278269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:17.230048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:16.581471Z","time spent":"648.565207ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T20:03:30.54984Z","caller":"traceutil/trace.go:171","msg":"trace[2003373679] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"112.030199ms","start":"2026-05-12T20:03:30.437783Z","end":"2026-05-12T20:03:30.549813Z","steps":["trace[2003373679] 'read index received' (duration: 111.883285ms)","trace[2003373679] 'applied index is now lower than readState.Index' (duration: 145.944µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:30.549959Z","caller":"traceutil/trace.go:171","msg":"trace[94349439] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"120.196008ms","start":"2026-05-12T20:03:30.429733Z","end":"2026-05-12T20:03:30.549929Z","steps":["trace[94349439] 'process raft request' (duration: 119.930921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:30.55004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.236985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:30.550099Z","caller":"traceutil/trace.go:171","msg":"trace[684830257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"112.335688ms","start":"2026-05-12T20:03:30.437749Z","end":"2026-05-12T20:03:30.550085Z","steps":["trace[684830257] 'agreement among raft nodes before linearized reading' (duration: 112.189003ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:33.321096Z","caller":"traceutil/trace.go:171","msg":"trace[1080425132] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"210.658003ms","start":"2026-05-12T20:03:33.110398Z","end":"2026-05-12T20:03:33.321056Z","steps":["trace[1080425132] 'process raft request' (duration: 210.436477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:33.92412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.923356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:03:33.924508Z","caller":"traceutil/trace.go:171","msg":"trace[168859794] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"530.114535ms","start":"2026-05-12T20:03:33.394375Z","end":"2026-05-12T20:03:33.92449Z","steps":["trace[168859794] 'read index received' (duration: 240.964943ms)","trace[168859794] 'applied index is now lower than readState.Index' (duration: 289.148312ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:33.924532Z","caller":"traceutil/trace.go:171","msg":"trace[1039459654] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"531.271147ms","start":"2026-05-12T20:03:33.393241Z","end":"2026-05-12T20:03:33.924512Z","steps":["trace[1039459654] 'process raft request' (duration: 241.883858ms)","trace[1039459654] 'compare' (duration: 288.519614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:33.924685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.39322Z","time spent":"531.423491ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:33.924869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.464805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:33.924948Z","caller":"traceutil/trace.go:171","msg":"trace[755394839] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1252; }","duration":"530.581348ms","start":"2026-05-12T20:03:33.394346Z","end":"2026-05-12T20:03:33.924928Z","steps":["trace[755394839] 'agreement among raft nodes before linearized reading' (duration: 530.199737ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:33.924958Z","caller":"traceutil/trace.go:171","msg":"trace[932797832] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"530.426733ms","start":"2026-05-12T20:03:33.394517Z","end":"2026-05-12T20:03:33.924944Z","steps":["trace[932797832] 'process raft request' (duration: 529.722434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:33.924992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.394311Z","time spent":"530.669749ms","remote":"127.0.0.1:58744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:03:33.925027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.394487Z","time spent":"530.506936ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:33.925028Z","caller":"traceutil/trace.go:171","msg":"trace[2077392038] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"530.251448ms","start":"2026-05-12T20:03:33.394733Z","end":"2026-05-12T20:03:33.924984Z","steps":["trace[2077392038] 'process raft request' (duration: 530.122834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:33.925109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.394721Z","time spent":"530.346851ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:33.925113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.156628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T20:03:33.925103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.104283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-z8lxd\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-05-12T20:03:33.925176Z","caller":"traceutil/trace.go:171","msg":"trace[667813424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"489.23714ms","start":"2026-05-12T20:03:33.435921Z","end":"2026-05-12T20:03:33.925158Z","steps":["trace[667813424] 'agreement among raft nodes before linearized reading' (duration: 489.160707ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:33.92518Z","caller":"traceutil/trace.go:171","msg":"trace[2130551486] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-z8lxd; range_end:; response_count:1; response_revision:1253; }","duration":"495.227127ms","start":"2026-05-12T20:03:33.429939Z","end":"2026-05-12T20:03:33.925166Z","steps":["trace[2130551486] 'agreement among raft nodes before linearized reading' (duration: 495.012111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:33.925211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.43591Z","time spent":"489.293851ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:03:33.925213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.429921Z","time spent":"495.284008ms","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11079,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-z8lxd\" "} {"level":"info","ts":"2026-05-12T20:03:34.332681Z","caller":"traceutil/trace.go:171","msg":"trace[365318127] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"397.407995ms","start":"2026-05-12T20:03:33.935246Z","end":"2026-05-12T20:03:34.332654Z","steps":["trace[365318127] 'process raft request' (duration: 397.24096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:34.332832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.935225Z","time spent":"397.528919ms","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:35.936854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020364893861560,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T20:03:36.328651Z","caller":"traceutil/trace.go:171","msg":"trace[238133817] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"892.448657ms","start":"2026-05-12T20:03:35.436183Z","end":"2026-05-12T20:03:36.328631Z","steps":["trace[238133817] 'read index received' (duration: 892.364815ms)","trace[238133817] 'applied index is now lower than readState.Index' (duration: 83.182µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:36.328704Z","caller":"traceutil/trace.go:171","msg":"trace[832299350] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"931.164632ms","start":"2026-05-12T20:03:35.397518Z","end":"2026-05-12T20:03:36.328683Z","steps":["trace[832299350] 'process raft request' (duration: 931.002687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:36.328765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.579921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:36.328791Z","caller":"traceutil/trace.go:171","msg":"trace[1977199518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"892.655354ms","start":"2026-05-12T20:03:35.436129Z","end":"2026-05-12T20:03:36.328784Z","steps":["trace[1977199518] 'agreement among raft nodes before linearized reading' (duration: 892.600432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:36.328816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:35.436101Z","time spent":"892.710324ms","remote":"127.0.0.1:33488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:03:36.328803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:35.397494Z","time spent":"931.248816ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:36.413904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.228539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:36.413984Z","caller":"traceutil/trace.go:171","msg":"trace[748011906] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1261; }","duration":"610.351074ms","start":"2026-05-12T20:03:35.803614Z","end":"2026-05-12T20:03:36.413965Z","steps":["trace[748011906] 'agreement among raft nodes before linearized reading' (duration: 610.203169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:36.414028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:35.803596Z","time spent":"610.421975ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:03:36.414031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.064344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T20:03:36.414151Z","caller":"traceutil/trace.go:171","msg":"trace[1658545304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1261; }","duration":"484.222128ms","start":"2026-05-12T20:03:35.929905Z","end":"2026-05-12T20:03:36.414127Z","steps":["trace[1658545304] 'agreement among raft nodes before linearized reading' (duration: 483.963001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:36.414205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:35.929891Z","time spent":"484.299491ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T20:03:36.414369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.712622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T20:03:36.414402Z","caller":"traceutil/trace.go:171","msg":"trace[1009785701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1261; }","duration":"484.745443ms","start":"2026-05-12T20:03:35.929646Z","end":"2026-05-12T20:03:36.414392Z","steps":["trace[1009785701] 'agreement among raft nodes before linearized reading' (duration: 484.589018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:36.414428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:35.92959Z","time spent":"484.831595ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T20:03:36.414588Z","caller":"traceutil/trace.go:171","msg":"trace[1520829557] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"348.500186ms","start":"2026-05-12T20:03:36.066065Z","end":"2026-05-12T20:03:36.414565Z","steps":["trace[1520829557] 'process raft request' (duration: 346.157091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:36.414647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.024201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T20:03:36.414675Z","caller":"traceutil/trace.go:171","msg":"trace[2144810893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"485.051942ms","start":"2026-05-12T20:03:35.929615Z","end":"2026-05-12T20:03:36.414666Z","steps":["trace[2144810893] 'agreement among raft nodes before linearized reading' (duration: 484.97489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:36.414703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:35.929548Z","time spent":"485.149724ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T20:03:36.414699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:36.06604Z","time spent":"348.589238ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:36.60277Z","caller":"traceutil/trace.go:171","msg":"trace[1903731335] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"183.662777ms","start":"2026-05-12T20:03:36.419091Z","end":"2026-05-12T20:03:36.602753Z","steps":["trace[1903731335] 'process raft request' (duration: 101.466854ms)","trace[1903731335] 'compare' (duration: 82.072489ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:36.602954Z","caller":"traceutil/trace.go:171","msg":"trace[1355285726] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"183.855552ms","start":"2026-05-12T20:03:36.419091Z","end":"2026-05-12T20:03:36.602947Z","steps":["trace[1355285726] 'process raft request' (duration: 183.638226ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:36.603458Z","caller":"traceutil/trace.go:171","msg":"trace[1119656506] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1310; }","duration":"165.753255ms","start":"2026-05-12T20:03:36.437683Z","end":"2026-05-12T20:03:36.603436Z","steps":["trace[1119656506] 'read index received' (duration: 82.856582ms)","trace[1119656506] 'applied index is now lower than readState.Index' (duration: 82.895252ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:36.603565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.865708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:36.603604Z","caller":"traceutil/trace.go:171","msg":"trace[1607390449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"165.94906ms","start":"2026-05-12T20:03:36.437644Z","end":"2026-05-12T20:03:36.603593Z","steps":["trace[1607390449] 'agreement among raft nodes before linearized reading' (duration: 165.876868ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:36.603598Z","caller":"traceutil/trace.go:171","msg":"trace[1901587083] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"184.107169ms","start":"2026-05-12T20:03:36.419458Z","end":"2026-05-12T20:03:36.603565Z","steps":["trace[1901587083] 'process raft request' (duration: 183.456031ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:36.781834Z","caller":"traceutil/trace.go:171","msg":"trace[1865340017] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"114.5682ms","start":"2026-05-12T20:03:36.667239Z","end":"2026-05-12T20:03:36.781808Z","steps":["trace[1865340017] 'process raft request' (duration: 93.354035ms)","trace[1865340017] 'compare' (duration: 21.106082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:37.670663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.968399ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-12T20:03:37.670791Z","caller":"traceutil/trace.go:171","msg":"trace[6012468] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"325.817469ms","start":"2026-05-12T20:03:37.344952Z","end":"2026-05-12T20:03:37.670769Z","steps":["trace[6012468] 'read index received' (duration: 62.690306ms)","trace[6012468] 'applied index is now lower than readState.Index' (duration: 263.125583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:37.670879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.919394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:37.670912Z","caller":"traceutil/trace.go:171","msg":"trace[2123013116] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1266; }","duration":"325.993695ms","start":"2026-05-12T20:03:37.344903Z","end":"2026-05-12T20:03:37.670897Z","steps":["trace[2123013116] 'agreement among raft nodes before linearized reading' (duration: 325.925782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:37.670969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:37.344883Z","time spent":"326.076098ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-05-12T20:03:37.6721Z","caller":"traceutil/trace.go:171","msg":"trace[827553440] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"154.999874ms","start":"2026-05-12T20:03:37.517078Z","end":"2026-05-12T20:03:37.672078Z","steps":["trace[827553440] 'process raft request' (duration: 153.847821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:37.794439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.018287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:37.794502Z","caller":"traceutil/trace.go:171","msg":"trace[131385950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"356.171091ms","start":"2026-05-12T20:03:37.438312Z","end":"2026-05-12T20:03:37.794483Z","steps":["trace[131385950] 'agreement among raft nodes before linearized reading' (duration: 233.73695ms)","trace[131385950] 'range keys from in-memory index tree' (duration: 122.338248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:37.794538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:37.438294Z","time spent":"356.235843ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T20:03:48.565233Z","caller":"traceutil/trace.go:171","msg":"trace[1254252961] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1399; }","duration":"175.364934ms","start":"2026-05-12T20:03:48.389844Z","end":"2026-05-12T20:03:48.565209Z","steps":["trace[1254252961] 'read index received' (duration: 152.879334ms)","trace[1254252961] 'applied index is now lower than readState.Index' (duration: 22.48445ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:48.565482Z","caller":"traceutil/trace.go:171","msg":"trace[1474790932] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"182.818354ms","start":"2026-05-12T20:03:48.38265Z","end":"2026-05-12T20:03:48.565468Z","steps":["trace[1474790932] 'process raft request' (duration: 160.067545ms)","trace[1474790932] 'compare' (duration: 22.357177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:48.565734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.862968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-12T20:03:48.565761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.898204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:48.565803Z","caller":"traceutil/trace.go:171","msg":"trace[203917137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1348; }","duration":"175.970152ms","start":"2026-05-12T20:03:48.389815Z","end":"2026-05-12T20:03:48.565785Z","steps":["trace[203917137] 'agreement among raft nodes before linearized reading' (duration: 175.789977ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:48.565856Z","caller":"traceutil/trace.go:171","msg":"trace[1019507580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"128.041488ms","start":"2026-05-12T20:03:48.437789Z","end":"2026-05-12T20:03:48.565831Z","steps":["trace[1019507580] 'agreement among raft nodes before linearized reading' (duration: 127.868373ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:49.048337Z","caller":"traceutil/trace.go:171","msg":"trace[828640776] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"388.674351ms","start":"2026-05-12T20:03:48.659636Z","end":"2026-05-12T20:03:49.04831Z","steps":["trace[828640776] 'read index received' (duration: 388.521967ms)","trace[828640776] 'applied index is now lower than readState.Index' (duration: 151.074µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:49.048482Z","caller":"traceutil/trace.go:171","msg":"trace[2000973760] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"479.182437ms","start":"2026-05-12T20:03:48.569247Z","end":"2026-05-12T20:03:49.048429Z","steps":["trace[2000973760] 'process raft request' (duration: 478.92033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:49.048558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.894647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T20:03:49.048604Z","caller":"traceutil/trace.go:171","msg":"trace[1455308588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"388.99667ms","start":"2026-05-12T20:03:48.659593Z","end":"2026-05-12T20:03:49.048589Z","steps":["trace[1455308588] 'agreement among raft nodes before linearized reading' (duration: 388.834275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:49.048642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:48.569231Z","time spent":"479.330652ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:49.048657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:48.659572Z","time spent":"389.073072ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T20:03:49.162089Z","caller":"traceutil/trace.go:171","msg":"trace[197219026] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"109.725264ms","start":"2026-05-12T20:03:49.052341Z","end":"2026-05-12T20:03:49.162066Z","steps":["trace[197219026] 'process raft request' (duration: 109.680093ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:49.162244Z","caller":"traceutil/trace.go:171","msg":"trace[779103369] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1401; }","duration":"113.824279ms","start":"2026-05-12T20:03:49.048407Z","end":"2026-05-12T20:03:49.162232Z","steps":["trace[779103369] 'read index received' (duration: 93.656253ms)","trace[779103369] 'applied index is now lower than readState.Index' (duration: 20.167206ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:49.162344Z","caller":"traceutil/trace.go:171","msg":"trace[2044021482] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"485.646018ms","start":"2026-05-12T20:03:48.67651Z","end":"2026-05-12T20:03:49.162156Z","steps":["trace[2044021482] 'process raft request' (duration: 465.573986ms)","trace[2044021482] 'compare' (duration: 19.812295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:49.162551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:48.676493Z","time spent":"486.001819ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:49.162551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.43054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T20:03:49.162611Z","caller":"traceutil/trace.go:171","msg":"trace[48621405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"330.531192ms","start":"2026-05-12T20:03:48.832069Z","end":"2026-05-12T20:03:49.1626Z","steps":["trace[48621405] 'agreement among raft nodes before linearized reading' (duration: 330.368138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:49.162647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:48.83205Z","time spent":"330.585534ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T20:03:49.162651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.967715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T20:03:49.162712Z","caller":"traceutil/trace.go:171","msg":"trace[1443295621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"502.103999ms","start":"2026-05-12T20:03:48.660594Z","end":"2026-05-12T20:03:49.162698Z","steps":["trace[1443295621] 'agreement among raft nodes before linearized reading' (duration: 501.866263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:49.162749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:48.660574Z","time spent":"502.170002ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T20:03:49.162935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.936382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T20:03:49.162975Z","caller":"traceutil/trace.go:171","msg":"trace[2055360222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"502.992475ms","start":"2026-05-12T20:03:48.659972Z","end":"2026-05-12T20:03:49.162965Z","steps":["trace[2055360222] 'agreement among raft nodes before linearized reading' (duration: 502.296825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:49.163004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:48.659966Z","time spent":"503.032356ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T20:06:59.66649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020364893863958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:07:01.058562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020364893863958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:07:01.834027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020364893863958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:07:01.834651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.397437654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-12T20:07:01.835311Z","caller":"traceutil/trace.go:171","msg":"trace[1145429634] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.398149903s","start":"2026-05-12T20:06:59.437143Z","end":"2026-05-12T20:07:01.835293Z","steps":["trace[1145429634] 'agreement among raft nodes before linearized reading' (duration: 2.397475164s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:01.835938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:06:59.437122Z","time spent":"2.398792952s","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/12 20:07:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-12T20:07:02.334257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020364893863958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:07:02.83474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020364893863958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:07:03.096893Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.076880313s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T20:07:03.097385Z","caller":"traceutil/trace.go:171","msg":"trace[722336380] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"4.077479409s","start":"2026-05-12T20:06:59.019876Z","end":"2026-05-12T20:07:03.097355Z","steps":["trace[722336380] 'process raft request' (duration: 4.077259262s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.097554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:06:59.019848Z","time spent":"4.077634444s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:07:03.099284Z","caller":"traceutil/trace.go:171","msg":"trace[98862931] linearizableReadLoop","detail":"{readStateIndex:2342; appliedIndex:2339; }","duration":"3.93345054s","start":"2026-05-12T20:06:59.165816Z","end":"2026-05-12T20:07:03.099267Z","steps":["trace[98862931] 'read index received' (duration: 3.931225s)","trace[98862931] 'applied index is now lower than readState.Index' (duration: 2.22495ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:07:03.099469Z","caller":"traceutil/trace.go:171","msg":"trace[1466706375] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"3.188215468s","start":"2026-05-12T20:06:59.911245Z","end":"2026-05-12T20:07:03.09946Z","steps":["trace[1466706375] 'process raft request' (duration: 3.187852468s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.09955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:06:59.911218Z","time spent":"3.188271709s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:07:03.101113Z","caller":"traceutil/trace.go:171","msg":"trace[642577036] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"2.039374181s","start":"2026-05-12T20:07:01.061729Z","end":"2026-05-12T20:07:03.101103Z","steps":["trace[642577036] 'process raft request' (duration: 2.037509081s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.101189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.061704Z","time spent":"2.039456705s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:07:03.10131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.935506627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:07:03.10134Z","caller":"traceutil/trace.go:171","msg":"trace[440906828] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2251; }","duration":"3.935585198s","start":"2026-05-12T20:06:59.165748Z","end":"2026-05-12T20:07:03.101333Z","steps":["trace[440906828] 'agreement among raft nodes before linearized reading' (duration: 3.935534387s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.101363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:06:59.165727Z","time spent":"3.935628781s","remote":"127.0.0.1:58540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:07:03.101535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.448728138s","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-05-12T20:07:03.101746Z","caller":"traceutil/trace.go:171","msg":"trace[109702621] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2251; }","duration":"3.448966844s","start":"2026-05-12T20:06:59.652774Z","end":"2026-05-12T20:07:03.101741Z","steps":["trace[109702621] 'agreement among raft nodes before linearized reading' (duration: 3.448686427s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.101762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:06:59.652758Z","time spent":"3.449001204s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-12T20:07:03.10211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.277531ms","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:525"} {"level":"info","ts":"2026-05-12T20:07:03.102138Z","caller":"traceutil/trace.go:171","msg":"trace[1000777660] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2251; }","duration":"372.356384ms","start":"2026-05-12T20:07:02.729775Z","end":"2026-05-12T20:07:03.102131Z","steps":["trace[1000777660] 'agreement among raft nodes before linearized reading' (duration: 372.279572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.102158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:02.729752Z","time spent":"372.400535ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-12T20:07:03.10242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.989644ms","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-05-12T20:07:03.102447Z","caller":"traceutil/trace.go:171","msg":"trace[253072286] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2251; }","duration":"427.019976ms","start":"2026-05-12T20:07:02.675421Z","end":"2026-05-12T20:07:03.10244Z","steps":["trace[253072286] 'agreement among raft nodes before linearized reading' (duration: 426.978244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.102468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:02.675351Z","time spent":"427.110539ms","remote":"127.0.0.1:58620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:07:03.102599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.108843916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-12T20:07:03.102623Z","caller":"traceutil/trace.go:171","msg":"trace[1630578586] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2251; }","duration":"3.108909068s","start":"2026-05-12T20:06:59.993708Z","end":"2026-05-12T20:07:03.102618Z","steps":["trace[1630578586] 'agreement among raft nodes before linearized reading' (duration: 3.108849777s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.102639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:06:59.993686Z","time spent":"3.10894958s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-12T20:07:03.102707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.040905124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-12T20:07:03.102731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.144190598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2812"} {"level":"info","ts":"2026-05-12T20:07:03.10282Z","caller":"traceutil/trace.go:171","msg":"trace[1118684342] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2251; }","duration":"3.144275711s","start":"2026-05-12T20:06:59.95849Z","end":"2026-05-12T20:07:03.102766Z","steps":["trace[1118684342] 'agreement among raft nodes before linearized reading' (duration: 3.144149107s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.10286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:06:59.958469Z","time spent":"3.144382334s","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2834,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-05-12T20:07:03.102887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.877857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:07:03.102935Z","caller":"traceutil/trace.go:171","msg":"trace[1095482067] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2251; }","duration":"520.970628ms","start":"2026-05-12T20:07:02.581953Z","end":"2026-05-12T20:07:03.102924Z","steps":["trace[1095482067] 'agreement among raft nodes before linearized reading' (duration: 520.890216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.10297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:02.581934Z","time spent":"521.02743ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-12T20:07:03.10282Z","caller":"traceutil/trace.go:171","msg":"trace[2138639085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2251; }","duration":"2.040991846s","start":"2026-05-12T20:07:01.061765Z","end":"2026-05-12T20:07:03.102757Z","steps":["trace[2138639085] 'agreement among raft nodes before linearized reading' (duration: 2.040791941s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.103079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.061749Z","time spent":"2.041321736s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T20:07:03.10319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.302032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-12T20:07:03.103226Z","caller":"traceutil/trace.go:171","msg":"trace[464475856] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:2251; }","duration":"909.367534ms","start":"2026-05-12T20:07:02.193849Z","end":"2026-05-12T20:07:03.103217Z","steps":["trace[464475856] 'agreement among raft nodes before linearized reading' (duration: 909.275451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.103252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:02.193833Z","time spent":"909.413594ms","remote":"127.0.0.1:33518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-05-12T20:07:03.103266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268084862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:07:03.103295Z","caller":"traceutil/trace.go:171","msg":"trace[2085976839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2251; }","duration":"1.268139184s","start":"2026-05-12T20:07:01.835146Z","end":"2026-05-12T20:07:03.103286Z","steps":["trace[2085976839] 'agreement among raft nodes before linearized reading' (duration: 1.268089902s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.103296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.976677159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-12T20:07:03.103329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.835133Z","time spent":"1.268189616s","remote":"127.0.0.1:33488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T20:07:03.103337Z","caller":"traceutil/trace.go:171","msg":"trace[1788639588] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2251; }","duration":"1.97674718s","start":"2026-05-12T20:07:01.126584Z","end":"2026-05-12T20:07:03.103331Z","steps":["trace[1788639588] 'agreement among raft nodes before linearized reading' (duration: 1.976686818s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.10336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.126567Z","time spent":"1.97678771s","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":14,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:07:03.103557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266285643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-12T20:07:03.103626Z","caller":"traceutil/trace.go:171","msg":"trace[1636565271] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2251; }","duration":"1.266380956s","start":"2026-05-12T20:07:01.837228Z","end":"2026-05-12T20:07:03.103609Z","steps":["trace[1636565271] 'agreement among raft nodes before linearized reading' (duration: 1.266243641s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.103644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041798768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-12T20:07:03.103667Z","caller":"traceutil/trace.go:171","msg":"trace[1853865513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2251; }","duration":"2.041867271s","start":"2026-05-12T20:07:01.061793Z","end":"2026-05-12T20:07:03.10366Z","steps":["trace[1853865513] 'agreement among raft nodes before linearized reading' (duration: 2.041792478s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.103671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.837212Z","time spent":"1.266446228s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-12T20:07:03.103692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.061776Z","time spent":"2.041908381s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T20:07:03.103794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041977334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T20:07:03.103816Z","caller":"traceutil/trace.go:171","msg":"trace[1066024298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2251; }","duration":"2.042007695s","start":"2026-05-12T20:07:01.061803Z","end":"2026-05-12T20:07:03.10381Z","steps":["trace[1066024298] 'agreement among raft nodes before linearized reading' (duration: 2.041942343s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.103832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.061796Z","time spent":"2.042032036s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T20:07:03.103241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.04041323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-12T20:07:03.104004Z","caller":"traceutil/trace.go:171","msg":"trace[2117594672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2251; }","duration":"2.041199952s","start":"2026-05-12T20:07:01.062792Z","end":"2026-05-12T20:07:03.103992Z","steps":["trace[2117594672] 'agreement among raft nodes before linearized reading' (duration: 2.04040398s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.104049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.062771Z","time spent":"2.041266495s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T20:07:03.107855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.045997894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-12T20:07:03.107914Z","caller":"traceutil/trace.go:171","msg":"trace[1546600802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2251; }","duration":"2.046094287s","start":"2026-05-12T20:07:01.061805Z","end":"2026-05-12T20:07:03.1079Z","steps":["trace[1546600802] 'agreement among raft nodes before linearized reading' (duration: 2.041578564s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:07:03.107947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:07:01.061795Z","time spent":"2.046143679s","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}