{"level":"warn","ts":"2026-05-29T22:00:10.747654Z","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-29T22:00:10.747788Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-29T22:00:10.748048Z","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-29T22:00:10.748079Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-29T22:00:10.748106Z","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-29T22:00:10.749184Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-05-29T22:00:10.749379Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-29T22:00:10.758988Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.200484ms"} {"level":"info","ts":"2026-05-29T22:00:10.764838Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-05-29T22:00:10.764916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T22:00:10.765089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-05-29T22:00:10.765115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T22:00:10.765151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-05-29T22:00:10.765219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-05-29T22:00:10.773698Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T22:00:10.775401Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T22:00:10.776743Z","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-29T22:00:10.779106Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T22:00:10.779884Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T22:00:10.779949Z","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-29T22:00:10.780896Z","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-29T22:00:10.780969Z","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-29T22:00:10.781546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-05-29T22:00:10.782651Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-29T22:00:10.784933Z","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-29T22:00:10.785151Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-29T22:00:10.78522Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-29T22:00:10.785476Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T22:00:10.785522Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T22:00:10.966155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T22:00:10.966283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T22:00:10.96635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-05-29T22:00:10.966405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-05-29T22:00:10.966449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-29T22:00:10.966496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-05-29T22:00:10.966537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-29T22:00:10.96748Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T22:00:10.968298Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T22:00:10.968468Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T22:00:10.968631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T22:00:10.96889Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T22:00:10.969077Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T22:00:10.96915Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T22:00:10.972038Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T22:00:10.972116Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T22:00:10.972046Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-05-29T22:00:10.972938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-29T22:00:13.056439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114681251302785,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:00:13.357193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.646854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T22:00:13.35739Z","caller":"traceutil/trace.go:171","msg":"trace[1363548706] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5; }","duration":"803.99723ms","start":"2026-05-29T22:00:12.553375Z","end":"2026-05-29T22:00:13.357373Z","steps":["trace[1363548706] 'process raft request' (duration: 803.952199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:13.357474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.553358Z","time spent":"804.087352ms","remote":"127.0.0.1:55462","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T22:00:13.357543Z","caller":"traceutil/trace.go:171","msg":"trace[866487680] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"804.00976ms","start":"2026-05-29T22:00:12.553372Z","end":"2026-05-29T22:00:13.357382Z","steps":["trace[866487680] 'process raft request' (duration: 505.540448ms)","trace[866487680] 'compare' (duration: 297.47534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:13.357703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.553357Z","time spent":"804.282156ms","remote":"127.0.0.1:55462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:14.09973Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.040452091s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T22:00:14.319941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.948485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T22:00:14.320202Z","caller":"traceutil/trace.go:171","msg":"trace[903341137] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.760138227s","start":"2026-05-29T22:00:12.560021Z","end":"2026-05-29T22:00:14.320159Z","steps":["trace[903341137] 'process raft request' (duration: 1.760003014s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.320349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.559998Z","time spent":"1.76028084s","remote":"127.0.0.1:55874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T22:00:14.320333Z","caller":"traceutil/trace.go:171","msg":"trace[53255255] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.766030988s","start":"2026-05-29T22:00:12.55422Z","end":"2026-05-29T22:00:14.320251Z","steps":["trace[53255255] 'process raft request' (duration: 1.545708085s)","trace[53255255] 'compare' (duration: 219.783051ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:14.320421Z","caller":"traceutil/trace.go:171","msg":"trace[1265165275] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.760199309s","start":"2026-05-29T22:00:12.560165Z","end":"2026-05-29T22:00:14.320364Z","steps":["trace[1265165275] 'process raft request' (duration: 1.759921242s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.320476Z","caller":"traceutil/trace.go:171","msg":"trace[1105752329] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.7602578s","start":"2026-05-29T22:00:12.560207Z","end":"2026-05-29T22:00:14.320465Z","steps":["trace[1105752329] 'process raft request' (duration: 1.760071336s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.320482Z","caller":"traceutil/trace.go:171","msg":"trace[522309316] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.760142756s","start":"2026-05-29T22:00:12.560266Z","end":"2026-05-29T22:00:14.320409Z","steps":["trace[522309316] 'process raft request' (duration: 1.760047834s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.320541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.560176Z","time spent":"1.760330971s","remote":"127.0.0.1:55874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:14.320585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.554206Z","time spent":"1.766266173s","remote":"127.0.0.1:55790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:14.320553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764703977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T22:00:14.32062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.560243Z","time spent":"1.760317901s","remote":"127.0.0.1:55874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T22:00:14.320651Z","caller":"traceutil/trace.go:171","msg":"trace[16929609] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"1.764868312s","start":"2026-05-29T22:00:12.555771Z","end":"2026-05-29T22:00:14.320639Z","steps":["trace[16929609] 'agreement among raft nodes before linearized reading' (duration: 1.764666437s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.320681Z","caller":"traceutil/trace.go:171","msg":"trace[894603920] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.757540309s","start":"2026-05-29T22:00:12.5631Z","end":"2026-05-29T22:00:14.32064Z","steps":["trace[894603920] 'process raft request' (duration: 1.757246412s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.320707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.555761Z","time spent":"1.764928163s","remote":"127.0.0.1:55742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-29T22:00:14.320399Z","caller":"traceutil/trace.go:171","msg":"trace[808237243] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:7; }","duration":"1.764590465s","start":"2026-05-29T22:00:12.555791Z","end":"2026-05-29T22:00:14.320382Z","steps":["trace[808237243] 'read index received' (duration: 503.232098ms)","trace[808237243] 'applied index is now lower than readState.Index' (duration: 1.261357577s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:14.320773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.563089Z","time spent":"1.75762929s","remote":"127.0.0.1:55532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:14.32084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715448832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-29T22:00:14.32088Z","caller":"traceutil/trace.go:171","msg":"trace[987757360] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:12; }","duration":"1.715541984s","start":"2026-05-29T22:00:12.605328Z","end":"2026-05-29T22:00:14.32087Z","steps":["trace[987757360] 'agreement among raft nodes before linearized reading' (duration: 1.715475842s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.320877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.452981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T22:00:14.320918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.605308Z","time spent":"1.715603254s","remote":"127.0.0.1:55462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-29T22:00:14.320926Z","caller":"traceutil/trace.go:171","msg":"trace[1017492142] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:12; }","duration":"871.511903ms","start":"2026-05-29T22:00:13.449402Z","end":"2026-05-29T22:00:14.320914Z","steps":["trace[1017492142] 'agreement among raft nodes before linearized reading' (duration: 871.41032ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.320607Z","caller":"traceutil/trace.go:171","msg":"trace[186309276] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.760227479s","start":"2026-05-29T22:00:12.56011Z","end":"2026-05-29T22:00:14.320338Z","steps":["trace[186309276] 'process raft request' (duration: 1.759952573s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.321044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.308233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-05-29T22:00:14.321053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.534139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T22:00:14.321072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.421422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T22:00:14.321094Z","caller":"traceutil/trace.go:171","msg":"trace[1335101874] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:12; }","duration":"962.395915ms","start":"2026-05-29T22:00:13.358689Z","end":"2026-05-29T22:00:14.321084Z","steps":["trace[1335101874] 'agreement among raft nodes before linearized reading' (duration: 962.261132ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.321107Z","caller":"traceutil/trace.go:171","msg":"trace[694252664] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:12; }","duration":"867.478074ms","start":"2026-05-29T22:00:13.453619Z","end":"2026-05-29T22:00:14.321098Z","steps":["trace[694252664] 'agreement among raft nodes before linearized reading' (duration: 867.428172ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.321105Z","caller":"traceutil/trace.go:171","msg":"trace[1169523450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"767.615831ms","start":"2026-05-29T22:00:13.553476Z","end":"2026-05-29T22:00:14.321092Z","steps":["trace[1169523450] 'agreement among raft nodes before linearized reading' (duration: 767.511079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.321116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589330775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T22:00:14.320588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.560148Z","time spent":"1.760352271s","remote":"127.0.0.1:55874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:14.321137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:13.453607Z","time spent":"867.523854ms","remote":"127.0.0.1:55712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-29T22:00:14.321149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:13.553459Z","time spent":"767.679642ms","remote":"127.0.0.1:55372","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-29T22:00:14.321152Z","caller":"traceutil/trace.go:171","msg":"trace[386510537] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:12; }","duration":"1.589403277s","start":"2026-05-29T22:00:12.731738Z","end":"2026-05-29T22:00:14.321142Z","steps":["trace[386510537] 'agreement among raft nodes before linearized reading' (duration: 1.589350745s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.321183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.357555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T22:00:14.321226Z","caller":"traceutil/trace.go:171","msg":"trace[1620549682] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:12; }","duration":"958.411206ms","start":"2026-05-29T22:00:13.362801Z","end":"2026-05-29T22:00:14.321212Z","steps":["trace[1620549682] 'agreement among raft nodes before linearized reading' (duration: 958.348465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.321259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:13.362789Z","time spent":"958.463877ms","remote":"127.0.0.1:55442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-05-29T22:00:14.32119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.731722Z","time spent":"1.589460777s","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-05-29T22:00:14.321131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:13.358626Z","time spent":"962.498328ms","remote":"127.0.0.1:55462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-29T22:00:14.321495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:12.560078Z","time spent":"1.760949404s","remote":"127.0.0.1:55874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:00:14.320961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:13.449316Z","time spent":"871.637365ms","remote":"127.0.0.1:55694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-05-29T22:00:14.56395Z","caller":"traceutil/trace.go:171","msg":"trace[1306397085] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"238.266672ms","start":"2026-05-29T22:00:14.325647Z","end":"2026-05-29T22:00:14.563914Z","steps":["trace[1306397085] 'process raft request' (duration: 193.20047ms)","trace[1306397085] 'compare' (duration: 44.861568ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:14.564267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.374364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-05-29T22:00:14.564366Z","caller":"traceutil/trace.go:171","msg":"trace[881225195] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:13; }","duration":"238.557568ms","start":"2026-05-29T22:00:14.325787Z","end":"2026-05-29T22:00:14.564344Z","steps":["trace[881225195] 'agreement among raft nodes before linearized reading' (duration: 238.198981ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.564525Z","caller":"traceutil/trace.go:171","msg":"trace[1883100489] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:16; }","duration":"238.000546ms","start":"2026-05-29T22:00:14.325828Z","end":"2026-05-29T22:00:14.563829Z","steps":["trace[1883100489] 'read index received' (duration: 193.119788ms)","trace[1883100489] 'applied index is now lower than readState.Index' (duration: 44.878998ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:14.565071Z","caller":"traceutil/trace.go:171","msg":"trace[1966086165] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"239.109491ms","start":"2026-05-29T22:00:14.325927Z","end":"2026-05-29T22:00:14.565036Z","steps":["trace[1966086165] 'process raft request' (duration: 238.358384ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.565216Z","caller":"traceutil/trace.go:171","msg":"trace[1139168284] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"236.048753ms","start":"2026-05-29T22:00:14.329137Z","end":"2026-05-29T22:00:14.565186Z","steps":["trace[1139168284] 'process raft request' (duration: 235.90845ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.565331Z","caller":"traceutil/trace.go:171","msg":"trace[1416779867] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"236.182185ms","start":"2026-05-29T22:00:14.329111Z","end":"2026-05-29T22:00:14.565293Z","steps":["trace[1416779867] 'process raft request' (duration: 235.882429ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.565436Z","caller":"traceutil/trace.go:171","msg":"trace[1090198949] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"236.439051ms","start":"2026-05-29T22:00:14.328947Z","end":"2026-05-29T22:00:14.565386Z","steps":["trace[1090198949] 'process raft request' (duration: 235.969751ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.565656Z","caller":"traceutil/trace.go:171","msg":"trace[2119294763] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"236.464752ms","start":"2026-05-29T22:00:14.329161Z","end":"2026-05-29T22:00:14.565626Z","steps":["trace[2119294763] 'process raft request' (duration: 235.92596ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.565812Z","caller":"traceutil/trace.go:171","msg":"trace[1338596696] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"237.216078ms","start":"2026-05-29T22:00:14.328577Z","end":"2026-05-29T22:00:14.565793Z","steps":["trace[1338596696] 'process raft request' (duration: 236.278118ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.566206Z","caller":"traceutil/trace.go:171","msg":"trace[1003905463] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"236.993434ms","start":"2026-05-29T22:00:14.329196Z","end":"2026-05-29T22:00:14.566189Z","steps":["trace[1003905463] 'process raft request' (duration: 235.93068ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.565189Z","caller":"traceutil/trace.go:171","msg":"trace[219955413] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"236.108394ms","start":"2026-05-29T22:00:14.329068Z","end":"2026-05-29T22:00:14.565176Z","steps":["trace[219955413] 'process raft request' (duration: 235.885219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.568342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.11207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T22:00:14.568447Z","caller":"traceutil/trace.go:171","msg":"trace[1430411021] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:23; }","duration":"239.235123ms","start":"2026-05-29T22:00:14.329197Z","end":"2026-05-29T22:00:14.568432Z","steps":["trace[1430411021] 'agreement among raft nodes before linearized reading' (duration: 238.358844ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.569836Z","caller":"traceutil/trace.go:171","msg":"trace[952056149] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"236.411881ms","start":"2026-05-29T22:00:14.333344Z","end":"2026-05-29T22:00:14.569756Z","steps":["trace[952056149] 'process raft request' (duration: 234.1111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:14.569833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.440431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T22:00:14.569896Z","caller":"traceutil/trace.go:171","msg":"trace[222241543] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:23; }","duration":"240.540463ms","start":"2026-05-29T22:00:14.32934Z","end":"2026-05-29T22:00:14.569881Z","steps":["trace[222241543] 'agreement among raft nodes before linearized reading' (duration: 240.4222ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:14.570486Z","caller":"traceutil/trace.go:171","msg":"trace[118795072] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"241.133545ms","start":"2026-05-29T22:00:14.329327Z","end":"2026-05-29T22:00:14.57046Z","steps":["trace[118795072] 'process raft request' (duration: 235.828847ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:15.219931Z","caller":"traceutil/trace.go:171","msg":"trace[2027942890] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"178.311528ms","start":"2026-05-29T22:00:15.041584Z","end":"2026-05-29T22:00:15.219895Z","steps":["trace[2027942890] 'process raft request' (duration: 173.671304ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:40.943797Z","caller":"traceutil/trace.go:171","msg":"trace[1240239116] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"214.395267ms","start":"2026-05-29T22:00:40.729362Z","end":"2026-05-29T22:00:40.943757Z","steps":["trace[1240239116] 'process raft request' (duration: 213.275983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:51.274428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.371852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T22:00:51.274599Z","caller":"traceutil/trace.go:171","msg":"trace[1431290582] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"382.273068ms","start":"2026-05-29T22:00:50.892257Z","end":"2026-05-29T22:00:51.27453Z","steps":["trace[1431290582] 'read index received' (duration: 57.740942ms)","trace[1431290582] 'applied index is now lower than readState.Index' (duration: 324.530366ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:51.274918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.640536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T22:00:51.274963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.741221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:51.275009Z","caller":"traceutil/trace.go:171","msg":"trace[1795568419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"217.815252ms","start":"2026-05-29T22:00:51.057179Z","end":"2026-05-29T22:00:51.274995Z","steps":["trace[1795568419] 'agreement among raft nodes before linearized reading' (duration: 217.72458ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:51.275004Z","caller":"traceutil/trace.go:171","msg":"trace[2145485411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"382.767858ms","start":"2026-05-29T22:00:50.892215Z","end":"2026-05-29T22:00:51.274983Z","steps":["trace[2145485411] 'agreement among raft nodes before linearized reading' (duration: 382.578184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:51.275019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.13001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:00:51.275067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:50.8922Z","time spent":"382.85685ms","remote":"127.0.0.1:55624","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\" "} {"level":"warn","ts":"2026-05-29T22:00:51.275106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.463514ms","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-29T22:00:51.275143Z","caller":"traceutil/trace.go:171","msg":"trace[1376580208] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:518; }","duration":"326.330614ms","start":"2026-05-29T22:00:50.948778Z","end":"2026-05-29T22:00:51.275109Z","steps":["trace[1376580208] 'agreement among raft nodes before linearized reading' (duration: 326.105929ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:51.275165Z","caller":"traceutil/trace.go:171","msg":"trace[1103747784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"197.530255ms","start":"2026-05-29T22:00:51.077614Z","end":"2026-05-29T22:00:51.275144Z","steps":["trace[1103747784] 'agreement among raft nodes before linearized reading' (duration: 197.349871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:51.275211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:50.948747Z","time spent":"326.447997ms","remote":"127.0.0.1:55452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-29T22:00:51.275225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.150241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-29T22:00:51.275296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.760059ms","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-29T22:00:51.275383Z","caller":"traceutil/trace.go:171","msg":"trace[807954211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"263.343196ms","start":"2026-05-29T22:00:51.012005Z","end":"2026-05-29T22:00:51.275348Z","steps":["trace[807954211] 'agreement among raft nodes before linearized reading' (duration: 263.021208ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:51.275423Z","caller":"traceutil/trace.go:171","msg":"trace[62272737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"275.947514ms","start":"2026-05-29T22:00:50.999443Z","end":"2026-05-29T22:00:51.27539Z","steps":["trace[62272737] 'agreement among raft nodes before linearized reading' (duration: 275.648207ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:51.41054Z","caller":"traceutil/trace.go:171","msg":"trace[338176163] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:536; }","duration":"119.449624ms","start":"2026-05-29T22:00:51.291056Z","end":"2026-05-29T22:00:51.410506Z","steps":["trace[338176163] 'read index received' (duration: 68.377919ms)","trace[338176163] 'applied index is now lower than readState.Index' (duration: 51.066915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:51.410745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.655348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:51.410788Z","caller":"traceutil/trace.go:171","msg":"trace[994869498] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:522; }","duration":"119.809592ms","start":"2026-05-29T22:00:51.290964Z","end":"2026-05-29T22:00:51.410773Z","steps":["trace[994869498] 'agreement among raft nodes before linearized reading' (duration: 119.69604ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:51.410979Z","caller":"traceutil/trace.go:171","msg":"trace[600410930] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"130.451227ms","start":"2026-05-29T22:00:51.280495Z","end":"2026-05-29T22:00:51.410946Z","steps":["trace[600410930] 'process raft request' (duration: 127.690466ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:51.411141Z","caller":"traceutil/trace.go:171","msg":"trace[135543986] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"130.371225ms","start":"2026-05-29T22:00:51.280754Z","end":"2026-05-29T22:00:51.411126Z","steps":["trace[135543986] 'process raft request' (duration: 129.550317ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:51.411265Z","caller":"traceutil/trace.go:171","msg":"trace[1992621626] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"129.75946ms","start":"2026-05-29T22:00:51.281496Z","end":"2026-05-29T22:00:51.411255Z","steps":["trace[1992621626] 'process raft request' (duration: 128.954693ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:58.766873Z","caller":"traceutil/trace.go:171","msg":"trace[1923117877] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"161.379308ms","start":"2026-05-29T22:00:58.605454Z","end":"2026-05-29T22:00:58.766834Z","steps":["trace[1923117877] 'read index received' (duration: 158.209678ms)","trace[1923117877] 'applied index is now lower than readState.Index' (duration: 3.16793ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:58.766964Z","caller":"traceutil/trace.go:171","msg":"trace[1585730834] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"183.018466ms","start":"2026-05-29T22:00:58.583889Z","end":"2026-05-29T22:00:58.766907Z","steps":["trace[1585730834] 'process raft request' (duration: 180.789536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:58.767166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.678754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mrljr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-29T22:00:58.767218Z","caller":"traceutil/trace.go:171","msg":"trace[314130871] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mrljr; range_end:; response_count:1; response_revision:628; }","duration":"161.804287ms","start":"2026-05-29T22:00:58.6054Z","end":"2026-05-29T22:00:58.767204Z","steps":["trace[314130871] 'agreement among raft nodes before linearized reading' (duration: 161.587532ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:59.850048Z","caller":"traceutil/trace.go:171","msg":"trace[329864375] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"510.398934ms","start":"2026-05-29T22:00:59.339605Z","end":"2026-05-29T22:00:59.850004Z","steps":["trace[329864375] 'process raft request' (duration: 509.147307ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:59.85054Z","caller":"traceutil/trace.go:171","msg":"trace[595320330] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"451.035025ms","start":"2026-05-29T22:00:59.398875Z","end":"2026-05-29T22:00:59.84991Z","steps":["trace[595320330] 'read index received' (duration: 449.378429ms)","trace[595320330] 'applied index is now lower than readState.Index' (duration: 1.654856ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:00:59.850602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.681439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T22:00:59.850683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.339552Z","time spent":"510.642399ms","remote":"127.0.0.1:55452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:00:59.850809Z","caller":"traceutil/trace.go:171","msg":"trace[1802214024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"451.884894ms","start":"2026-05-29T22:00:59.398809Z","end":"2026-05-29T22:00:59.850693Z","steps":["trace[1802214024] 'agreement among raft nodes before linearized reading' (duration: 451.31964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:59.850897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.466596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:00:59.850979Z","caller":"traceutil/trace.go:171","msg":"trace[1220617194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"349.582208ms","start":"2026-05-29T22:00:59.501373Z","end":"2026-05-29T22:00:59.850956Z","steps":["trace[1220617194] 'agreement among raft nodes before linearized reading' (duration: 349.456966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:59.850976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.578565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T22:00:59.851018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.501361Z","time spent":"349.6486ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T22:00:59.851026Z","caller":"traceutil/trace.go:171","msg":"trace[735888805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"377.697478ms","start":"2026-05-29T22:00:59.473311Z","end":"2026-05-29T22:00:59.851009Z","steps":["trace[735888805] 'agreement among raft nodes before linearized reading' (duration: 377.441092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:59.850888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.398775Z","time spent":"452.099339ms","remote":"127.0.0.1:55624","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-29T22:00:59.851163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.758759ms","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-29T22:00:59.851202Z","caller":"traceutil/trace.go:171","msg":"trace[407803267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"377.808551ms","start":"2026-05-29T22:00:59.473385Z","end":"2026-05-29T22:00:59.851193Z","steps":["trace[407803267] 'agreement among raft nodes before linearized reading' (duration: 377.705178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:59.851229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.473379Z","time spent":"377.845091ms","remote":"127.0.0.1:55624","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-29T22:00:59.851067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.473274Z","time spent":"377.78417ms","remote":"127.0.0.1:55624","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-29T22:00:59.851739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.652706ms","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-29T22:00:59.851861Z","caller":"traceutil/trace.go:171","msg":"trace[953268259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"377.79277ms","start":"2026-05-29T22:00:59.474029Z","end":"2026-05-29T22:00:59.851822Z","steps":["trace[953268259] 'agreement among raft nodes before linearized reading' (duration: 377.468763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:59.851922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.47402Z","time spent":"377.892252ms","remote":"127.0.0.1:55624","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\" "} {"level":"warn","ts":"2026-05-29T22:01:01.109541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028468235s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:01:01.109895Z","caller":"traceutil/trace.go:171","msg":"trace[104910476] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.252312591s","start":"2026-05-29T22:00:59.857507Z","end":"2026-05-29T22:01:01.109819Z","steps":["trace[104910476] 'process raft request' (duration: 223.421266ms)","trace[104910476] 'compare' (duration: 1.028291792s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:01:01.11004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.857491Z","time spent":"1.252466915s","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:01:01.110104Z","caller":"traceutil/trace.go:171","msg":"trace[1095947528] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.252462915s","start":"2026-05-29T22:00:59.857592Z","end":"2026-05-29T22:01:01.110055Z","steps":["trace[1095947528] 'process raft request' (duration: 1.252127418s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:01.110138Z","caller":"traceutil/trace.go:171","msg":"trace[1977441128] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.190062219s","start":"2026-05-29T22:00:59.920035Z","end":"2026-05-29T22:01:01.110097Z","steps":["trace[1977441128] 'process raft request' (duration: 1.189729782s)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:01.110205Z","caller":"traceutil/trace.go:171","msg":"trace[1516244348] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.252628208s","start":"2026-05-29T22:00:59.857555Z","end":"2026-05-29T22:01:01.110184Z","steps":["trace[1516244348] 'process raft request' (duration: 1.252114547s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:01.110314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.857521Z","time spent":"1.25274047s","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:01:01.11031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.857582Z","time spent":"1.252617598s","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:01:01.11033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:00:59.920007Z","time spent":"1.190229253s","remote":"127.0.0.1:55850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T22:01:18.537234Z","caller":"traceutil/trace.go:171","msg":"trace[1106369757] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"110.958556ms","start":"2026-05-29T22:01:18.426253Z","end":"2026-05-29T22:01:18.537212Z","steps":["trace[1106369757] 'process raft request' (duration: 110.548497ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:18.856311Z","caller":"traceutil/trace.go:171","msg":"trace[608874373] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"347.337168ms","start":"2026-05-29T22:01:18.508933Z","end":"2026-05-29T22:01:18.85627Z","steps":["trace[608874373] 'process raft request' (duration: 347.092582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:18.856532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:18.508912Z","time spent":"347.504562ms","remote":"127.0.0.1:55532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:01:18.856904Z","caller":"traceutil/trace.go:171","msg":"trace[493461697] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:832; }","duration":"318.891901ms","start":"2026-05-29T22:01:18.537997Z","end":"2026-05-29T22:01:18.856888Z","steps":["trace[493461697] 'read index received' (duration: 318.885071ms)","trace[493461697] 'applied index is now lower than readState.Index' (duration: 5.82µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:01:18.857048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.042895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:01:18.85709Z","caller":"traceutil/trace.go:171","msg":"trace[1674670291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"319.104726ms","start":"2026-05-29T22:01:18.537974Z","end":"2026-05-29T22:01:18.857078Z","steps":["trace[1674670291] 'agreement among raft nodes before linearized reading' (duration: 318.988264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:18.857121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:18.537965Z","time spent":"319.149867ms","remote":"127.0.0.1:55366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T22:01:18.920025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.856431ms","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-29T22:01:18.920194Z","caller":"traceutil/trace.go:171","msg":"trace[80034758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"280.065385ms","start":"2026-05-29T22:01:18.640109Z","end":"2026-05-29T22:01:18.920174Z","steps":["trace[80034758] 'agreement among raft nodes before linearized reading' (duration: 279.721867ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:18.920043Z","caller":"traceutil/trace.go:171","msg":"trace[540728132] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"169.539508ms","start":"2026-05-29T22:01:18.750416Z","end":"2026-05-29T22:01:18.919955Z","steps":["trace[540728132] 'process raft request' (duration: 169.224841ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:19.083608Z","caller":"traceutil/trace.go:171","msg":"trace[181840899] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"158.416492ms","start":"2026-05-29T22:01:18.92516Z","end":"2026-05-29T22:01:19.083576Z","steps":["trace[181840899] 'process raft request' (duration: 158.252149ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:54.324233Z","caller":"traceutil/trace.go:171","msg":"trace[486161081] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"158.184493ms","start":"2026-05-29T22:01:54.166021Z","end":"2026-05-29T22:01:54.324206Z","steps":["trace[486161081] 'read index received' (duration: 158.05892ms)","trace[486161081] 'applied index is now lower than readState.Index' (duration: 124.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:54.324297Z","caller":"traceutil/trace.go:171","msg":"trace[842630786] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"184.691454ms","start":"2026-05-29T22:01:54.139565Z","end":"2026-05-29T22:01:54.324256Z","steps":["trace[842630786] 'process raft request' (duration: 184.50553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:54.3244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.367487ms","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-29T22:01:54.324443Z","caller":"traceutil/trace.go:171","msg":"trace[1325961739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"158.465359ms","start":"2026-05-29T22:01:54.165968Z","end":"2026-05-29T22:01:54.324433Z","steps":["trace[1325961739] 'agreement among raft nodes before linearized reading' (duration: 158.329766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:55.281936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.24409ms","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-29T22:01:55.28204Z","caller":"traceutil/trace.go:171","msg":"trace[1648521976] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1019; }","duration":"710.412123ms","start":"2026-05-29T22:01:54.571603Z","end":"2026-05-29T22:01:55.282015Z","steps":["trace[1648521976] 'count revisions from in-memory index tree' (duration: 710.169558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:55.282098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:54.571563Z","time spent":"710.518636ms","remote":"127.0.0.1:55772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:01:55.282108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.091556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:01:55.282153Z","caller":"traceutil/trace.go:171","msg":"trace[1911094991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1019; }","duration":"780.137077ms","start":"2026-05-29T22:01:54.502003Z","end":"2026-05-29T22:01:55.28214Z","steps":["trace[1911094991] 'range keys from in-memory index tree' (duration: 779.963114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:55.282184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:54.501954Z","time spent":"780.222819ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T22:01:55.282388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.057859ms","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-29T22:01:55.282651Z","caller":"traceutil/trace.go:171","msg":"trace[1894423538] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1019; }","duration":"330.343625ms","start":"2026-05-29T22:01:54.952269Z","end":"2026-05-29T22:01:55.282613Z","steps":["trace[1894423538] 'range keys from in-memory index tree' (duration: 330.030158ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:55.81806Z","caller":"traceutil/trace.go:171","msg":"trace[2061756930] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"419.401396ms","start":"2026-05-29T22:01:55.398622Z","end":"2026-05-29T22:01:55.818023Z","steps":["trace[2061756930] 'read index received' (duration: 419.231993ms)","trace[2061756930] 'applied index is now lower than readState.Index' (duration: 168.583µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:55.818176Z","caller":"traceutil/trace.go:171","msg":"trace[1664509069] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"531.463133ms","start":"2026-05-29T22:01:55.286677Z","end":"2026-05-29T22:01:55.81814Z","steps":["trace[1664509069] 'process raft request' (duration: 531.192977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:55.818324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:55.286647Z","time spent":"531.589285ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:01:55.818394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.788605ms","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-29T22:01:55.818425Z","caller":"traceutil/trace.go:171","msg":"trace[1748511375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"419.884047ms","start":"2026-05-29T22:01:55.398533Z","end":"2026-05-29T22:01:55.818417Z","steps":["trace[1748511375] 'agreement among raft nodes before linearized reading' (duration: 419.668752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:55.818442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:55.398507Z","time spent":"419.931507ms","remote":"127.0.0.1:55624","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-29T22:01:55.818429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.098225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T22:01:55.818483Z","caller":"traceutil/trace.go:171","msg":"trace[936705783] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1020; }","duration":"257.200457ms","start":"2026-05-29T22:01:55.561267Z","end":"2026-05-29T22:01:55.818468Z","steps":["trace[936705783] 'agreement among raft nodes before linearized reading' (duration: 257.021203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:55.818642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.562132ms","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-29T22:01:55.818757Z","caller":"traceutil/trace.go:171","msg":"trace[1723582246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"415.727226ms","start":"2026-05-29T22:01:55.403006Z","end":"2026-05-29T22:01:55.818733Z","steps":["trace[1723582246] 'agreement among raft nodes before linearized reading' (duration: 415.339448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:55.818779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.063153ms","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-29T22:01:55.818849Z","caller":"traceutil/trace.go:171","msg":"trace[178599923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"416.215197ms","start":"2026-05-29T22:01:55.402621Z","end":"2026-05-29T22:01:55.818836Z","steps":["trace[178599923] 'agreement among raft nodes before linearized reading' (duration: 415.615124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:55.818897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:55.40261Z","time spent":"416.280528ms","remote":"127.0.0.1:55624","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-29T22:01:55.818806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:55.402994Z","time spent":"415.801918ms","remote":"127.0.0.1:55624","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\" "} {"level":"warn","ts":"2026-05-29T22:01:56.182588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:55.820256Z","time spent":"362.324914ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T22:01:56.183159Z","caller":"traceutil/trace.go:171","msg":"trace[1361701559] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"361.431486ms","start":"2026-05-29T22:01:55.821697Z","end":"2026-05-29T22:01:56.183128Z","steps":["trace[1361701559] 'process raft request' (duration: 361.125829ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:56.18321Z","caller":"traceutil/trace.go:171","msg":"trace[2136939915] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"360.039556ms","start":"2026-05-29T22:01:55.823142Z","end":"2026-05-29T22:01:56.183182Z","steps":["trace[2136939915] 'process raft request' (duration: 359.884272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:56.183296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:55.821681Z","time spent":"361.534429ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:01:56.183382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:55.823134Z","time spent":"360.147378ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:01:56.183536Z","caller":"traceutil/trace.go:171","msg":"trace[1146111759] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"359.750771ms","start":"2026-05-29T22:01:55.823776Z","end":"2026-05-29T22:01:56.183526Z","steps":["trace[1146111759] 'process raft request' (duration: 359.29309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:01:56.183587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:01:55.823768Z","time spent":"359.788191ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:22.532036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.655564ms","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-29T22:02:22.532328Z","caller":"traceutil/trace.go:171","msg":"trace[1998789062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"135.002632ms","start":"2026-05-29T22:02:22.397296Z","end":"2026-05-29T22:02:22.532299Z","steps":["trace[1998789062] 'range keys from in-memory index tree' (duration: 134.44638ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:22.682489Z","caller":"traceutil/trace.go:171","msg":"trace[1538635218] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"148.202476ms","start":"2026-05-29T22:02:22.534262Z","end":"2026-05-29T22:02:22.682464Z","steps":["trace[1538635218] 'read index received' (duration: 148.097774ms)","trace[1538635218] 'applied index is now lower than readState.Index' (duration: 103.372µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:22.68278Z","caller":"traceutil/trace.go:171","msg":"trace[1983777318] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"150.374414ms","start":"2026-05-29T22:02:22.532393Z","end":"2026-05-29T22:02:22.682768Z","steps":["trace[1983777318] 'process raft request' (duration: 149.877963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:22.682999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.950073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:22.683033Z","caller":"traceutil/trace.go:171","msg":"trace[1762652510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"149.035474ms","start":"2026-05-29T22:02:22.533985Z","end":"2026-05-29T22:02:22.683021Z","steps":["trace[1762652510] 'agreement among raft nodes before linearized reading' (duration: 148.967273ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:23.012083Z","caller":"traceutil/trace.go:171","msg":"trace[1481285793] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"473.73934ms","start":"2026-05-29T22:02:22.538308Z","end":"2026-05-29T22:02:23.012047Z","steps":["trace[1481285793] 'process raft request' (duration: 473.33978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:23.012246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:22.538281Z","time spent":"473.880372ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:23.012274Z","caller":"traceutil/trace.go:171","msg":"trace[1327153360] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"473.7814ms","start":"2026-05-29T22:02:22.538453Z","end":"2026-05-29T22:02:23.012234Z","steps":["trace[1327153360] 'process raft request' (duration: 473.416182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:23.012513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:22.538444Z","time spent":"473.928003ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:23.013533Z","caller":"traceutil/trace.go:171","msg":"trace[103818411] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1151; }","duration":"330.943628ms","start":"2026-05-29T22:02:22.682571Z","end":"2026-05-29T22:02:23.013515Z","steps":["trace[103818411] 'read index received' (duration: 328.995005ms)","trace[103818411] 'applied index is now lower than readState.Index' (duration: 1.947463ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:23.013702Z","caller":"traceutil/trace.go:171","msg":"trace[1000558288] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"474.833751ms","start":"2026-05-29T22:02:22.538847Z","end":"2026-05-29T22:02:23.013681Z","steps":["trace[1000558288] 'process raft request' (duration: 473.325759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:23.013734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.969966ms","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-29T22:02:23.013797Z","caller":"traceutil/trace.go:171","msg":"trace[642748922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1111; }","duration":"438.074948ms","start":"2026-05-29T22:02:22.575696Z","end":"2026-05-29T22:02:23.013771Z","steps":["trace[642748922] 'agreement among raft nodes before linearized reading' (duration: 437.886544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:23.0138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:22.538839Z","time spent":"474.914833ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:23.013831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:22.575668Z","time spent":"438.1547ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T22:02:24.567654Z","caller":"traceutil/trace.go:171","msg":"trace[702414594] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"161.141026ms","start":"2026-05-29T22:02:24.406491Z","end":"2026-05-29T22:02:24.567632Z","steps":["trace[702414594] 'read index received' (duration: 160.946092ms)","trace[702414594] 'applied index is now lower than readState.Index' (duration: 193.874µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:24.56776Z","caller":"traceutil/trace.go:171","msg":"trace[2050372731] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"429.697478ms","start":"2026-05-29T22:02:24.138051Z","end":"2026-05-29T22:02:24.567749Z","steps":["trace[2050372731] 'process raft request' (duration: 429.443063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:24.567985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:24.138024Z","time spent":"429.76085ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:24.568181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.540945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:24.568244Z","caller":"traceutil/trace.go:171","msg":"trace[793863841] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1113; }","duration":"161.815141ms","start":"2026-05-29T22:02:24.406412Z","end":"2026-05-29T22:02:24.568227Z","steps":["trace[793863841] 'agreement among raft nodes before linearized reading' (duration: 161.500304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:25.09198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.404677ms","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-29T22:02:25.092106Z","caller":"traceutil/trace.go:171","msg":"trace[847995050] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1113; }","duration":"139.55161ms","start":"2026-05-29T22:02:24.952535Z","end":"2026-05-29T22:02:25.092087Z","steps":["trace[847995050] 'range keys from in-memory index tree' (duration: 139.387067ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:25.092784Z","caller":"traceutil/trace.go:171","msg":"trace[1509782677] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"402.153614ms","start":"2026-05-29T22:02:24.690609Z","end":"2026-05-29T22:02:25.092762Z","steps":["trace[1509782677] 'process raft request' (duration: 307.567815ms)","trace[1509782677] 'compare' (duration: 94.439286ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:25.092908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:24.690578Z","time spent":"402.256565ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:25.46482Z","caller":"traceutil/trace.go:171","msg":"trace[1673546436] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"368.352864ms","start":"2026-05-29T22:02:25.096438Z","end":"2026-05-29T22:02:25.464791Z","steps":["trace[1673546436] 'process raft request' (duration: 336.866776ms)","trace[1673546436] 'compare' (duration: 31.247563ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:25.465209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:25.096411Z","time spent":"368.713652ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:25.621526Z","caller":"traceutil/trace.go:171","msg":"trace[1795480227] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"524.481733ms","start":"2026-05-29T22:02:25.097021Z","end":"2026-05-29T22:02:25.621503Z","steps":["trace[1795480227] 'process raft request' (duration: 524.402091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:25.621673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:25.096999Z","time spent":"524.597835ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:25.621669Z","caller":"traceutil/trace.go:171","msg":"trace[33350649] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"524.582834ms","start":"2026-05-29T22:02:25.097049Z","end":"2026-05-29T22:02:25.621632Z","steps":["trace[33350649] 'process raft request' (duration: 524.416981ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:25.621524Z","caller":"traceutil/trace.go:171","msg":"trace[1325308940] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"524.642946ms","start":"2026-05-29T22:02:25.096843Z","end":"2026-05-29T22:02:25.621486Z","steps":["trace[1325308940] 'process raft request' (duration: 475.755572ms)","trace[1325308940] 'compare' (duration: 48.725371ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:25.62185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.00214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:25.621973Z","caller":"traceutil/trace.go:171","msg":"trace[425300137] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1118; }","duration":"344.230924ms","start":"2026-05-29T22:02:25.277729Z","end":"2026-05-29T22:02:25.62196Z","steps":["trace[425300137] 'agreement among raft nodes before linearized reading' (duration: 344.02937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:25.62202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:25.096821Z","time spent":"525.056065ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:25.622101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:25.277693Z","time spent":"344.354677ms","remote":"127.0.0.1:55646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-05-29T22:02:25.621701Z","caller":"traceutil/trace.go:171","msg":"trace[1466321837] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1157; }","duration":"343.759025ms","start":"2026-05-29T22:02:25.277812Z","end":"2026-05-29T22:02:25.621571Z","steps":["trace[1466321837] 'read index received' (duration: 155.508395ms)","trace[1466321837] 'applied index is now lower than readState.Index' (duration: 188.24945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:25.62301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.491842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:25.623433Z","caller":"traceutil/trace.go:171","msg":"trace[956041587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1118; }","duration":"122.946502ms","start":"2026-05-29T22:02:25.500472Z","end":"2026-05-29T22:02:25.623418Z","steps":["trace[956041587] 'agreement among raft nodes before linearized reading' (duration: 122.501453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:25.623052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:25.097036Z","time spent":"525.924295ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:26.734844Z","caller":"traceutil/trace.go:171","msg":"trace[1269412722] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"158.645882ms","start":"2026-05-29T22:02:26.576178Z","end":"2026-05-29T22:02:26.734824Z","steps":["trace[1269412722] 'process raft request' (duration: 158.514709ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:27.470541Z","caller":"traceutil/trace.go:171","msg":"trace[404512439] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"371.714738ms","start":"2026-05-29T22:02:27.098674Z","end":"2026-05-29T22:02:27.470389Z","steps":["trace[404512439] 'process raft request' (duration: 371.492974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:27.470804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:27.098642Z","time spent":"372.019254ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:27.749349Z","caller":"traceutil/trace.go:171","msg":"trace[1358261700] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"118.484086ms","start":"2026-05-29T22:02:27.630831Z","end":"2026-05-29T22:02:27.749315Z","steps":["trace[1358261700] 'process raft request' (duration: 95.118651ms)","trace[1358261700] 'compare' (duration: 22.679609ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:27.749581Z","caller":"traceutil/trace.go:171","msg":"trace[17437454] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"117.082546ms","start":"2026-05-29T22:02:27.63247Z","end":"2026-05-29T22:02:27.749553Z","steps":["trace[17437454] 'process raft request' (duration: 116.419501ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:27.750144Z","caller":"traceutil/trace.go:171","msg":"trace[298952432] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"116.757829ms","start":"2026-05-29T22:02:27.633361Z","end":"2026-05-29T22:02:27.750118Z","steps":["trace[298952432] 'process raft request' (duration: 115.963182ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:43.677314Z","caller":"traceutil/trace.go:171","msg":"trace[473758748] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"145.735313ms","start":"2026-05-29T22:02:43.531545Z","end":"2026-05-29T22:02:43.67728Z","steps":["trace[473758748] 'process raft request' (duration: 144.946656ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:45.956394Z","caller":"traceutil/trace.go:171","msg":"trace[1555893696] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"267.222957ms","start":"2026-05-29T22:02:45.689144Z","end":"2026-05-29T22:02:45.956367Z","steps":["trace[1555893696] 'read index received' (duration: 267.152715ms)","trace[1555893696] 'applied index is now lower than readState.Index' (duration: 68.532µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:45.956435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:45.564564Z","time spent":"391.861332ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-29T22:02:45.956727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.548784ms","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-29T22:02:45.956817Z","caller":"traceutil/trace.go:171","msg":"trace[1761472190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1231; }","duration":"267.689087ms","start":"2026-05-29T22:02:45.689105Z","end":"2026-05-29T22:02:45.956794Z","steps":["trace[1761472190] 'agreement among raft nodes before linearized reading' (duration: 267.38123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:46.52482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.195892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:46.524981Z","caller":"traceutil/trace.go:171","msg":"trace[1757347118] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"835.764787ms","start":"2026-05-29T22:02:45.689171Z","end":"2026-05-29T22:02:46.524936Z","steps":["trace[1757347118] 'process raft request' (duration: 529.370252ms)","trace[1757347118] 'compare' (duration: 306.015807ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:46.52505Z","caller":"traceutil/trace.go:171","msg":"trace[189981947] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"568.543731ms","start":"2026-05-29T22:02:45.956469Z","end":"2026-05-29T22:02:46.525013Z","steps":["trace[189981947] 'read index received' (duration: 262.119605ms)","trace[189981947] 'applied index is now lower than readState.Index' (duration: 306.422526ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:46.525107Z","caller":"traceutil/trace.go:171","msg":"trace[260994078] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"561.402003ms","start":"2026-05-29T22:02:45.963683Z","end":"2026-05-29T22:02:46.525085Z","steps":["trace[260994078] 'process raft request' (duration: 561.353172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:46.525177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:45.689151Z","time spent":"835.924181ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:46.525189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:45.96365Z","time spent":"561.501444ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:46.525299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.044356ms","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-29T22:02:46.525333Z","caller":"traceutil/trace.go:171","msg":"trace[2016829429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"594.137218ms","start":"2026-05-29T22:02:45.931183Z","end":"2026-05-29T22:02:46.525321Z","steps":["trace[2016829429] 'agreement among raft nodes before linearized reading' (duration: 593.973554ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:46.525329Z","caller":"traceutil/trace.go:171","msg":"trace[1141707986] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"565.608026ms","start":"2026-05-29T22:02:45.959712Z","end":"2026-05-29T22:02:46.52532Z","steps":["trace[1141707986] 'process raft request' (duration: 565.231198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:46.525357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:45.931159Z","time spent":"594.193959ms","remote":"127.0.0.1:55624","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-29T22:02:46.525329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.011466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T22:02:46.525388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:45.959684Z","time spent":"565.677638ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:46.525401Z","caller":"traceutil/trace.go:171","msg":"trace[829704022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"594.136168ms","start":"2026-05-29T22:02:45.931251Z","end":"2026-05-29T22:02:46.525387Z","steps":["trace[829704022] 'agreement among raft nodes before linearized reading' (duration: 593.812231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:46.525435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:45.931215Z","time spent":"594.21185ms","remote":"127.0.0.1:55624","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\" "} {"level":"warn","ts":"2026-05-29T22:02:46.525619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.11628ms","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-29T22:02:46.525715Z","caller":"traceutil/trace.go:171","msg":"trace[41469847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"609.273704ms","start":"2026-05-29T22:02:45.916417Z","end":"2026-05-29T22:02:46.52569Z","steps":["trace[41469847] 'agreement among raft nodes before linearized reading' (duration: 608.986448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:46.525763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:45.916389Z","time spent":"609.365066ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T22:03:00.786003Z","caller":"traceutil/trace.go:171","msg":"trace[249971091] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"179.993553ms","start":"2026-05-29T22:03:00.605972Z","end":"2026-05-29T22:03:00.785965Z","steps":["trace[249971091] 'read index received' (duration: 179.81394ms)","trace[249971091] 'applied index is now lower than readState.Index' (duration: 177.993µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:00.786087Z","caller":"traceutil/trace.go:171","msg":"trace[338189113] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"197.099342ms","start":"2026-05-29T22:03:00.588939Z","end":"2026-05-29T22:03:00.786038Z","steps":["trace[338189113] 'process raft request' (duration: 196.808686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:00.786447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.440443ms","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-29T22:03:00.786566Z","caller":"traceutil/trace.go:171","msg":"trace[211310376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"180.623357ms","start":"2026-05-29T22:03:00.605914Z","end":"2026-05-29T22:03:00.786538Z","steps":["trace[211310376] 'agreement among raft nodes before linearized reading' (duration: 180.256119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:00.792589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.169686ms","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-29T22:03:00.792656Z","caller":"traceutil/trace.go:171","msg":"trace[388727864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"184.255587ms","start":"2026-05-29T22:03:00.608384Z","end":"2026-05-29T22:03:00.79264Z","steps":["trace[388727864] 'agreement among raft nodes before linearized reading' (duration: 184.047783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:00.792719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.648243ms","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-29T22:03:00.792788Z","caller":"traceutil/trace.go:171","msg":"trace[346424644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"178.801386ms","start":"2026-05-29T22:03:00.613969Z","end":"2026-05-29T22:03:00.79277Z","steps":["trace[346424644] 'agreement among raft nodes before linearized reading' (duration: 178.588621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:00.792783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.998311ms","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-29T22:03:00.792847Z","caller":"traceutil/trace.go:171","msg":"trace[466990532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"184.132934ms","start":"2026-05-29T22:03:00.608701Z","end":"2026-05-29T22:03:00.792833Z","steps":["trace[466990532] 'agreement among raft nodes before linearized reading' (duration: 183.897459ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:08.923193Z","caller":"traceutil/trace.go:171","msg":"trace[223142819] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"576.169009ms","start":"2026-05-29T22:03:08.346999Z","end":"2026-05-29T22:03:08.923168Z","steps":["trace[223142819] 'process raft request' (duration: 576.013896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:08.923343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:08.346962Z","time spent":"576.302173ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:03:09.2513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.842959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:03:09.251406Z","caller":"traceutil/trace.go:171","msg":"trace[69399705] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1392; }","duration":"413.502402ms","start":"2026-05-29T22:03:08.837887Z","end":"2026-05-29T22:03:09.251389Z","steps":["trace[69399705] 'read index received' (duration: 85.20626ms)","trace[69399705] 'applied index is now lower than readState.Index' (duration: 328.294432ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:09.251667Z","caller":"traceutil/trace.go:171","msg":"trace[1174290253] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"437.168876ms","start":"2026-05-29T22:03:08.814452Z","end":"2026-05-29T22:03:09.251621Z","steps":["trace[1174290253] 'process raft request' (duration: 311.883457ms)","trace[1174290253] 'compare' (duration: 124.653645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:09.251748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.836669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T22:03:09.251826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.364838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-29T22:03:09.251837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:08.814426Z","time spent":"437.30951ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:03:09.251863Z","caller":"traceutil/trace.go:171","msg":"trace[840969571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"275.45249ms","start":"2026-05-29T22:03:08.976399Z","end":"2026-05-29T22:03:09.251851Z","steps":["trace[840969571] 'agreement among raft nodes before linearized reading' (duration: 275.268836ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:09.251855Z","caller":"traceutil/trace.go:171","msg":"trace[583529009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"413.998763ms","start":"2026-05-29T22:03:08.837831Z","end":"2026-05-29T22:03:09.251829Z","steps":["trace[583529009] 'agreement among raft nodes before linearized reading' (duration: 413.665616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:09.251912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:08.837811Z","time spent":"414.091786ms","remote":"127.0.0.1:55624","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-29T22:03:09.25191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.749246ms","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-29T22:03:09.252018Z","caller":"traceutil/trace.go:171","msg":"trace[562589573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"408.91308ms","start":"2026-05-29T22:03:08.843084Z","end":"2026-05-29T22:03:09.251997Z","steps":["trace[562589573] 'agreement among raft nodes before linearized reading' (duration: 408.586103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:09.252061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:08.843054Z","time spent":"408.997912ms","remote":"127.0.0.1:55624","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-29T22:03:09.25212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.246099ms","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-29T22:03:09.252151Z","caller":"traceutil/trace.go:171","msg":"trace[1855476652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"405.28729ms","start":"2026-05-29T22:03:08.846852Z","end":"2026-05-29T22:03:09.252139Z","steps":["trace[1855476652] 'agreement among raft nodes before linearized reading' (duration: 405.201588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:09.252188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:08.846847Z","time spent":"405.335431ms","remote":"127.0.0.1:55624","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-29T22:03:09.252398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.557385ms","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-29T22:03:09.252514Z","caller":"traceutil/trace.go:171","msg":"trace[1707650000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"405.722449ms","start":"2026-05-29T22:03:08.84678Z","end":"2026-05-29T22:03:09.252502Z","steps":["trace[1707650000] 'agreement among raft nodes before linearized reading' (duration: 405.472823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:09.252548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:08.846757Z","time spent":"405.78235ms","remote":"127.0.0.1:55624","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\" "} {"level":"warn","ts":"2026-05-29T22:03:10.289939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.228768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:10.290047Z","caller":"traceutil/trace.go:171","msg":"trace[1541154754] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1346; }","duration":"254.431003ms","start":"2026-05-29T22:03:10.03559Z","end":"2026-05-29T22:03:10.290021Z","steps":["trace[1541154754] 'count revisions from in-memory index tree' (duration: 254.097485ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:11.269468Z","caller":"traceutil/trace.go:171","msg":"trace[1174929615] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"557.691469ms","start":"2026-05-29T22:03:10.711749Z","end":"2026-05-29T22:03:11.269441Z","steps":["trace[1174929615] 'process raft request' (duration: 557.508225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:11.269643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:10.711712Z","time spent":"557.834943ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:03:11.656938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.933665ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T22:03:11.657489Z","caller":"traceutil/trace.go:171","msg":"trace[1804990865] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"551.979693ms","start":"2026-05-29T22:03:11.105475Z","end":"2026-05-29T22:03:11.657454Z","steps":["trace[1804990865] 'process raft request' (duration: 551.641146ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:11.657665Z","caller":"traceutil/trace.go:171","msg":"trace[1633407949] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1400; }","duration":"392.481296ms","start":"2026-05-29T22:03:11.265144Z","end":"2026-05-29T22:03:11.657625Z","steps":["trace[1633407949] 'read index received' (duration: 4.170963ms)","trace[1633407949] 'applied index is now lower than readState.Index' (duration: 388.308603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:11.657762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:11.10543Z","time spent":"552.123456ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:03:11.658021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.860065ms","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-29T22:03:11.658068Z","caller":"traceutil/trace.go:171","msg":"trace[1538706783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"392.990537ms","start":"2026-05-29T22:03:11.265067Z","end":"2026-05-29T22:03:11.658058Z","steps":["trace[1538706783] 'agreement among raft nodes before linearized reading' (duration: 392.773043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:11.658112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:11.265033Z","time spent":"393.066109ms","remote":"127.0.0.1:55624","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\" "} {"level":"info","ts":"2026-05-29T22:03:11.65782Z","caller":"traceutil/trace.go:171","msg":"trace[1545525573] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"399.783208ms","start":"2026-05-29T22:03:11.258023Z","end":"2026-05-29T22:03:11.657807Z","steps":["trace[1545525573] 'process raft request' (duration: 399.325977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:11.658164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.557272ms","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-29T22:03:11.65828Z","caller":"traceutil/trace.go:171","msg":"trace[1132279414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"339.736547ms","start":"2026-05-29T22:03:11.318515Z","end":"2026-05-29T22:03:11.658252Z","steps":["trace[1132279414] 'agreement among raft nodes before linearized reading' (duration: 339.45416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:11.658342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:11.318484Z","time spent":"339.847168ms","remote":"127.0.0.1:55624","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-29T22:03:11.658451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.583052ms","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-29T22:03:11.65852Z","caller":"traceutil/trace.go:171","msg":"trace[490549741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"339.668074ms","start":"2026-05-29T22:03:11.318831Z","end":"2026-05-29T22:03:11.658499Z","steps":["trace[490549741] 'agreement among raft nodes before linearized reading' (duration: 339.452819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:11.658547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.685124ms","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-29T22:03:11.658587Z","caller":"traceutil/trace.go:171","msg":"trace[1668577312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"339.778876ms","start":"2026-05-29T22:03:11.318798Z","end":"2026-05-29T22:03:11.658577Z","steps":["trace[1668577312] 'agreement among raft nodes before linearized reading' (duration: 339.676953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:11.658638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:11.318774Z","time spent":"339.854898ms","remote":"127.0.0.1:55624","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-29T22:03:11.658622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.408008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T22:03:11.658209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:11.257995Z","time spent":"400.165686ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:03:11.658642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:11.318777Z","time spent":"339.857908ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T22:03:11.658764Z","caller":"traceutil/trace.go:171","msg":"trace[1515425168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"339.625673ms","start":"2026-05-29T22:03:11.319105Z","end":"2026-05-29T22:03:11.658731Z","steps":["trace[1515425168] 'agreement among raft nodes before linearized reading' (duration: 339.190294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:11.658831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:11.319096Z","time spent":"339.723975ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T22:03:11.832157Z","caller":"traceutil/trace.go:171","msg":"trace[1301778431] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"167.236621ms","start":"2026-05-29T22:03:11.66489Z","end":"2026-05-29T22:03:11.832126Z","steps":["trace[1301778431] 'process raft request' (duration: 167.070247ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:11.854074Z","caller":"traceutil/trace.go:171","msg":"trace[1332523025] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"188.501332ms","start":"2026-05-29T22:03:11.665545Z","end":"2026-05-29T22:03:11.854047Z","steps":["trace[1332523025] 'process raft request' (duration: 188.42416ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:11.85408Z","caller":"traceutil/trace.go:171","msg":"trace[1202466241] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"188.565834ms","start":"2026-05-29T22:03:11.665479Z","end":"2026-05-29T22:03:11.854045Z","steps":["trace[1202466241] 'process raft request' (duration: 188.356789ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:11.854328Z","caller":"traceutil/trace.go:171","msg":"trace[225190003] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1404; }","duration":"169.352397ms","start":"2026-05-29T22:03:11.684943Z","end":"2026-05-29T22:03:11.854295Z","steps":["trace[225190003] 'read index received' (duration: 146.991251ms)","trace[225190003] 'applied index is now lower than readState.Index' (duration: 22.359806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:11.854434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.473909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:11.854473Z","caller":"traceutil/trace.go:171","msg":"trace[2006048314] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1354; }","duration":"169.564752ms","start":"2026-05-29T22:03:11.684893Z","end":"2026-05-29T22:03:11.854458Z","steps":["trace[2006048314] 'agreement among raft nodes before linearized reading' (duration: 169.47881ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:11.854575Z","caller":"traceutil/trace.go:171","msg":"trace[1762207754] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"188.980172ms","start":"2026-05-29T22:03:11.665583Z","end":"2026-05-29T22:03:11.854563Z","steps":["trace[1762207754] 'process raft request' (duration: 188.42596ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:11.854665Z","caller":"traceutil/trace.go:171","msg":"trace[801944582] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"188.240085ms","start":"2026-05-29T22:03:11.666402Z","end":"2026-05-29T22:03:11.854642Z","steps":["trace[801944582] 'process raft request' (duration: 187.802785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:12.366643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.703471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:12.366741Z","caller":"traceutil/trace.go:171","msg":"trace[558689429] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1355; }","duration":"388.861175ms","start":"2026-05-29T22:03:11.977856Z","end":"2026-05-29T22:03:12.366717Z","steps":["trace[558689429] 'range keys from in-memory index tree' (duration: 388.63269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:12.366789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:11.977835Z","time spent":"388.944587ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-29T22:03:12.366801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.551172ms","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:507"} {"level":"info","ts":"2026-05-29T22:03:12.366895Z","caller":"traceutil/trace.go:171","msg":"trace[1752514328] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1355; }","duration":"238.695855ms","start":"2026-05-29T22:03:12.128175Z","end":"2026-05-29T22:03:12.366871Z","steps":["trace[1752514328] 'range keys from in-memory index tree' (duration: 238.321366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:12.367412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.313579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:12.36749Z","caller":"traceutil/trace.go:171","msg":"trace[956353808] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1355; }","duration":"134.479042ms","start":"2026-05-29T22:03:12.232994Z","end":"2026-05-29T22:03:12.367473Z","steps":["trace[956353808] 'count revisions from in-memory index tree' (duration: 134.227247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:12.880419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114681251305630,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T22:03:13.158368Z","caller":"traceutil/trace.go:171","msg":"trace[36239501] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"779.022319ms","start":"2026-05-29T22:03:12.379324Z","end":"2026-05-29T22:03:13.158347Z","steps":["trace[36239501] 'read index received' (duration: 778.918537ms)","trace[36239501] 'applied index is now lower than readState.Index' (duration: 102.872µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:13.158499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.160042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:13.158533Z","caller":"traceutil/trace.go:171","msg":"trace[233452201] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1356; }","duration":"779.246734ms","start":"2026-05-29T22:03:12.379276Z","end":"2026-05-29T22:03:13.158523Z","steps":["trace[233452201] 'agreement among raft nodes before linearized reading' (duration: 779.164772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.158561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:12.379262Z","time spent":"779.292845ms","remote":"127.0.0.1:55442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-05-29T22:03:13.158491Z","caller":"traceutil/trace.go:171","msg":"trace[333233300] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"786.533085ms","start":"2026-05-29T22:03:12.371911Z","end":"2026-05-29T22:03:13.158444Z","steps":["trace[333233300] 'process raft request' (duration: 786.315071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.158878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:12.371871Z","time spent":"786.75586ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:03:13.540967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.150353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:03:13.541261Z","caller":"traceutil/trace.go:171","msg":"trace[1192730062] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"563.756414ms","start":"2026-05-29T22:03:12.977457Z","end":"2026-05-29T22:03:13.541214Z","steps":["trace[1192730062] 'process raft request' (duration: 435.243603ms)","trace[1192730062] 'compare' (duration: 127.55549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:13.541331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043390074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-29T22:03:13.541377Z","caller":"traceutil/trace.go:171","msg":"trace[1689101557] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1357; }","duration":"1.043474695s","start":"2026-05-29T22:03:12.497889Z","end":"2026-05-29T22:03:13.541364Z","steps":["trace[1689101557] 'agreement among raft nodes before linearized reading' (duration: 1.043340902s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.541336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039896215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:03:13.541431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:12.497877Z","time spent":"1.043543456s","remote":"127.0.0.1:55418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-29T22:03:13.54145Z","caller":"traceutil/trace.go:171","msg":"trace[19822874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"1.040088499s","start":"2026-05-29T22:03:12.501336Z","end":"2026-05-29T22:03:13.541425Z","steps":["trace[19822874] 'agreement among raft nodes before linearized reading' (duration: 1.039816563s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.541451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:12.977277Z","time spent":"564.078551ms","remote":"127.0.0.1:55430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T22:03:13.541504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:12.501325Z","time spent":"1.040169331s","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T22:03:13.541163Z","caller":"traceutil/trace.go:171","msg":"trace[443881682] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"382.718279ms","start":"2026-05-29T22:03:13.158418Z","end":"2026-05-29T22:03:13.541137Z","steps":["trace[443881682] 'read index received' (duration: 254.380302ms)","trace[443881682] 'applied index is now lower than readState.Index' (duration: 128.335927ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:03:13.542121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051088483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129473"} {"level":"info","ts":"2026-05-29T22:03:13.542169Z","caller":"traceutil/trace.go:171","msg":"trace[1735390094] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1357; }","duration":"1.051177695s","start":"2026-05-29T22:03:12.490979Z","end":"2026-05-29T22:03:13.542157Z","steps":["trace[1735390094] 'agreement among raft nodes before linearized reading' (duration: 1.05094341s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.542207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:12.490962Z","time spent":"1.051237956s","remote":"127.0.0.1:55414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129496,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-29T22:03:13.582885Z","caller":"traceutil/trace.go:171","msg":"trace[35235141] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"422.41772ms","start":"2026-05-29T22:03:13.160435Z","end":"2026-05-29T22:03:13.582853Z","steps":["trace[35235141] 'process raft request' (duration: 422.258536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.582974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.314597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T22:03:13.583003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.124633ms","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-29T22:03:13.583027Z","caller":"traceutil/trace.go:171","msg":"trace[945569754] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1358; }","duration":"394.407768ms","start":"2026-05-29T22:03:13.188604Z","end":"2026-05-29T22:03:13.583011Z","steps":["trace[945569754] 'agreement among raft nodes before linearized reading' (duration: 394.278715ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:13.583064Z","caller":"traceutil/trace.go:171","msg":"trace[842719956] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1358; }","duration":"119.273166ms","start":"2026-05-29T22:03:13.463774Z","end":"2026-05-29T22:03:13.583047Z","steps":["trace[842719956] 'agreement among raft nodes before linearized reading' (duration: 119.107072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.583066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:13.188585Z","time spent":"394.47084ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-29T22:03:13.58305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:13.160411Z","time spent":"422.576054ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T22:03:13.700993Z","caller":"traceutil/trace.go:171","msg":"trace[888353579] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"111.394511ms","start":"2026-05-29T22:03:13.589556Z","end":"2026-05-29T22:03:13.700951Z","steps":["trace[888353579] 'process raft request' (duration: 111.065984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.701776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.576662ms","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-29T22:03:13.701897Z","caller":"traceutil/trace.go:171","msg":"trace[291980193] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1360; }","duration":"114.782346ms","start":"2026-05-29T22:03:13.587088Z","end":"2026-05-29T22:03:13.701871Z","steps":["trace[291980193] 'agreement among raft nodes before linearized reading' (duration: 114.251294ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:18.788717Z","caller":"traceutil/trace.go:171","msg":"trace[1992588998] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"618.435858ms","start":"2026-05-29T22:03:18.170244Z","end":"2026-05-29T22:03:18.78868Z","steps":["trace[1992588998] 'process raft request' (duration: 618.183113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:18.789136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:18.170209Z","time spent":"618.721914ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:03:19.75854Z","caller":"traceutil/trace.go:171","msg":"trace[564477305] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"426.635813ms","start":"2026-05-29T22:03:19.331861Z","end":"2026-05-29T22:03:19.758497Z","steps":["trace[564477305] 'read index received' (duration: 426.290056ms)","trace[564477305] 'applied index is now lower than readState.Index' (duration: 343.517µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:19.758595Z","caller":"traceutil/trace.go:171","msg":"trace[301953635] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"554.103361ms","start":"2026-05-29T22:03:19.204466Z","end":"2026-05-29T22:03:19.758569Z","steps":["trace[301953635] 'process raft request' (duration: 553.701162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:19.758862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:19.204441Z","time spent":"554.320206ms","remote":"127.0.0.1:55624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:03:19.759008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.102023ms","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:531"} {"level":"info","ts":"2026-05-29T22:03:19.759084Z","caller":"traceutil/trace.go:171","msg":"trace[673042587] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1404; }","duration":"427.229986ms","start":"2026-05-29T22:03:19.331836Z","end":"2026-05-29T22:03:19.759066Z","steps":["trace[673042587] 'agreement among raft nodes before linearized reading' (duration: 426.877068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:19.75915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:03:19.331821Z","time spent":"427.312957ms","remote":"127.0.0.1:55624","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-29T22:03:19.760059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.261848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:19.760129Z","caller":"traceutil/trace.go:171","msg":"trace[1960125949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1405; }","duration":"258.380561ms","start":"2026-05-29T22:03:19.501733Z","end":"2026-05-29T22:03:19.760113Z","steps":["trace[1960125949] 'agreement among raft nodes before linearized reading' (duration: 258.254878ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:07:16.782226Z","caller":"traceutil/trace.go:171","msg":"trace[1345073930] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"121.97372ms","start":"2026-05-29T22:07:16.660217Z","end":"2026-05-29T22:07:16.782191Z","steps":["trace[1345073930] 'process raft request' (duration: 121.730595ms)"],"step_count":1}