{"level":"warn","ts":"2026-08-19T09:05:27.13283Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:05:27.132914Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-08-19T09:05:27.132973Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:05:27.132985Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-19T09:05:27.133008Z","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-08-19T09:05:27.133671Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-08-19T09:05:27.133749Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-08-19T09:05:27.137883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.956655ms"} {"level":"info","ts":"2026-08-19T09:05:27.149662Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-08-19T09:05:27.149743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T09:05:27.149764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-08-19T09:05:27.149778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T09:05:27.149815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-08-19T09:05:27.149874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-08-19T09:05:27.156672Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T09:05:27.158528Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T09:05:27.161213Z","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-08-19T09:05:27.164743Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T09:05:27.164978Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T09:05:27.165113Z","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-08-19T09:05:27.165204Z","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-08-19T09:05:27.165216Z","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-08-19T09:05:27.17236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-08-19T09:05:27.172859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-19T09:05:27.175695Z","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-08-19T09:05:27.175822Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-19T09:05:27.175949Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-19T09:05:27.176124Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T09:05:27.176153Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T09:05:28.150127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T09:05:28.150179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T09:05:28.150209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-08-19T09:05:28.150229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-08-19T09:05:28.150241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-19T09:05:28.150253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-08-19T09:05:28.150267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-19T09:05:28.151367Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T09:05:28.151441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:05:28.151756Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T09:05:28.151854Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T09:05:28.151718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:05:28.152082Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:05:28.153946Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-08-19T09:05:28.153969Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:05:28.154321Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:05:28.154434Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:05:28.155153Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T09:05:40.124759Z","caller":"traceutil/trace.go:171","msg":"trace[1930442924] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"121.108605ms","start":"2026-08-19T09:05:40.003624Z","end":"2026-08-19T09:05:40.124733Z","steps":["trace[1930442924] 'process raft request' (duration: 120.874699ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:05:45.845897Z","caller":"traceutil/trace.go:171","msg":"trace[1803066725] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"112.586089ms","start":"2026-08-19T09:05:45.733286Z","end":"2026-08-19T09:05:45.845872Z","steps":["trace[1803066725] 'read index received' (duration: 112.374534ms)","trace[1803066725] 'applied index is now lower than readState.Index' (duration: 210.635µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:45.845967Z","caller":"traceutil/trace.go:171","msg":"trace[1641229929] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"218.827397ms","start":"2026-08-19T09:05:45.627107Z","end":"2026-08-19T09:05:45.845935Z","steps":["trace[1641229929] 'process raft request' (duration: 218.609571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:45.846116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.768664ms","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-08-19T09:05:45.846231Z","caller":"traceutil/trace.go:171","msg":"trace[1984231225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"112.944958ms","start":"2026-08-19T09:05:45.733244Z","end":"2026-08-19T09:05:45.846189Z","steps":["trace[1984231225] 'agreement among raft nodes before linearized reading' (duration: 112.746433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:46.938904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754593626,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:05:47.44007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754593626,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:05:47.75249Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.499709082s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-19T09:05:47.888958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635190812s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:05:47.88926Z","caller":"traceutil/trace.go:171","msg":"trace[1835393290] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"2.037356021s","start":"2026-08-19T09:05:45.851852Z","end":"2026-08-19T09:05:47.889208Z","steps":["trace[1835393290] 'process raft request' (duration: 400.883157ms)","trace[1835393290] 'compare' (duration: 1.634951487s)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:05:47.889464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:45.851836Z","time spent":"2.037508444s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:05:47.889881Z","caller":"traceutil/trace.go:171","msg":"trace[817262345] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"2.029854399s","start":"2026-08-19T09:05:45.859592Z","end":"2026-08-19T09:05:47.889446Z","steps":["trace[817262345] 'process raft request' (duration: 2.029572043s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:47.89005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:45.859574Z","time spent":"2.030371202s","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:05:48.379676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.35453ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-19T09:05:48.379863Z","caller":"traceutil/trace.go:171","msg":"trace[1749030085] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:385; }","duration":"1.942065377s","start":"2026-08-19T09:05:46.437778Z","end":"2026-08-19T09:05:48.379844Z","steps":["trace[1749030085] 'read index received' (duration: 1.314953645s)","trace[1749030085] 'applied index is now lower than readState.Index' (duration: 627.109932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:05:48.38Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.94220939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:05:48.380088Z","caller":"traceutil/trace.go:171","msg":"trace[210990612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"1.942338194s","start":"2026-08-19T09:05:46.437728Z","end":"2026-08-19T09:05:48.380066Z","steps":["trace[210990612] 'agreement among raft nodes before linearized reading' (duration: 1.942202511s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:48.380137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:46.437709Z","time spent":"1.942420896s","remote":"127.0.0.1:54648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:05:48.380174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.323998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-08-19T09:05:48.380212Z","caller":"traceutil/trace.go:171","msg":"trace[336162423] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:374; }","duration":"487.371828ms","start":"2026-08-19T09:05:47.892829Z","end":"2026-08-19T09:05:48.380201Z","steps":["trace[336162423] 'agreement among raft nodes before linearized reading' (duration: 487.287926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:48.380249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:47.89282Z","time spent":"487.42234ms","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7316,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-19T09:05:48.380028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.807692403s","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-08-19T09:05:48.380396Z","caller":"traceutil/trace.go:171","msg":"trace[596118501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"1.808110093s","start":"2026-08-19T09:05:46.572275Z","end":"2026-08-19T09:05:48.380385Z","steps":["trace[596118501] 'agreement among raft nodes before linearized reading' (duration: 1.807684933s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:48.38043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:46.572254Z","time spent":"1.808165665s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:05:48.380519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.401711ms","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-08-19T09:05:48.380562Z","caller":"traceutil/trace.go:171","msg":"trace[1501823520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:374; }","duration":"529.482883ms","start":"2026-08-19T09:05:47.851068Z","end":"2026-08-19T09:05:48.380551Z","steps":["trace[1501823520] 'agreement among raft nodes before linearized reading' (duration: 529.420511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:48.380591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:47.851046Z","time spent":"529.538143ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T09:05:48.380097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.856302ms","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-08-19T09:05:48.380742Z","caller":"traceutil/trace.go:171","msg":"trace[1808673335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:374; }","duration":"476.510467ms","start":"2026-08-19T09:05:47.904215Z","end":"2026-08-19T09:05:48.380726Z","steps":["trace[1808673335] 'agreement among raft nodes before linearized reading' (duration: 475.842881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:48.380797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:47.904205Z","time spent":"476.574939ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-19T09:05:50.343054Z","caller":"traceutil/trace.go:171","msg":"trace[998896149] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"156.777193ms","start":"2026-08-19T09:05:50.186233Z","end":"2026-08-19T09:05:50.34301Z","steps":["trace[998896149] 'process raft request' (duration: 156.363032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.724998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754593870,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T09:06:07.770198Z","caller":"traceutil/trace.go:171","msg":"trace[310673243] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"549.905673ms","start":"2026-08-19T09:06:07.220258Z","end":"2026-08-19T09:06:07.770164Z","steps":["trace[310673243] 'process raft request' (duration: 549.678178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.770411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.220229Z","time spent":"550.081389ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:06:07.776467Z","caller":"traceutil/trace.go:171","msg":"trace[1046258040] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"549.776672ms","start":"2026-08-19T09:06:07.226662Z","end":"2026-08-19T09:06:07.776438Z","steps":["trace[1046258040] 'process raft request' (duration: 547.791624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.776608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.226652Z","time spent":"549.880334ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:06:07.77668Z","caller":"traceutil/trace.go:171","msg":"trace[1559734860] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"548.617183ms","start":"2026-08-19T09:06:07.228039Z","end":"2026-08-19T09:06:07.776656Z","steps":["trace[1559734860] 'process raft request' (duration: 548.299436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.776783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.228017Z","time spent":"548.718575ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:06:07.776792Z","caller":"traceutil/trace.go:171","msg":"trace[102016668] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"541.118323ms","start":"2026-08-19T09:06:07.235636Z","end":"2026-08-19T09:06:07.776754Z","steps":["trace[102016668] 'process raft request' (duration: 540.843736ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:07.776895Z","caller":"traceutil/trace.go:171","msg":"trace[557363188] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"541.304017ms","start":"2026-08-19T09:06:07.235582Z","end":"2026-08-19T09:06:07.776886Z","steps":["trace[557363188] 'process raft request' (duration: 540.831745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.77695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.235567Z","time spent":"541.349627ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:07.777021Z","caller":"traceutil/trace.go:171","msg":"trace[1856781422] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"541.199235ms","start":"2026-08-19T09:06:07.235814Z","end":"2026-08-19T09:06:07.777014Z","steps":["trace[1856781422] 'process raft request' (duration: 540.838246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.777055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.23581Z","time spent":"541.225525ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:06:07.77706Z","caller":"traceutil/trace.go:171","msg":"trace[888095163] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"541.303017ms","start":"2026-08-19T09:06:07.235707Z","end":"2026-08-19T09:06:07.77701Z","steps":["trace[888095163] 'process raft request' (duration: 540.880707ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:07.777138Z","caller":"traceutil/trace.go:171","msg":"trace[726475932] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"534.552794ms","start":"2026-08-19T09:06:07.242576Z","end":"2026-08-19T09:06:07.777129Z","steps":["trace[726475932] 'process raft request' (duration: 534.135375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.777171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.242515Z","time spent":"534.640127ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:06:07.777136Z","caller":"traceutil/trace.go:171","msg":"trace[730468889] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:519; }","duration":"553.09055ms","start":"2026-08-19T09:06:07.223984Z","end":"2026-08-19T09:06:07.777075Z","steps":["trace[730468889] 'read index received' (duration: 545.801344ms)","trace[730468889] 'applied index is now lower than readState.Index' (duration: 7.287396ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:07.777263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.235702Z","time spent":"541.45586ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:07.777396Z","caller":"traceutil/trace.go:171","msg":"trace[1793786296] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"399.164975ms","start":"2026-08-19T09:06:07.378212Z","end":"2026-08-19T09:06:07.777377Z","steps":["trace[1793786296] 'process raft request' (duration: 398.648213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.777515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.272839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:06:07.77767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.378185Z","time spent":"399.301719ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:06:07.777571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.569951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-08-19T09:06:07.778381Z","caller":"traceutil/trace.go:171","msg":"trace[246667035] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:507; }","duration":"554.38119ms","start":"2026-08-19T09:06:07.223958Z","end":"2026-08-19T09:06:07.77834Z","steps":["trace[246667035] 'agreement among raft nodes before linearized reading' (duration: 553.445688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.778537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.223947Z","time spent":"554.499275ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10100,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-19T09:06:07.780146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.235633Z","time spent":"541.314537ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:07.781967Z","caller":"traceutil/trace.go:171","msg":"trace[253931496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"340.447582ms","start":"2026-08-19T09:06:07.437189Z","end":"2026-08-19T09:06:07.777636Z","steps":["trace[253931496] 'agreement among raft nodes before linearized reading' (duration: 340.243188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:07.782027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:07.437178Z","time spent":"344.830548ms","remote":"127.0.0.1:54632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:06:08.87126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.83966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.87134Z","caller":"traceutil/trace.go:171","msg":"trace[552084341] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:547; }","duration":"299.954973ms","start":"2026-08-19T09:06:08.571368Z","end":"2026-08-19T09:06:08.871323Z","steps":["trace[552084341] 'range keys from in-memory index tree' (duration: 299.793569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.871374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.150552ms","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-08-19T09:06:08.871407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.083426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.871428Z","caller":"traceutil/trace.go:171","msg":"trace[680040681] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:547; }","duration":"300.132737ms","start":"2026-08-19T09:06:08.571289Z","end":"2026-08-19T09:06:08.871422Z","steps":["trace[680040681] 'range keys from in-memory index tree' (duration: 299.976533ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:08.871427Z","caller":"traceutil/trace.go:171","msg":"trace[89386248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"341.221534ms","start":"2026-08-19T09:06:08.530188Z","end":"2026-08-19T09:06:08.871409Z","steps":["trace[89386248] 'range keys from in-memory index tree' (duration: 341.011229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.871445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:08.57127Z","time spent":"300.170617ms","remote":"127.0.0.1:38394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-08-19T09:06:08.871477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:08.530138Z","time spent":"341.330756ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:06:08.871379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:08.571366Z","time spent":"300.005194ms","remote":"127.0.0.1:38394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-08-19T09:06:08.8717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.671532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.87172Z","caller":"traceutil/trace.go:171","msg":"trace[545187937] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:547; }","duration":"295.694453ms","start":"2026-08-19T09:06:08.57602Z","end":"2026-08-19T09:06:08.871715Z","steps":["trace[545187937] 'count revisions from in-memory index tree' (duration: 295.622821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.871741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.276612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:06:08.871757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.542538ms","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-08-19T09:06:08.871852Z","caller":"traceutil/trace.go:171","msg":"trace[444198902] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:547; }","duration":"340.458586ms","start":"2026-08-19T09:06:08.531372Z","end":"2026-08-19T09:06:08.871831Z","steps":["trace[444198902] 'range keys from in-memory index tree' (duration: 340.19649ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:08.871876Z","caller":"traceutil/trace.go:171","msg":"trace[54091329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"340.695162ms","start":"2026-08-19T09:06:08.531164Z","end":"2026-08-19T09:06:08.871859Z","steps":["trace[54091329] 'range keys from in-memory index tree' (duration: 340.391634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.871916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:08.531358Z","time spent":"340.548668ms","remote":"127.0.0.1:38388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-08-19T09:06:08.87192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:08.531156Z","time spent":"340.755983ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T09:06:08.872071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.418529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.872098Z","caller":"traceutil/trace.go:171","msg":"trace[164848779] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:547; }","duration":"341.49443ms","start":"2026-08-19T09:06:08.530595Z","end":"2026-08-19T09:06:08.872089Z","steps":["trace[164848779] 'range keys from in-memory index tree' (duration: 341.320727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.872118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:08.530569Z","time spent":"341.542951ms","remote":"127.0.0.1:38388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-08-19T09:06:08.872149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.478469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:06:08.871253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.824189ms","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-08-19T09:06:08.872186Z","caller":"traceutil/trace.go:171","msg":"trace[825697851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"303.818742ms","start":"2026-08-19T09:06:08.568361Z","end":"2026-08-19T09:06:08.87218Z","steps":["trace[825697851] 'range keys from in-memory index tree' (duration: 302.607423ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:08.872228Z","caller":"traceutil/trace.go:171","msg":"trace[963669332] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"269.615954ms","start":"2026-08-19T09:06:08.602605Z","end":"2026-08-19T09:06:08.872221Z","steps":["trace[963669332] 'read index received' (duration: 265.666202ms)","trace[963669332] 'applied index is now lower than readState.Index' (duration: 3.949042ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:08.872239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:08.568337Z","time spent":"303.895804ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-19T09:06:08.872189Z","caller":"traceutil/trace.go:171","msg":"trace[171665590] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:547; }","duration":"341.546651ms","start":"2026-08-19T09:06:08.530626Z","end":"2026-08-19T09:06:08.872173Z","steps":["trace[171665590] 'count revisions from in-memory index tree' (duration: 341.377867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.872307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:08.530605Z","time spent":"341.693785ms","remote":"127.0.0.1:38388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:06:08.872326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.721067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.872347Z","caller":"traceutil/trace.go:171","msg":"trace[544782537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:548; }","duration":"269.772938ms","start":"2026-08-19T09:06:08.602567Z","end":"2026-08-19T09:06:08.87234Z","steps":["trace[544782537] 'agreement among raft nodes before linearized reading' (duration: 269.721177ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:08.87228Z","caller":"traceutil/trace.go:171","msg":"trace[1233942691] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"287.897181ms","start":"2026-08-19T09:06:08.584377Z","end":"2026-08-19T09:06:08.872274Z","steps":["trace[1233942691] 'process raft request' (duration: 283.802945ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:08.880039Z","caller":"traceutil/trace.go:171","msg":"trace[396793743] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"244.52393ms","start":"2026-08-19T09:06:08.635484Z","end":"2026-08-19T09:06:08.880008Z","steps":["trace[396793743] 'process raft request' (duration: 243.442525ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:08.887206Z","caller":"traceutil/trace.go:171","msg":"trace[1171033286] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"201.753483ms","start":"2026-08-19T09:06:08.685437Z","end":"2026-08-19T09:06:08.88719Z","steps":["trace[1171033286] 'process raft request' (duration: 194.74012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.887381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.684816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.887412Z","caller":"traceutil/trace.go:171","msg":"trace[985912135] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:553; }","duration":"284.745267ms","start":"2026-08-19T09:06:08.602655Z","end":"2026-08-19T09:06:08.887401Z","steps":["trace[985912135] 'agreement among raft nodes before linearized reading' (duration: 284.671965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.887493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.940651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:06:08.887534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.836314ms","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-08-19T09:06:08.887562Z","caller":"traceutil/trace.go:171","msg":"trace[1526593424] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:553; }","duration":"234.884545ms","start":"2026-08-19T09:06:08.652669Z","end":"2026-08-19T09:06:08.887554Z","steps":["trace[1526593424] 'agreement among raft nodes before linearized reading' (duration: 234.840554ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:08.887558Z","caller":"traceutil/trace.go:171","msg":"trace[1176316171] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:553; }","duration":"197.045493ms","start":"2026-08-19T09:06:08.690494Z","end":"2026-08-19T09:06:08.88754Z","steps":["trace[1176316171] 'agreement among raft nodes before linearized reading' (duration: 196.950921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.887685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.193283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.887708Z","caller":"traceutil/trace.go:171","msg":"trace[1273018998] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:553; }","duration":"235.246164ms","start":"2026-08-19T09:06:08.652455Z","end":"2026-08-19T09:06:08.887701Z","steps":["trace[1273018998] 'agreement among raft nodes before linearized reading' (duration: 235.210253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.887753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.82954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:06:08.88783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.268498ms","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-08-19T09:06:08.887835Z","caller":"traceutil/trace.go:171","msg":"trace[993662894] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:553; }","duration":"269.945393ms","start":"2026-08-19T09:06:08.617873Z","end":"2026-08-19T09:06:08.887818Z","steps":["trace[993662894] 'agreement among raft nodes before linearized reading' (duration: 269.82087ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:08.887853Z","caller":"traceutil/trace.go:171","msg":"trace[2019159173] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:553; }","duration":"197.301958ms","start":"2026-08-19T09:06:08.690544Z","end":"2026-08-19T09:06:08.887846Z","steps":["trace[2019159173] 'agreement among raft nodes before linearized reading' (duration: 197.270828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.888011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.607126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.888035Z","caller":"traceutil/trace.go:171","msg":"trace[511704664] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:553; }","duration":"269.643976ms","start":"2026-08-19T09:06:08.618383Z","end":"2026-08-19T09:06:08.888027Z","steps":["trace[511704664] 'agreement among raft nodes before linearized reading' (duration: 269.605596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.887775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.574983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.888125Z","caller":"traceutil/trace.go:171","msg":"trace[280651917] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:553; }","duration":"196.932641ms","start":"2026-08-19T09:06:08.691181Z","end":"2026-08-19T09:06:08.888114Z","steps":["trace[280651917] 'agreement among raft nodes before linearized reading' (duration: 196.572462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:08.888207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.26614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:08.888229Z","caller":"traceutil/trace.go:171","msg":"trace[67003155] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:553; }","duration":"270.307092ms","start":"2026-08-19T09:06:08.617915Z","end":"2026-08-19T09:06:08.888222Z","steps":["trace[67003155] 'agreement among raft nodes before linearized reading' (duration: 270.268291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:14.529829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754594058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:06:15.030982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754594058,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T09:06:15.460783Z","caller":"traceutil/trace.go:171","msg":"trace[1183136971] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"1.433967907s","start":"2026-08-19T09:06:14.026792Z","end":"2026-08-19T09:06:15.46076Z","steps":["trace[1183136971] 'read index received' (duration: 1.433297531s)","trace[1183136971] 'applied index is now lower than readState.Index' (duration: 669.406µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:15.460886Z","caller":"traceutil/trace.go:171","msg":"trace[2105852042] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.479062417s","start":"2026-08-19T09:06:13.981817Z","end":"2026-08-19T09:06:15.460879Z","steps":["trace[2105852042] 'process raft request' (duration: 1.478465004s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:15.460972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:13.981783Z","time spent":"1.479124389s","remote":"127.0.0.1:54756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:15.461211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.937583ms","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-08-19T09:06:15.461257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434472358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T09:06:15.461286Z","caller":"traceutil/trace.go:171","msg":"trace[1173117239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.434538419s","start":"2026-08-19T09:06:14.026738Z","end":"2026-08-19T09:06:15.461276Z","steps":["trace[1173117239] 'agreement among raft nodes before linearized reading' (duration: 1.434428707s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:15.461306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:14.026717Z","time spent":"1.4345844s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-19T09:06:15.461295Z","caller":"traceutil/trace.go:171","msg":"trace[1462102677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"548.094776ms","start":"2026-08-19T09:06:14.913176Z","end":"2026-08-19T09:06:15.46127Z","steps":["trace[1462102677] 'agreement among raft nodes before linearized reading' (duration: 547.873001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:15.461341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:14.913157Z","time spent":"548.174849ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T09:06:15.461349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382664761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dvrx9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-19T09:06:15.461454Z","caller":"traceutil/trace.go:171","msg":"trace[744148764] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dvrx9; range_end:; response_count:1; response_revision:593; }","duration":"1.382825515s","start":"2026-08-19T09:06:14.078601Z","end":"2026-08-19T09:06:15.461427Z","steps":["trace[744148764] 'agreement among raft nodes before linearized reading' (duration: 1.382438296s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:15.461488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.208055ms","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-08-19T09:06:15.461516Z","caller":"traceutil/trace.go:171","msg":"trace[1549999075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"550.275887ms","start":"2026-08-19T09:06:14.911233Z","end":"2026-08-19T09:06:15.461509Z","steps":["trace[1549999075] 'agreement among raft nodes before linearized reading' (duration: 550.212805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:15.461509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:14.078583Z","time spent":"1.382917217s","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-dvrx9\" "} {"level":"warn","ts":"2026-08-19T09:06:15.461535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:14.911202Z","time spent":"550.328357ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:06:15.46161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023740803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:15.461688Z","caller":"traceutil/trace.go:171","msg":"trace[1764750592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.023804224s","start":"2026-08-19T09:06:14.437863Z","end":"2026-08-19T09:06:15.461668Z","steps":["trace[1764750592] 'agreement among raft nodes before linearized reading' (duration: 1.023716532s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:15.461723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:14.437801Z","time spent":"1.023916338s","remote":"127.0.0.1:54648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:06:15.461912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.622635ms","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-08-19T09:06:15.461959Z","caller":"traceutil/trace.go:171","msg":"trace[586802845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"548.058835ms","start":"2026-08-19T09:06:14.913878Z","end":"2026-08-19T09:06:15.461937Z","steps":["trace[586802845] 'agreement among raft nodes before linearized reading' (duration: 547.483141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:15.461981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:14.913853Z","time spent":"548.120947ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:06:15.969526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754594062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:06:16.470507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754594062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:06:16.600524Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.133478981s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T09:06:16.600954Z","caller":"traceutil/trace.go:171","msg":"trace[1358798413] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.134590357s","start":"2026-08-19T09:06:15.466337Z","end":"2026-08-19T09:06:16.600927Z","steps":["trace[1358798413] 'process raft request' (duration: 1.134423863s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:16.601089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:15.466285Z","time spent":"1.134726361s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:17.042846Z","caller":"traceutil/trace.go:171","msg":"trace[1031724194] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.576008287s","start":"2026-08-19T09:06:15.46682Z","end":"2026-08-19T09:06:17.042828Z","steps":["trace[1031724194] 'process raft request' (duration: 1.556762088s)","trace[1031724194] 'compare' (duration: 18.88493ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:17.042953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:15.466806Z","time spent":"1.57609081s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:17.043Z","caller":"traceutil/trace.go:171","msg":"trace[177531451] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.562907261s","start":"2026-08-19T09:06:15.480066Z","end":"2026-08-19T09:06:17.042973Z","steps":["trace[177531451] 'process raft request' (duration: 1.562780859s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:17.042969Z","caller":"traceutil/trace.go:171","msg":"trace[689301008] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.573591591s","start":"2026-08-19T09:06:15.469347Z","end":"2026-08-19T09:06:17.042939Z","steps":["trace[689301008] 'process raft request' (duration: 1.573466638s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:17.043082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:15.480039Z","time spent":"1.563001294s","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:17.043135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:15.469341Z","time spent":"1.573716023s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:17.043182Z","caller":"traceutil/trace.go:171","msg":"trace[847487950] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.574046451s","start":"2026-08-19T09:06:15.469128Z","end":"2026-08-19T09:06:17.043175Z","steps":["trace[847487950] 'process raft request' (duration: 1.573644322s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:17.04322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:15.469121Z","time spent":"1.574079433s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:17.043205Z","caller":"traceutil/trace.go:171","msg":"trace[1165892696] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.527516197s","start":"2026-08-19T09:06:15.51566Z","end":"2026-08-19T09:06:17.043176Z","steps":["trace[1165892696] 'process raft request' (duration: 1.52721824s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:17.043344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:15.515619Z","time spent":"1.52765339s","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:06:17.478477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00897478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-19T09:06:17.478578Z","caller":"traceutil/trace.go:171","msg":"trace[847273061] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.009127123s","start":"2026-08-19T09:06:15.469432Z","end":"2026-08-19T09:06:17.47856Z","steps":["trace[847273061] 'agreement among raft nodes before linearized reading' (duration: 2.008994969s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:17.478618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:15.469412Z","time spent":"2.009198975s","remote":"127.0.0.1:54632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:06:18.034541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.616665ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-19T09:06:18.034642Z","caller":"traceutil/trace.go:171","msg":"trace[1859182072] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:615; }","duration":"2.565160895s","start":"2026-08-19T09:06:15.469464Z","end":"2026-08-19T09:06:18.034625Z","steps":["trace[1859182072] 'read index received' (duration: 1.131354423s)","trace[1859182072] 'applied index is now lower than readState.Index' (duration: 1.433804622s)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:18.035071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.346696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:18.035208Z","caller":"traceutil/trace.go:171","msg":"trace[209854975] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:599; }","duration":"981.54007ms","start":"2026-08-19T09:06:17.053653Z","end":"2026-08-19T09:06:18.035193Z","steps":["trace[209854975] 'agreement among raft nodes before linearized reading' (duration: 981.372526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:18.03525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:17.053622Z","time spent":"981.614602ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-19T09:06:18.035525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.062962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:18.03559Z","caller":"traceutil/trace.go:171","msg":"trace[511969476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"556.159295ms","start":"2026-08-19T09:06:17.479414Z","end":"2026-08-19T09:06:18.035573Z","steps":["trace[511969476] 'agreement among raft nodes before linearized reading' (duration: 556.055843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:18.035627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:17.479402Z","time spent":"556.217836ms","remote":"127.0.0.1:54648","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-08-19T09:06:18.148682Z","caller":"traceutil/trace.go:171","msg":"trace[1230651848] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"103.916543ms","start":"2026-08-19T09:06:18.044746Z","end":"2026-08-19T09:06:18.148662Z","steps":["trace[1230651848] 'read index received' (duration: 103.711658ms)","trace[1230651848] 'applied index is now lower than readState.Index' (duration: 204.035µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:18.148722Z","caller":"traceutil/trace.go:171","msg":"trace[2007236392] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"106.560404ms","start":"2026-08-19T09:06:18.042131Z","end":"2026-08-19T09:06:18.148692Z","steps":["trace[2007236392] 'process raft request' (duration: 106.38533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:18.148799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.043065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:18.148858Z","caller":"traceutil/trace.go:171","msg":"trace[1393499012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"104.135037ms","start":"2026-08-19T09:06:18.044713Z","end":"2026-08-19T09:06:18.148848Z","steps":["trace[1393499012] 'agreement among raft nodes before linearized reading' (duration: 104.030294ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:20.469742Z","caller":"traceutil/trace.go:171","msg":"trace[1193558769] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"178.733836ms","start":"2026-08-19T09:06:20.290984Z","end":"2026-08-19T09:06:20.469718Z","steps":["trace[1193558769] 'process raft request' (duration: 178.606893ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:32.976301Z","caller":"traceutil/trace.go:171","msg":"trace[580495791] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"300.912123ms","start":"2026-08-19T09:06:32.675358Z","end":"2026-08-19T09:06:32.97627Z","steps":["trace[580495791] 'process raft request' (duration: 300.683588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:32.976495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:32.675347Z","time spent":"301.053167ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:39.712038Z","caller":"traceutil/trace.go:171","msg":"trace[186329848] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"161.28214ms","start":"2026-08-19T09:06:39.550729Z","end":"2026-08-19T09:06:39.712011Z","steps":["trace[186329848] 'process raft request' (duration: 160.957603ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:46.297108Z","caller":"traceutil/trace.go:171","msg":"trace[62460397] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"665.679147ms","start":"2026-08-19T09:06:45.63141Z","end":"2026-08-19T09:06:46.297089Z","steps":["trace[62460397] 'process raft request' (duration: 665.569774ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:46.297163Z","caller":"traceutil/trace.go:171","msg":"trace[1377851355] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:922; }","duration":"486.842547ms","start":"2026-08-19T09:06:45.810272Z","end":"2026-08-19T09:06:46.297114Z","steps":["trace[1377851355] 'read index received' (duration: 486.820447ms)","trace[1377851355] 'applied index is now lower than readState.Index' (duration: 17.32µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:46.297231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:45.631401Z","time spent":"665.759538ms","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5468,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:46.297411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.104873ms","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-08-19T09:06:46.297496Z","caller":"traceutil/trace.go:171","msg":"trace[1526293551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"487.235807ms","start":"2026-08-19T09:06:45.810243Z","end":"2026-08-19T09:06:46.297479Z","steps":["trace[1526293551] 'agreement among raft nodes before linearized reading' (duration: 486.983381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:46.29754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:45.810227Z","time spent":"487.304119ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-19T09:06:47.296543Z","caller":"traceutil/trace.go:171","msg":"trace[1055725731] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"994.148074ms","start":"2026-08-19T09:06:46.302384Z","end":"2026-08-19T09:06:47.296532Z","steps":["trace[1055725731] 'process raft request' (duration: 994.074412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:47.296633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:46.302369Z","time spent":"994.216405ms","remote":"127.0.0.1:54824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1104,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:48.083281Z","caller":"traceutil/trace.go:171","msg":"trace[1521397518] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.779375938s","start":"2026-08-19T09:06:46.303883Z","end":"2026-08-19T09:06:48.083258Z","steps":["trace[1521397518] 'process raft request' (duration: 1.779156703s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:48.083338Z","caller":"traceutil/trace.go:171","msg":"trace[1433015472] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.777886073s","start":"2026-08-19T09:06:46.305434Z","end":"2026-08-19T09:06:48.08332Z","steps":["trace[1433015472] 'process raft request' (duration: 1.777783021s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:48.083335Z","caller":"traceutil/trace.go:171","msg":"trace[1044947401] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"1.779404498s","start":"2026-08-19T09:06:46.30391Z","end":"2026-08-19T09:06:48.083315Z","steps":["trace[1044947401] 'process raft request' (duration: 1.779261645s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:48.083382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:46.303865Z","time spent":"1.779466109s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:48.083422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:46.305415Z","time spent":"1.777959334s","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:48.083474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:46.303894Z","time spent":"1.77951241s","remote":"127.0.0.1:54934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:48.21062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.335958ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-19T09:06:48.211205Z","caller":"traceutil/trace.go:171","msg":"trace[1778867945] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"1.226954205s","start":"2026-08-19T09:06:46.984219Z","end":"2026-08-19T09:06:48.211173Z","steps":["trace[1778867945] 'process raft request' (duration: 1.226481013s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:48.211369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:46.984206Z","time spent":"1.227072078s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:48.212002Z","caller":"traceutil/trace.go:171","msg":"trace[2134116451] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:923; }","duration":"1.115546824s","start":"2026-08-19T09:06:47.095558Z","end":"2026-08-19T09:06:48.211105Z","steps":["trace[2134116451] 'read index received' (duration: 201.303553ms)","trace[2134116451] 'applied index is now lower than readState.Index' (duration: 914.241021ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:48.233537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.1838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:06:48.233614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.406201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:48.233683Z","caller":"traceutil/trace.go:171","msg":"trace[700733640] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:897; }","duration":"219.437676ms","start":"2026-08-19T09:06:48.014197Z","end":"2026-08-19T09:06:48.233634Z","steps":["trace[700733640] 'agreement among raft nodes before linearized reading' (duration: 197.952514ms)","trace[700733640] 'count revisions from in-memory index tree' (duration: 21.267946ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:48.23372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.488057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:48.233749Z","caller":"traceutil/trace.go:171","msg":"trace[893999224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"796.538068ms","start":"2026-08-19T09:06:47.437202Z","end":"2026-08-19T09:06:48.23374Z","steps":["trace[893999224] 'agreement among raft nodes before linearized reading' (duration: 774.974975ms)","trace[893999224] 'range keys from in-memory index tree' (duration: 21.528802ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:48.23368Z","caller":"traceutil/trace.go:171","msg":"trace[1962474456] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:897; }","duration":"272.524663ms","start":"2026-08-19T09:06:47.961136Z","end":"2026-08-19T09:06:48.233661Z","steps":["trace[1962474456] 'agreement among raft nodes before linearized reading' (duration: 251.030292ms)","trace[1962474456] 'count revisions from in-memory index tree' (duration: 21.386259ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:48.233784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:47.437184Z","time spent":"796.59242ms","remote":"127.0.0.1:54632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:06:48.2339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.053316ms","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-08-19T09:06:48.233907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.281075ms","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-08-19T09:06:48.233944Z","caller":"traceutil/trace.go:171","msg":"trace[1604625832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"944.106677ms","start":"2026-08-19T09:06:47.289822Z","end":"2026-08-19T09:06:48.233928Z","steps":["trace[1604625832] 'agreement among raft nodes before linearized reading' (duration: 922.379531ms)","trace[1604625832] 'range keys from in-memory index tree' (duration: 21.563773ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:48.233947Z","caller":"traceutil/trace.go:171","msg":"trace[1359430302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"921.360987ms","start":"2026-08-19T09:06:47.312574Z","end":"2026-08-19T09:06:48.233935Z","steps":["trace[1359430302] 'agreement among raft nodes before linearized reading' (duration: 899.61112ms)","trace[1359430302] 'range keys from in-memory index tree' (duration: 21.654555ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:48.233971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:47.289815Z","time spent":"944.148698ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T09:06:48.233973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:47.312553Z","time spent":"921.413548ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:06:48.234181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.337482ms","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-08-19T09:06:48.234234Z","caller":"traceutil/trace.go:171","msg":"trace[1835976751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"953.408494ms","start":"2026-08-19T09:06:47.280812Z","end":"2026-08-19T09:06:48.234221Z","steps":["trace[1835976751] 'agreement among raft nodes before linearized reading' (duration: 931.309999ms)","trace[1835976751] 'range keys from in-memory index tree' (duration: 21.641245ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:48.23429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:47.2808Z","time spent":"953.478516ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:06:48.234846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138872208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T09:06:48.234882Z","caller":"traceutil/trace.go:171","msg":"trace[469282385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"1.139337719s","start":"2026-08-19T09:06:47.095531Z","end":"2026-08-19T09:06:48.234869Z","steps":["trace[469282385] 'agreement among raft nodes before linearized reading' (duration: 1.11578379s)","trace[469282385] 'range keys from in-memory index tree' (duration: 22.378931ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:48.235983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:47.095519Z","time spent":"1.140451764s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:06:48.974471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754594673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:06:49.342086Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.071570438s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-19T09:06:49.731315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.010498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:06:49.731449Z","caller":"traceutil/trace.go:171","msg":"trace[1359069122] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"1.49074773s","start":"2026-08-19T09:06:48.24069Z","end":"2026-08-19T09:06:49.731438Z","steps":["trace[1359069122] 'process raft request' (duration: 1.490692849s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:49.73149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:48.240677Z","time spent":"1.490793441s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:49.731574Z","caller":"traceutil/trace.go:171","msg":"trace[1947606413] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"1.494017586s","start":"2026-08-19T09:06:48.237524Z","end":"2026-08-19T09:06:49.731542Z","steps":["trace[1947606413] 'process raft request' (duration: 1.104722301s)","trace[1947606413] 'compare' (duration: 388.627119ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:49.731654Z","caller":"traceutil/trace.go:171","msg":"trace[1168555488] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:930; }","duration":"1.258063625s","start":"2026-08-19T09:06:48.473559Z","end":"2026-08-19T09:06:49.731623Z","steps":["trace[1168555488] 'read index received' (duration: 868.641428ms)","trace[1168555488] 'applied index is now lower than readState.Index' (duration: 389.419677ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:49.731711Z","caller":"traceutil/trace.go:171","msg":"trace[1695586615] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"1.490571696s","start":"2026-08-19T09:06:48.241125Z","end":"2026-08-19T09:06:49.731696Z","steps":["trace[1695586615] 'process raft request' (duration: 1.49029268s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:49.731722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:48.237504Z","time spent":"1.494130709s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:49.731813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:48.241118Z","time spent":"1.490630727s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:49.732033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258448005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:49.732076Z","caller":"traceutil/trace.go:171","msg":"trace[1033066094] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:902; }","duration":"1.258534627s","start":"2026-08-19T09:06:48.473525Z","end":"2026-08-19T09:06:49.732059Z","steps":["trace[1033066094] 'agreement among raft nodes before linearized reading' (duration: 1.25823585s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:49.732107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:48.473509Z","time spent":"1.258590629s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:06:49.732412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.866404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:06:49.73245Z","caller":"traceutil/trace.go:171","msg":"trace[1888445234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"294.922206ms","start":"2026-08-19T09:06:49.437506Z","end":"2026-08-19T09:06:49.732429Z","steps":["trace[1888445234] 'agreement among raft nodes before linearized reading' (duration: 294.873515ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:50.453219Z","caller":"traceutil/trace.go:171","msg":"trace[2045635577] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"176.477544ms","start":"2026-08-19T09:06:50.276724Z","end":"2026-08-19T09:06:50.453201Z","steps":["trace[2045635577] 'process raft request' (duration: 110.984458ms)","trace[2045635577] 'compare' (duration: 65.416934ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:56.159402Z","caller":"traceutil/trace.go:171","msg":"trace[2078545814] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"304.243163ms","start":"2026-08-19T09:06:55.855132Z","end":"2026-08-19T09:06:56.159375Z","steps":["trace[2078545814] 'process raft request' (duration: 304.014737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:56.159577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:55.85512Z","time spent":"304.371475ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:56.27493Z","caller":"traceutil/trace.go:171","msg":"trace[996002149] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"419.243523ms","start":"2026-08-19T09:06:55.855658Z","end":"2026-08-19T09:06:56.274902Z","steps":["trace[996002149] 'process raft request' (duration: 415.797783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:56.275369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:55.855647Z","time spent":"419.453008ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:56.276422Z","caller":"traceutil/trace.go:171","msg":"trace[995452786] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"108.441608ms","start":"2026-08-19T09:06:56.16796Z","end":"2026-08-19T09:06:56.276402Z","steps":["trace[995452786] 'read index received' (duration: 103.485082ms)","trace[995452786] 'applied index is now lower than readState.Index' (duration: 4.954586ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:06:56.276831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.870878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-19T09:06:56.2769Z","caller":"traceutil/trace.go:171","msg":"trace[1756972884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"108.988381ms","start":"2026-08-19T09:06:56.167899Z","end":"2026-08-19T09:06:56.276888Z","steps":["trace[1756972884] 'agreement among raft nodes before linearized reading' (duration: 108.623052ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:20.397782Z","caller":"traceutil/trace.go:171","msg":"trace[655177730] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"428.680436ms","start":"2026-08-19T09:07:19.969082Z","end":"2026-08-19T09:07:20.397763Z","steps":["trace[655177730] 'process raft request' (duration: 428.570894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:20.397889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:19.969062Z","time spent":"428.774898ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:07:20.397985Z","caller":"traceutil/trace.go:171","msg":"trace[1067863396] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"141.868634ms","start":"2026-08-19T09:07:20.256103Z","end":"2026-08-19T09:07:20.397972Z","steps":["trace[1067863396] 'read index received' (duration: 141.507536ms)","trace[1067863396] 'applied index is now lower than readState.Index' (duration: 360.288µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:07:20.3981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.002067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T09:07:20.398128Z","caller":"traceutil/trace.go:171","msg":"trace[1400689659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"142.064128ms","start":"2026-08-19T09:07:20.256057Z","end":"2026-08-19T09:07:20.398121Z","steps":["trace[1400689659] 'agreement among raft nodes before linearized reading' (duration: 141.971286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:21.684483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.737474ms","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-08-19T09:07:21.684608Z","caller":"traceutil/trace.go:171","msg":"trace[749624685] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1014; }","duration":"106.870438ms","start":"2026-08-19T09:07:21.577718Z","end":"2026-08-19T09:07:21.684588Z","steps":["trace[749624685] 'range keys from in-memory index tree' (duration: 106.722214ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:22.768992Z","caller":"traceutil/trace.go:171","msg":"trace[951226064] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"366.840229ms","start":"2026-08-19T09:07:22.402103Z","end":"2026-08-19T09:07:22.768943Z","steps":["trace[951226064] 'read index received' (duration: 366.684895ms)","trace[951226064] 'applied index is now lower than readState.Index' (duration: 150.024µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:07:22.769064Z","caller":"traceutil/trace.go:171","msg":"trace[414616660] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"386.763719ms","start":"2026-08-19T09:07:22.382265Z","end":"2026-08-19T09:07:22.769029Z","steps":["trace[414616660] 'process raft request' (duration: 386.39983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:22.769486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:22.382239Z","time spent":"386.915922ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:07:22.769874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.720619ms","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-08-19T09:07:22.770021Z","caller":"traceutil/trace.go:171","msg":"trace[1970178790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"367.916113ms","start":"2026-08-19T09:07:22.402058Z","end":"2026-08-19T09:07:22.769974Z","steps":["trace[1970178790] 'agreement among raft nodes before linearized reading' (duration: 367.035673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:22.770123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:22.402039Z","time spent":"368.030715ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:07:22.803816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.752106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-19T09:07:22.803908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.789707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:07:22.80395Z","caller":"traceutil/trace.go:171","msg":"trace[126578133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"383.016732ms","start":"2026-08-19T09:07:22.420908Z","end":"2026-08-19T09:07:22.803925Z","steps":["trace[126578133] 'agreement among raft nodes before linearized reading' (duration: 382.627913ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:22.803998Z","caller":"traceutil/trace.go:171","msg":"trace[1155538257] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1016; }","duration":"243.928701ms","start":"2026-08-19T09:07:22.560043Z","end":"2026-08-19T09:07:22.803972Z","steps":["trace[1155538257] 'agreement among raft nodes before linearized reading' (duration: 243.713506ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:22.803871Z","caller":"traceutil/trace.go:171","msg":"trace[1170682758] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"390.638478ms","start":"2026-08-19T09:07:22.413206Z","end":"2026-08-19T09:07:22.803845Z","steps":["trace[1170682758] 'process raft request' (duration: 390.184838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:22.804011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:22.420897Z","time spent":"383.099214ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:07:22.804275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:22.41319Z","time spent":"390.980365ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:07:22.804305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.31693ms","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-08-19T09:07:22.804391Z","caller":"traceutil/trace.go:171","msg":"trace[947874264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"335.440193ms","start":"2026-08-19T09:07:22.468929Z","end":"2026-08-19T09:07:22.804369Z","steps":["trace[947874264] 'agreement among raft nodes before linearized reading' (duration: 335.064964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:22.804438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:22.468918Z","time spent":"335.510344ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T09:07:23.001044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.5833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:07:23.00106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.969657ms","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-08-19T09:07:23.001104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.416198ms","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-08-19T09:07:23.00113Z","caller":"traceutil/trace.go:171","msg":"trace[1534942145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"563.708403ms","start":"2026-08-19T09:07:22.437399Z","end":"2026-08-19T09:07:23.001107Z","steps":["trace[1534942145] 'agreement among raft nodes before linearized reading' (duration: 366.745227ms)","trace[1534942145] 'range keys from in-memory index tree' (duration: 196.820633ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:07:23.001159Z","caller":"traceutil/trace.go:171","msg":"trace[151401498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"515.108861ms","start":"2026-08-19T09:07:22.486023Z","end":"2026-08-19T09:07:23.001132Z","steps":["trace[151401498] 'agreement among raft nodes before linearized reading' (duration: 318.152114ms)","trace[151401498] 'range keys from in-memory index tree' (duration: 196.69977ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:07:23.001193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:22.437389Z","time spent":"563.794916ms","remote":"127.0.0.1:54632","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-08-19T09:07:23.001189Z","caller":"traceutil/trace.go:171","msg":"trace[887349219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"324.548993ms","start":"2026-08-19T09:07:22.676619Z","end":"2026-08-19T09:07:23.001168Z","steps":["trace[887349219] 'agreement among raft nodes before linearized reading' (duration: 127.484533ms)","trace[887349219] 'range keys from in-memory index tree' (duration: 196.829134ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:07:23.001227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:22.486012Z","time spent":"515.199564ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:07:23.001242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:22.676599Z","time spent":"324.633954ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-19T09:07:23.044651Z","caller":"traceutil/trace.go:171","msg":"trace[2099800401] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"269.43196ms","start":"2026-08-19T09:07:22.775202Z","end":"2026-08-19T09:07:23.044634Z","steps":["trace[2099800401] 'process raft request' (duration: 269.293186ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:23.06732Z","caller":"traceutil/trace.go:171","msg":"trace[1370387130] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"257.600387ms","start":"2026-08-19T09:07:22.809701Z","end":"2026-08-19T09:07:23.067302Z","steps":["trace[1370387130] 'process raft request' (duration: 257.473164ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:23.067699Z","caller":"traceutil/trace.go:171","msg":"trace[706360162] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"257.954415ms","start":"2026-08-19T09:07:22.809715Z","end":"2026-08-19T09:07:23.067669Z","steps":["trace[706360162] 'process raft request' (duration: 257.565226ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:36.184809Z","caller":"traceutil/trace.go:171","msg":"trace[258116997] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"114.354721ms","start":"2026-08-19T09:07:36.070404Z","end":"2026-08-19T09:07:36.184759Z","steps":["trace[258116997] 'process raft request' (duration: 114.050864ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:36.184845Z","caller":"traceutil/trace.go:171","msg":"trace[1366793435] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"102.213211ms","start":"2026-08-19T09:07:36.082584Z","end":"2026-08-19T09:07:36.184797Z","steps":["trace[1366793435] 'process raft request' (duration: 102.035377ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:41.307911Z","caller":"traceutil/trace.go:171","msg":"trace[722237997] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"182.857041ms","start":"2026-08-19T09:07:41.125018Z","end":"2026-08-19T09:07:41.307875Z","steps":["trace[722237997] 'read index received' (duration: 182.013651ms)","trace[722237997] 'applied index is now lower than readState.Index' (duration: 842.229µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:07:41.308075Z","caller":"traceutil/trace.go:171","msg":"trace[1672571520] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"438.981293ms","start":"2026-08-19T09:07:40.869081Z","end":"2026-08-19T09:07:41.308063Z","steps":["trace[1672571520] 'process raft request' (duration: 437.926129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:41.308208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:40.869056Z","time spent":"439.051025ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:07:41.308442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.289276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-19T09:07:41.308542Z","caller":"traceutil/trace.go:171","msg":"trace[742597967] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1125; }","duration":"178.425939ms","start":"2026-08-19T09:07:41.130089Z","end":"2026-08-19T09:07:41.308515Z","steps":["trace[742597967] 'agreement among raft nodes before linearized reading' (duration: 178.168643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:41.308802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.78041ms","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-08-19T09:07:41.308847Z","caller":"traceutil/trace.go:171","msg":"trace[1466075063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"124.851812ms","start":"2026-08-19T09:07:41.183984Z","end":"2026-08-19T09:07:41.308836Z","steps":["trace[1466075063] 'agreement among raft nodes before linearized reading' (duration: 124.633577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:41.308907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.865193ms","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-08-19T09:07:41.308944Z","caller":"traceutil/trace.go:171","msg":"trace[441397022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"163.919675ms","start":"2026-08-19T09:07:41.145014Z","end":"2026-08-19T09:07:41.308933Z","steps":["trace[441397022] 'agreement among raft nodes before linearized reading' (duration: 163.814402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:41.309082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.060329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T09:07:41.309123Z","caller":"traceutil/trace.go:171","msg":"trace[1612184665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1125; }","duration":"184.125221ms","start":"2026-08-19T09:07:41.124988Z","end":"2026-08-19T09:07:41.309114Z","steps":["trace[1612184665] 'agreement among raft nodes before linearized reading' (duration: 184.023108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:41.309155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.148141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T09:07:41.309188Z","caller":"traceutil/trace.go:171","msg":"trace[1609153675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1125; }","duration":"171.217282ms","start":"2026-08-19T09:07:41.137961Z","end":"2026-08-19T09:07:41.309178Z","steps":["trace[1609153675] 'agreement among raft nodes before linearized reading' (duration: 171.12483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:41.309373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.029802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-19T09:07:41.309418Z","caller":"traceutil/trace.go:171","msg":"trace[453360681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"124.077334ms","start":"2026-08-19T09:07:41.185326Z","end":"2026-08-19T09:07:41.309403Z","steps":["trace[453360681] 'agreement among raft nodes before linearized reading' (duration: 123.977002ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:53.762287Z","caller":"traceutil/trace.go:171","msg":"trace[2056457291] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"289.85196ms","start":"2026-08-19T09:07:53.472412Z","end":"2026-08-19T09:07:53.762264Z","steps":["trace[2056457291] 'process raft request' (duration: 289.681136ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:57.638324Z","caller":"traceutil/trace.go:171","msg":"trace[565936158] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"242.040318ms","start":"2026-08-19T09:07:57.396267Z","end":"2026-08-19T09:07:57.638308Z","steps":["trace[565936158] 'read index received' (duration: 241.937206ms)","trace[565936158] 'applied index is now lower than readState.Index' (duration: 102.652µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:07:57.638376Z","caller":"traceutil/trace.go:171","msg":"trace[625717270] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"255.884016ms","start":"2026-08-19T09:07:57.382472Z","end":"2026-08-19T09:07:57.638356Z","steps":["trace[625717270] 'process raft request' (duration: 255.734863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:57.638461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.190711ms","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-08-19T09:07:57.638477Z","caller":"traceutil/trace.go:171","msg":"trace[28725491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"242.252463ms","start":"2026-08-19T09:07:57.39622Z","end":"2026-08-19T09:07:57.638472Z","steps":["trace[28725491] 'agreement among raft nodes before linearized reading' (duration: 242.163611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:57.638551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.290081ms","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-08-19T09:07:57.638563Z","caller":"traceutil/trace.go:171","msg":"trace[1069724263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"219.313113ms","start":"2026-08-19T09:07:57.419246Z","end":"2026-08-19T09:07:57.638559Z","steps":["trace[1069724263] 'agreement among raft nodes before linearized reading' (duration: 219.276842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:57.638602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.161334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:07:57.638613Z","caller":"traceutil/trace.go:171","msg":"trace[400109882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"201.187054ms","start":"2026-08-19T09:07:57.437422Z","end":"2026-08-19T09:07:57.638609Z","steps":["trace[400109882] 'agreement among raft nodes before linearized reading' (duration: 201.169903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:57.638607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.875524ms","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-08-19T09:07:57.638687Z","caller":"traceutil/trace.go:171","msg":"trace[844039426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1199; }","duration":"228.963546ms","start":"2026-08-19T09:07:57.409698Z","end":"2026-08-19T09:07:57.638662Z","steps":["trace[844039426] 'agreement among raft nodes before linearized reading' (duration: 228.739751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:57.638761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.182331ms","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-08-19T09:07:57.638779Z","caller":"traceutil/trace.go:171","msg":"trace[661675744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"226.222743ms","start":"2026-08-19T09:07:57.412553Z","end":"2026-08-19T09:07:57.638775Z","steps":["trace[661675744] 'agreement among raft nodes before linearized reading' (duration: 226.179542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:57.638855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.603253ms","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-08-19T09:07:57.638883Z","caller":"traceutil/trace.go:171","msg":"trace[1453245203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"227.652195ms","start":"2026-08-19T09:07:57.411222Z","end":"2026-08-19T09:07:57.638874Z","steps":["trace[1453245203] 'agreement among raft nodes before linearized reading' (duration: 227.574623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:58.729029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221965754595379,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T09:07:58.746138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.41115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:07:58.746207Z","caller":"traceutil/trace.go:171","msg":"trace[1076111904] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"1.104402294s","start":"2026-08-19T09:07:57.641794Z","end":"2026-08-19T09:07:58.746196Z","steps":["trace[1076111904] 'process raft request' (duration: 548.886671ms)","trace[1076111904] 'compare' (duration: 554.879878ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:07:58.746239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:57.641778Z","time spent":"1.104447274s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:07:58.996122Z","caller":"traceutil/trace.go:171","msg":"trace[1257947989] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"1.354102648s","start":"2026-08-19T09:07:57.641992Z","end":"2026-08-19T09:07:58.996095Z","steps":["trace[1257947989] 'process raft request' (duration: 1.275155365s)","trace[1257947989] 'compare' (duration: 78.779139ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:07:58.996274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:57.641969Z","time spent":"1.354229542s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:07:58.996364Z","caller":"traceutil/trace.go:171","msg":"trace[1453747485] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1247; }","duration":"768.300006ms","start":"2026-08-19T09:07:58.228048Z","end":"2026-08-19T09:07:58.996348Z","steps":["trace[1453747485] 'read index received' (duration: 689.047506ms)","trace[1453747485] 'applied index is now lower than readState.Index' (duration: 79.25161ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:07:58.996415Z","caller":"traceutil/trace.go:171","msg":"trace[940889286] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"1.354150059s","start":"2026-08-19T09:07:57.642254Z","end":"2026-08-19T09:07:58.996404Z","steps":["trace[940889286] 'process raft request' (duration: 1.353974015s)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:58.996462Z","caller":"traceutil/trace.go:171","msg":"trace[1974901138] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"1.354063598s","start":"2026-08-19T09:07:57.642386Z","end":"2026-08-19T09:07:58.99645Z","steps":["trace[1974901138] 'process raft request' (duration: 1.353902744s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:58.996466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:57.642242Z","time spent":"1.354196041s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:07:58.996533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:57.642364Z","time spent":"1.354123139s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:07:58.996546Z","caller":"traceutil/trace.go:171","msg":"trace[122957582] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"1.354532669s","start":"2026-08-19T09:07:57.642005Z","end":"2026-08-19T09:07:58.996538Z","steps":["trace[122957582] 'process raft request' (duration: 1.354048498s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:58.996591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:57.641992Z","time spent":"1.354573069s","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:07:58.996689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.425521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:07:58.996719Z","caller":"traceutil/trace.go:171","msg":"trace[894533754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"558.478262ms","start":"2026-08-19T09:07:58.438231Z","end":"2026-08-19T09:07:58.996709Z","steps":["trace[894533754] 'agreement among raft nodes before linearized reading' (duration: 558.433141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:58.996745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:58.438215Z","time spent":"558.524853ms","remote":"127.0.0.1:54632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T09:07:58.996924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.870309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:07:58.996955Z","caller":"traceutil/trace.go:171","msg":"trace[75980489] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1204; }","duration":"768.928501ms","start":"2026-08-19T09:07:58.228017Z","end":"2026-08-19T09:07:58.996945Z","steps":["trace[75980489] 'agreement among raft nodes before linearized reading' (duration: 768.87922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:07:58.996988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:58.228Z","time spent":"768.977021ms","remote":"127.0.0.1:38306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:08:00.469556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.918385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:08:00.470346Z","caller":"traceutil/trace.go:171","msg":"trace[1425662728] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1255; }","duration":"782.111824ms","start":"2026-08-19T09:07:59.68821Z","end":"2026-08-19T09:08:00.470322Z","steps":["trace[1425662728] 'read index received' (duration: 78.40766ms)","trace[1425662728] 'applied index is now lower than readState.Index' (duration: 703.702174ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:00.470441Z","caller":"traceutil/trace.go:171","msg":"trace[2106861279] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"863.964315ms","start":"2026-08-19T09:07:59.606435Z","end":"2026-08-19T09:08:00.470399Z","steps":["trace[2106861279] 'process raft request' (duration: 160.127538ms)","trace[2106861279] 'compare' (duration: 702.404593ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:00.470542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.504847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:08:00.470609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:59.606424Z","time spent":"864.100888ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:08:00.470621Z","caller":"traceutil/trace.go:171","msg":"trace[81721925] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1210; }","duration":"339.636009ms","start":"2026-08-19T09:08:00.130966Z","end":"2026-08-19T09:08:00.470602Z","steps":["trace[81721925] 'agreement among raft nodes before linearized reading' (duration: 339.494646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:00.470618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.816172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:08:00.470669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:00.130943Z","time spent":"339.716742ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:08:00.470706Z","caller":"traceutil/trace.go:171","msg":"trace[1447166010] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1210; }","duration":"215.913434ms","start":"2026-08-19T09:08:00.254772Z","end":"2026-08-19T09:08:00.470685Z","steps":["trace[1447166010] 'agreement among raft nodes before linearized reading' (duration: 215.786061ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:00.470977Z","caller":"traceutil/trace.go:171","msg":"trace[44399233] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"822.324353ms","start":"2026-08-19T09:07:59.648032Z","end":"2026-08-19T09:08:00.470356Z","steps":["trace[44399233] 'process raft request' (duration: 821.655198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:00.471234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:59.648005Z","time spent":"823.145992ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:00.47104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.8252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:00.471462Z","caller":"traceutil/trace.go:171","msg":"trace[2098304435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"783.24672ms","start":"2026-08-19T09:07:59.688205Z","end":"2026-08-19T09:08:00.471452Z","steps":["trace[2098304435] 'agreement among raft nodes before linearized reading' (duration: 782.80598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:00.471505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:07:59.688188Z","time spent":"783.308921ms","remote":"127.0.0.1:54632","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-08-19T09:08:01.053693Z","caller":"traceutil/trace.go:171","msg":"trace[848347840] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1257; }","duration":"303.324302ms","start":"2026-08-19T09:08:00.750329Z","end":"2026-08-19T09:08:01.053653Z","steps":["trace[848347840] 'read index received' (duration: 260.437712ms)","trace[848347840] 'applied index is now lower than readState.Index' (duration: 42.88553ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:01.053735Z","caller":"traceutil/trace.go:171","msg":"trace[1829240532] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"576.509169ms","start":"2026-08-19T09:08:00.477186Z","end":"2026-08-19T09:08:01.053695Z","steps":["trace[1829240532] 'process raft request' (duration: 576.355975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:01.053893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.560207ms","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-08-19T09:08:01.053918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:00.47717Z","time spent":"576.665622ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:08:01.053906Z","caller":"traceutil/trace.go:171","msg":"trace[1308715714] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"579.025537ms","start":"2026-08-19T09:08:00.474854Z","end":"2026-08-19T09:08:01.053879Z","steps":["trace[1308715714] 'process raft request' (duration: 535.897392ms)","trace[1308715714] 'compare' (duration: 38.665752ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:01.054035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:00.474818Z","time spent":"579.1478ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:08:01.053934Z","caller":"traceutil/trace.go:171","msg":"trace[969645948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1212; }","duration":"303.66321ms","start":"2026-08-19T09:08:00.750255Z","end":"2026-08-19T09:08:01.053918Z","steps":["trace[969645948] 'agreement among raft nodes before linearized reading' (duration: 303.534877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:01.054418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:00.750232Z","time spent":"304.170441ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T09:08:02.576893Z","caller":"traceutil/trace.go:171","msg":"trace[1227339336] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"101.555814ms","start":"2026-08-19T09:08:02.475306Z","end":"2026-08-19T09:08:02.576862Z","steps":["trace[1227339336] 'process raft request' (duration: 101.217746ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:03.434853Z","caller":"traceutil/trace.go:171","msg":"trace[619939621] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"328.012002ms","start":"2026-08-19T09:08:03.106821Z","end":"2026-08-19T09:08:03.434833Z","steps":["trace[619939621] 'read index received' (duration: 327.826198ms)","trace[619939621] 'applied index is now lower than readState.Index' (duration: 185.104µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:03.435096Z","caller":"traceutil/trace.go:171","msg":"trace[124696282] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"356.273504ms","start":"2026-08-19T09:08:03.078813Z","end":"2026-08-19T09:08:03.435086Z","steps":["trace[124696282] 'process raft request' (duration: 355.893525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:03.435179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:03.078789Z","time spent":"356.333546ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:03.435384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.885416ms","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-08-19T09:08:03.435412Z","caller":"traceutil/trace.go:171","msg":"trace[825793990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"326.998598ms","start":"2026-08-19T09:08:03.108406Z","end":"2026-08-19T09:08:03.435405Z","steps":["trace[825793990] 'agreement among raft nodes before linearized reading' (duration: 326.902506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:03.435435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:03.108395Z","time spent":"327.03571ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T09:08:03.435445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.609166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-19T09:08:03.435441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.886615ms","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-08-19T09:08:03.435427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.494933ms","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-08-19T09:08:03.435494Z","caller":"traceutil/trace.go:171","msg":"trace[805493742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"328.724028ms","start":"2026-08-19T09:08:03.106758Z","end":"2026-08-19T09:08:03.435482Z","steps":["trace[805493742] 'agreement among raft nodes before linearized reading' (duration: 328.548504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:03.435519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:03.106741Z","time spent":"328.773149ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-19T09:08:03.435513Z","caller":"traceutil/trace.go:171","msg":"trace[491518180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"326.994037ms","start":"2026-08-19T09:08:03.1085Z","end":"2026-08-19T09:08:03.435494Z","steps":["trace[491518180] 'agreement among raft nodes before linearized reading' (duration: 326.768172ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:03.435526Z","caller":"traceutil/trace.go:171","msg":"trace[625877607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"328.632806ms","start":"2026-08-19T09:08:03.106875Z","end":"2026-08-19T09:08:03.435508Z","steps":["trace[625877607] 'agreement among raft nodes before linearized reading' (duration: 328.362439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:03.435554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:03.108482Z","time spent":"327.062889ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:08:03.43557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:03.106866Z","time spent":"328.695278ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-19T09:08:04.689057Z","caller":"traceutil/trace.go:171","msg":"trace[1753992727] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"103.631132ms","start":"2026-08-19T09:08:04.585405Z","end":"2026-08-19T09:08:04.689037Z","steps":["trace[1753992727] 'process raft request' (duration: 103.504189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:08.704652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.250523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:08.704718Z","caller":"traceutil/trace.go:171","msg":"trace[499083011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"268.347275ms","start":"2026-08-19T09:08:08.436356Z","end":"2026-08-19T09:08:08.704703Z","steps":["trace[499083011] 'range keys from in-memory index tree' (duration: 268.196391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:08.704714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.387253ms","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-08-19T09:08:08.704826Z","caller":"traceutil/trace.go:171","msg":"trace[405314422] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1256; }","duration":"264.534637ms","start":"2026-08-19T09:08:08.440271Z","end":"2026-08-19T09:08:08.704806Z","steps":["trace[405314422] 'count revisions from in-memory index tree' (duration: 264.336691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:08.704843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.98823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:08:08.704909Z","caller":"traceutil/trace.go:171","msg":"trace[572996577] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1256; }","duration":"292.088052ms","start":"2026-08-19T09:08:08.412804Z","end":"2026-08-19T09:08:08.704892Z","steps":["trace[572996577] 'range keys from in-memory index tree' (duration: 291.930479ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:37.84255Z","caller":"traceutil/trace.go:171","msg":"trace[1562000639] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"105.391641ms","start":"2026-08-19T09:08:37.737128Z","end":"2026-08-19T09:08:37.84252Z","steps":["trace[1562000639] 'process raft request' (duration: 105.303099ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:37.84287Z","caller":"traceutil/trace.go:171","msg":"trace[113894168] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"106.955009ms","start":"2026-08-19T09:08:37.735883Z","end":"2026-08-19T09:08:37.842838Z","steps":["trace[113894168] 'process raft request' (duration: 98.774642ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:37.842924Z","caller":"traceutil/trace.go:171","msg":"trace[1025502508] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"105.851713ms","start":"2026-08-19T09:08:37.73705Z","end":"2026-08-19T09:08:37.842902Z","steps":["trace[1025502508] 'process raft request' (duration: 105.32488ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:04.383976Z","caller":"traceutil/trace.go:171","msg":"trace[804004281] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"157.875503ms","start":"2026-08-19T09:11:04.226063Z","end":"2026-08-19T09:11:04.383939Z","steps":["trace[804004281] 'process raft request' (duration: 157.659658ms)"],"step_count":1}