{"level":"warn","ts":"2026-09-18T15:13:22.528931Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T15:13:22.529112Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.113: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://162.253.55.113:2380","--initial-cluster=instance=https://162.253.55.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.113: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-09-18T15:13:22.529215Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T15:13:22.529254Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-09-18T15:13:22.529302Z","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-09-18T15:13:22.530289Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"]} {"level":"info","ts":"2026-09-18T15:13:22.530447Z","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://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.113: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-09-18T15:13:22.535578Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.836653ms"} {"level":"info","ts":"2026-09-18T15:13:22.542179Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3b717bddf98d48e3","cluster-id":"cef0f7c69f0576fc"} {"level":"info","ts":"2026-09-18T15:13:22.542292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T15:13:22.542354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 0"} {"level":"info","ts":"2026-09-18T15:13:22.542394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3b717bddf98d48e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T15:13:22.542422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 1"} {"level":"info","ts":"2026-09-18T15:13:22.542491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"warn","ts":"2026-09-18T15:13:22.561545Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T15:13:22.563518Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T15:13:22.57268Z","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-09-18T15:13:22.577098Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3b717bddf98d48e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T15:13:22.581098Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3b717bddf98d48e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T15:13:22.581257Z","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-09-18T15:13:22.581293Z","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-09-18T15:13:22.581303Z","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-09-18T15:13:22.5818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"info","ts":"2026-09-18T15:13:22.581919Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","added-peer-id":"3b717bddf98d48e3","added-peer-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-09-18T15:13:22.592069Z","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-09-18T15:13:22.592475Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3b717bddf98d48e3","initial-advertise-peer-urls":["https://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T15:13:22.592597Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T15:13:22.592751Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-09-18T15:13:22.593371Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-09-18T15:13:22.943194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T15:13:22.943371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T15:13:22.943454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgPreVoteResp from 3b717bddf98d48e3 at term 1"} {"level":"info","ts":"2026-09-18T15:13:22.943502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became candidate at term 2"} {"level":"info","ts":"2026-09-18T15:13:22.943534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgVoteResp from 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-09-18T15:13:22.943579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became leader at term 2"} {"level":"info","ts":"2026-09-18T15:13:22.94362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3b717bddf98d48e3 elected leader 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-09-18T15:13:22.948802Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:13:22.956719Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:13:22.95681Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:13:22.956831Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:13:22.956845Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:13:22.956837Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3b717bddf98d48e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.113:2379]}","request-path":"/0/members/3b717bddf98d48e3/attributes","cluster-id":"cef0f7c69f0576fc","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T15:13:22.95698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:13:22.957194Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T15:13:22.957233Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T15:13:22.959028Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T15:13:22.965008Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.113:2379"} {"level":"info","ts":"2026-09-18T15:13:26.708252Z","caller":"traceutil/trace.go:171","msg":"trace[618158411] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"225.86874ms","start":"2026-09-18T15:13:26.482355Z","end":"2026-09-18T15:13:26.708224Z","steps":["trace[618158411] 'process raft request' (duration: 211.894691ms)","trace[618158411] 'compare' (duration: 13.811334ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:13:26.708473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.850839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:13:26.708599Z","caller":"traceutil/trace.go:171","msg":"trace[867656333] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replicaset-controller; range_end:; response_count:0; response_revision:190; }","duration":"226.056655ms","start":"2026-09-18T15:13:26.482517Z","end":"2026-09-18T15:13:26.708574Z","steps":["trace[867656333] 'agreement among raft nodes before linearized reading' (duration: 225.801877ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:13:26.70825Z","caller":"traceutil/trace.go:171","msg":"trace[1373441596] linearizableReadLoop","detail":"{readStateIndex:195; appliedIndex:194; }","duration":"225.640433ms","start":"2026-09-18T15:13:26.48256Z","end":"2026-09-18T15:13:26.708201Z","steps":["trace[1373441596] 'read index received' (duration: 211.692945ms)","trace[1373441596] 'applied index is now lower than readState.Index' (duration: 13.945408ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:13:28.215969Z","caller":"traceutil/trace.go:171","msg":"trace[448099836] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"109.991416ms","start":"2026-09-18T15:13:28.10595Z","end":"2026-09-18T15:13:28.215942Z","steps":["trace[448099836] 'process raft request' (duration: 25.794002ms)","trace[448099836] 'compare' (duration: 83.868355ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:13:28.216415Z","caller":"traceutil/trace.go:171","msg":"trace[1230840624] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"108.583626ms","start":"2026-09-18T15:13:28.107809Z","end":"2026-09-18T15:13:28.216392Z","steps":["trace[1230840624] 'process raft request' (duration: 108.01668ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:07.123009Z","caller":"traceutil/trace.go:171","msg":"trace[706896829] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"352.642615ms","start":"2026-09-18T15:14:06.770323Z","end":"2026-09-18T15:14:07.122965Z","steps":["trace[706896829] 'process raft request' (duration: 352.545073ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:07.123197Z","caller":"traceutil/trace.go:171","msg":"trace[1280479589] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"360.587897ms","start":"2026-09-18T15:14:06.762557Z","end":"2026-09-18T15:14:07.123145Z","steps":["trace[1280479589] 'process raft request' (duration: 344.935292ms)","trace[1280479589] 'compare' (duration: 15.159146ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:14:07.123875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.762538Z","time spent":"360.7394ms","remote":"127.0.0.1:50580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:14:07.124019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.770293Z","time spent":"352.8422ms","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:14:07.125406Z","caller":"traceutil/trace.go:171","msg":"trace[1639254667] transaction","detail":"{read_only:false; number_of_response:1; response_revision:552; }","duration":"350.540122ms","start":"2026-09-18T15:14:06.774819Z","end":"2026-09-18T15:14:07.125359Z","steps":["trace[1639254667] 'process raft request' (duration: 350.285867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.125602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.774802Z","time spent":"350.694956ms","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10528,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:14:07.12566Z","caller":"traceutil/trace.go:171","msg":"trace[911649360] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"339.052469ms","start":"2026-09-18T15:14:06.786582Z","end":"2026-09-18T15:14:07.125635Z","steps":["trace[911649360] 'process raft request' (duration: 338.659271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.125773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.786557Z","time spent":"339.173652ms","remote":"127.0.0.1:50580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:14:07.12596Z","caller":"traceutil/trace.go:171","msg":"trace[757139591] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"339.192151ms","start":"2026-09-18T15:14:06.786753Z","end":"2026-09-18T15:14:07.125945Z","steps":["trace[757139591] 'process raft request' (duration: 338.679852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.126002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.786718Z","time spent":"339.264132ms","remote":"127.0.0.1:50580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:14:07.126613Z","caller":"traceutil/trace.go:171","msg":"trace[1125987150] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"334.361063ms","start":"2026-09-18T15:14:06.792232Z","end":"2026-09-18T15:14:07.126593Z","steps":["trace[1125987150] 'process raft request' (duration: 333.256691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.126811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.79221Z","time spent":"334.476286ms","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:14:07.127189Z","caller":"traceutil/trace.go:171","msg":"trace[1211846493] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"334.839773ms","start":"2026-09-18T15:14:06.792308Z","end":"2026-09-18T15:14:07.127147Z","steps":["trace[1211846493] 'process raft request' (duration: 334.21294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.127356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.792304Z","time spent":"335.024407ms","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:14:07.127464Z","caller":"traceutil/trace.go:171","msg":"trace[317829134] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"334.617058ms","start":"2026-09-18T15:14:06.792771Z","end":"2026-09-18T15:14:07.127388Z","steps":["trace[317829134] 'process raft request' (duration: 334.22545ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:07.12762Z","caller":"traceutil/trace.go:171","msg":"trace[1373309288] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:575; }","duration":"320.576684ms","start":"2026-09-18T15:14:06.807025Z","end":"2026-09-18T15:14:07.127602Z","steps":["trace[1373309288] 'read index received' (duration: 300.485109ms)","trace[1373309288] 'applied index is now lower than readState.Index' (duration: 20.090395ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:07.127731Z","caller":"traceutil/trace.go:171","msg":"trace[799685304] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"331.078097ms","start":"2026-09-18T15:14:06.79664Z","end":"2026-09-18T15:14:07.127718Z","steps":["trace[799685304] 'process raft request' (duration: 330.492245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.1278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.792766Z","time spent":"334.831282ms","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:14:07.127821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.79663Z","time spent":"331.137429ms","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:14:07.127914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.87599ms","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-09-18T15:14:07.127974Z","caller":"traceutil/trace.go:171","msg":"trace[641033564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"320.957872ms","start":"2026-09-18T15:14:06.807Z","end":"2026-09-18T15:14:07.127958Z","steps":["trace[641033564] 'agreement among raft nodes before linearized reading' (duration: 320.744137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.128049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.806992Z","time spent":"321.045733ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-18T15:14:07.128236Z","caller":"traceutil/trace.go:171","msg":"trace[145786280] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"335.487916ms","start":"2026-09-18T15:14:06.792712Z","end":"2026-09-18T15:14:07.1282Z","steps":["trace[145786280] 'process raft request' (duration: 334.177659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.128323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.792511Z","time spent":"335.767282ms","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:14:07.128429Z","caller":"traceutil/trace.go:171","msg":"trace[2012856000] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"235.882133ms","start":"2026-09-18T15:14:06.892535Z","end":"2026-09-18T15:14:07.128417Z","steps":["trace[2012856000] 'process raft request' (duration: 234.915883ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:07.128466Z","caller":"traceutil/trace.go:171","msg":"trace[901969559] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"323.781749ms","start":"2026-09-18T15:14:06.804674Z","end":"2026-09-18T15:14:07.128456Z","steps":["trace[901969559] 'process raft request' (duration: 322.552624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:07.128518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:06.80465Z","time spent":"323.845431ms","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:14:08.42927Z","caller":"traceutil/trace.go:171","msg":"trace[613879113] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"108.664136ms","start":"2026-09-18T15:14:08.320557Z","end":"2026-09-18T15:14:08.429222Z","steps":["trace[613879113] 'process raft request' (duration: 108.230367ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:13.617043Z","caller":"traceutil/trace.go:171","msg":"trace[1391344672] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"264.394543ms","start":"2026-09-18T15:14:13.352613Z","end":"2026-09-18T15:14:13.617008Z","steps":["trace[1391344672] 'process raft request' (duration: 263.703709ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:13.649436Z","caller":"traceutil/trace.go:171","msg":"trace[1751826853] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"173.369722ms","start":"2026-09-18T15:14:13.476034Z","end":"2026-09-18T15:14:13.649404Z","steps":["trace[1751826853] 'process raft request' (duration: 172.891113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:14.655147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034471792,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:14:14.731873Z","caller":"traceutil/trace.go:171","msg":"trace[909566203] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"576.93015ms","start":"2026-09-18T15:14:14.154917Z","end":"2026-09-18T15:14:14.731847Z","steps":["trace[909566203] 'read index received' (duration: 575.245687ms)","trace[909566203] 'applied index is now lower than readState.Index' (duration: 1.683453ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:14.731974Z","caller":"traceutil/trace.go:171","msg":"trace[1636775314] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"578.91628ms","start":"2026-09-18T15:14:14.15301Z","end":"2026-09-18T15:14:14.731926Z","steps":["trace[1636775314] 'process raft request' (duration: 577.676785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:14.733548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:14.152981Z","time spent":"579.091012ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:14:14.733586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.90328ms","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-09-18T15:14:14.734382Z","caller":"traceutil/trace.go:171","msg":"trace[1781240373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"124.751317ms","start":"2026-09-18T15:14:14.609578Z","end":"2026-09-18T15:14:14.734329Z","steps":["trace[1781240373] 'agreement among raft nodes before linearized reading' (duration: 122.560795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:14.732027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.088074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:14.735234Z","caller":"traceutil/trace.go:171","msg":"trace[1611920844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"580.298654ms","start":"2026-09-18T15:14:14.154859Z","end":"2026-09-18T15:14:14.735158Z","steps":["trace[1611920844] 'agreement among raft nodes before linearized reading' (duration: 577.106713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:14.735347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:14.154834Z","time spent":"580.492518ms","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T15:14:14.73713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.523295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tvd5d\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T15:14:14.737299Z","caller":"traceutil/trace.go:171","msg":"trace[1841595694] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tvd5d; range_end:; response_count:1; response_revision:650; }","duration":"397.701328ms","start":"2026-09-18T15:14:14.33952Z","end":"2026-09-18T15:14:14.737221Z","steps":["trace[1841595694] 'agreement among raft nodes before linearized reading' (duration: 392.533579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:14.737355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:14.339502Z","time spent":"397.833851ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-tvd5d\" "} {"level":"info","ts":"2026-09-18T15:14:14.876147Z","caller":"traceutil/trace.go:171","msg":"trace[666482720] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"119.897352ms","start":"2026-09-18T15:14:14.756217Z","end":"2026-09-18T15:14:14.876114Z","steps":["trace[666482720] 'process raft request' (duration: 113.060932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:15.70557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034471797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:14:16.009131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.644534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:14:16.009554Z","caller":"traceutil/trace.go:171","msg":"trace[1099700743] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:682; }","duration":"804.015807ms","start":"2026-09-18T15:14:15.20552Z","end":"2026-09-18T15:14:16.009536Z","steps":["trace[1099700743] 'read index received' (duration: 588.700911ms)","trace[1099700743] 'applied index is now lower than readState.Index' (duration: 215.313546ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:16.00955Z","caller":"traceutil/trace.go:171","msg":"trace[507796111] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.204679596s","start":"2026-09-18T15:14:14.80479Z","end":"2026-09-18T15:14:16.00947Z","steps":["trace[507796111] 'process raft request' (duration: 989.456361ms)","trace[507796111] 'compare' (duration: 214.497032ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:16.009637Z","caller":"traceutil/trace.go:171","msg":"trace[340018508] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"707.018396ms","start":"2026-09-18T15:14:15.302612Z","end":"2026-09-18T15:14:16.00963Z","steps":["trace[340018508] 'process raft request' (duration: 706.837163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:16.009708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:15.302585Z","time spent":"707.079838ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:14:16.009749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:14.804766Z","time spent":"1.20489708s","remote":"127.0.0.1:50304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:14:16.009872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.914559ms","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-09-18T15:14:16.009924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.409725ms","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-09-18T15:14:16.009947Z","caller":"traceutil/trace.go:171","msg":"trace[1130399700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"804.467836ms","start":"2026-09-18T15:14:15.205472Z","end":"2026-09-18T15:14:16.00994Z","steps":["trace[1130399700] 'agreement among raft nodes before linearized reading' (duration: 804.399245ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:16.009934Z","caller":"traceutil/trace.go:171","msg":"trace[891792748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"388.003521ms","start":"2026-09-18T15:14:15.621908Z","end":"2026-09-18T15:14:16.009912Z","steps":["trace[891792748] 'agreement among raft nodes before linearized reading' (duration: 387.831837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:16.00997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:15.205452Z","time spent":"804.513567ms","remote":"127.0.0.1:50322","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-09-18T15:14:16.009985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:15.621889Z","time spent":"388.084422ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T15:14:32.961046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034472163,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:14:32.962802Z","caller":"traceutil/trace.go:171","msg":"trace[986404662] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"502.48177ms","start":"2026-09-18T15:14:32.460281Z","end":"2026-09-18T15:14:32.962762Z","steps":["trace[986404662] 'read index received' (duration: 502.119373ms)","trace[986404662] 'applied index is now lower than readState.Index' (duration: 361.027µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:32.963089Z","caller":"traceutil/trace.go:171","msg":"trace[1069410204] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"506.649281ms","start":"2026-09-18T15:14:32.45642Z","end":"2026-09-18T15:14:32.963069Z","steps":["trace[1069410204] 'process raft request' (duration: 506.124803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.963257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.835145ms","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":"warn","ts":"2026-09-18T15:14:32.963296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.456403Z","time spent":"506.745763ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:14:32.963367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.925193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.963392Z","caller":"traceutil/trace.go:171","msg":"trace[1790442085] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:821; }","duration":"369.979814ms","start":"2026-09-18T15:14:32.593405Z","end":"2026-09-18T15:14:32.963385Z","steps":["trace[1790442085] 'agreement among raft nodes before linearized reading' (duration: 369.932293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.963436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.59337Z","time spent":"370.045005ms","remote":"127.0.0.1:45114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-09-18T15:14:32.963453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.606468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.963495Z","caller":"traceutil/trace.go:171","msg":"trace[1985700417] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:821; }","duration":"422.683928ms","start":"2026-09-18T15:14:32.540801Z","end":"2026-09-18T15:14:32.963485Z","steps":["trace[1985700417] 'agreement among raft nodes before linearized reading' (duration: 422.600127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.963518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.540783Z","time spent":"422.72955ms","remote":"127.0.0.1:45092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-09-18T15:14:32.963627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.851782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.963649Z","caller":"traceutil/trace.go:171","msg":"trace[1770961253] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:821; }","duration":"422.985934ms","start":"2026-09-18T15:14:32.540656Z","end":"2026-09-18T15:14:32.963641Z","steps":["trace[1770961253] 'agreement among raft nodes before linearized reading' (duration: 422.948374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.963679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.540594Z","time spent":"423.066906ms","remote":"127.0.0.1:45102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-09-18T15:14:32.963798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.257683ms","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-09-18T15:14:32.963832Z","caller":"traceutil/trace.go:171","msg":"trace[1589542021] 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:821; }","duration":"503.295433ms","start":"2026-09-18T15:14:32.460518Z","end":"2026-09-18T15:14:32.963813Z","steps":["trace[1589542021] 'agreement among raft nodes before linearized reading' (duration: 503.259413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.963837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.627091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:14:32.963868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.742901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:14:32.963937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.491373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:14:32.963965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.197312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.963986Z","caller":"traceutil/trace.go:171","msg":"trace[1672553509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:821; }","duration":"464.917054ms","start":"2026-09-18T15:14:32.499048Z","end":"2026-09-18T15:14:32.963965Z","steps":["trace[1672553509] 'agreement among raft nodes before linearized reading' (duration: 464.6787ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:32.96401Z","caller":"traceutil/trace.go:171","msg":"trace[1591755440] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:821; }","duration":"390.242593ms","start":"2026-09-18T15:14:32.57376Z","end":"2026-09-18T15:14:32.964003Z","steps":["trace[1591755440] 'agreement among raft nodes before linearized reading' (duration: 390.183371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.964037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.573705Z","time spent":"390.324943ms","remote":"127.0.0.1:45106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-09-18T15:14:32.964022Z","caller":"traceutil/trace.go:171","msg":"trace[413643842] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:821; }","duration":"370.636535ms","start":"2026-09-18T15:14:32.593358Z","end":"2026-09-18T15:14:32.963994Z","steps":["trace[413643842] 'agreement among raft nodes before linearized reading' (duration: 370.506013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.964031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.499025Z","time spent":"464.993956ms","remote":"127.0.0.1:45092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:14:32.964096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.593338Z","time spent":"370.737718ms","remote":"127.0.0.1:45114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:14:32.964181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.971926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.964208Z","caller":"traceutil/trace.go:171","msg":"trace[1736458957] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:821; }","duration":"465.040146ms","start":"2026-09-18T15:14:32.499162Z","end":"2026-09-18T15:14:32.964202Z","steps":["trace[1736458957] 'agreement among raft nodes before linearized reading' (duration: 464.982985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.964247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.499074Z","time spent":"465.147448ms","remote":"127.0.0.1:45092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-09-18T15:14:32.96385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.460509Z","time spent":"503.334754ms","remote":"127.0.0.1:45080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"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-09-18T15:14:32.964405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.132928ms","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-09-18T15:14:32.963879Z","caller":"traceutil/trace.go:171","msg":"trace[1358259697] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:821; }","duration":"389.727793ms","start":"2026-09-18T15:14:32.574144Z","end":"2026-09-18T15:14:32.963872Z","steps":["trace[1358259697] 'agreement among raft nodes before linearized reading' (duration: 389.667172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.964476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.574095Z","time spent":"390.370435ms","remote":"127.0.0.1:45106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-09-18T15:14:32.96444Z","caller":"traceutil/trace.go:171","msg":"trace[1352518735] 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:821; }","duration":"504.195619ms","start":"2026-09-18T15:14:32.460234Z","end":"2026-09-18T15:14:32.96443Z","steps":["trace[1352518735] 'agreement among raft nodes before linearized reading' (duration: 504.137468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.963804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.52326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.964573Z","caller":"traceutil/trace.go:171","msg":"trace[545036811] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:821; }","duration":"390.347244ms","start":"2026-09-18T15:14:32.574215Z","end":"2026-09-18T15:14:32.964562Z","steps":["trace[545036811] 'agreement among raft nodes before linearized reading' (duration: 389.469109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.9646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.573771Z","time spent":"390.821943ms","remote":"127.0.0.1:45106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-09-18T15:14:32.964604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.460213Z","time spent":"504.342051ms","remote":"127.0.0.1:45080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:14:32.96469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.034713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.964797Z","caller":"traceutil/trace.go:171","msg":"trace[1019194507] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:821; }","duration":"371.174775ms","start":"2026-09-18T15:14:32.593603Z","end":"2026-09-18T15:14:32.964777Z","steps":["trace[1019194507] 'agreement among raft nodes before linearized reading' (duration: 369.73256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.964825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.408452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.964868Z","caller":"traceutil/trace.go:171","msg":"trace[913663544] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:821; }","duration":"186.484323ms","start":"2026-09-18T15:14:32.778372Z","end":"2026-09-18T15:14:32.964856Z","steps":["trace[913663544] 'agreement among raft nodes before linearized reading' (duration: 186.418702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.96493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.593596Z","time spent":"371.252076ms","remote":"127.0.0.1:45114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"info","ts":"2026-09-18T15:14:32.963321Z","caller":"traceutil/trace.go:171","msg":"trace[1510486004] 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:821; }","duration":"502.917496ms","start":"2026-09-18T15:14:32.460384Z","end":"2026-09-18T15:14:32.963301Z","steps":["trace[1510486004] 'agreement among raft nodes before linearized reading' (duration: 502.793994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.965106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.691967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:14:32.965168Z","caller":"traceutil/trace.go:171","msg":"trace[236588827] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:821; }","duration":"186.784709ms","start":"2026-09-18T15:14:32.778372Z","end":"2026-09-18T15:14:32.965156Z","steps":["trace[236588827] 'agreement among raft nodes before linearized reading' (duration: 186.704937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.965173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.460299Z","time spent":"504.855411ms","remote":"127.0.0.1:45080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"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-09-18T15:14:32.964308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.774668ms","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-09-18T15:14:32.965239Z","caller":"traceutil/trace.go:171","msg":"trace[1391863859] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:821; }","duration":"424.733295ms","start":"2026-09-18T15:14:32.540498Z","end":"2026-09-18T15:14:32.965231Z","steps":["trace[1391863859] 'agreement among raft nodes before linearized reading' (duration: 423.792549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:32.96526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:14:32.540478Z","time spent":"424.774855ms","remote":"127.0.0.1:45102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-09-18T15:14:33.157288Z","caller":"traceutil/trace.go:171","msg":"trace[210479198] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:858; }","duration":"147.508489ms","start":"2026-09-18T15:14:33.009752Z","end":"2026-09-18T15:14:33.15726Z","steps":["trace[210479198] 'read index received' (duration: 128.062067ms)","trace[210479198] 'applied index is now lower than readState.Index' (duration: 19.445342ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:33.157417Z","caller":"traceutil/trace.go:171","msg":"trace[1784870548] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"187.039287ms","start":"2026-09-18T15:14:32.970364Z","end":"2026-09-18T15:14:33.157404Z","steps":["trace[1784870548] 'process raft request' (duration: 167.486064ms)","trace[1784870548] 'compare' (duration: 19.278379ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:14:33.157764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.980867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:14:33.157839Z","caller":"traceutil/trace.go:171","msg":"trace[295090511] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:822; }","duration":"148.11832ms","start":"2026-09-18T15:14:33.009704Z","end":"2026-09-18T15:14:33.157822Z","steps":["trace[295090511] 'agreement among raft nodes before linearized reading' (duration: 147.999088ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:36.777926Z","caller":"traceutil/trace.go:171","msg":"trace[1718887516] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"123.898402ms","start":"2026-09-18T15:14:36.653997Z","end":"2026-09-18T15:14:36.777895Z","steps":["trace[1718887516] 'process raft request' (duration: 69.469097ms)","trace[1718887516] 'compare' (duration: 54.250072ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:36.919383Z","caller":"traceutil/trace.go:171","msg":"trace[840450747] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"136.9246ms","start":"2026-09-18T15:14:36.78242Z","end":"2026-09-18T15:14:36.919345Z","steps":["trace[840450747] 'process raft request' (duration: 117.267689ms)","trace[840450747] 'compare' (duration: 19.492238ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:42.808906Z","caller":"traceutil/trace.go:171","msg":"trace[393862579] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"122.92976ms","start":"2026-09-18T15:14:42.685944Z","end":"2026-09-18T15:14:42.808873Z","steps":["trace[393862579] 'process raft request' (duration: 122.870249ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:42.809223Z","caller":"traceutil/trace.go:171","msg":"trace[249606743] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"128.134735ms","start":"2026-09-18T15:14:42.681033Z","end":"2026-09-18T15:14:42.809168Z","steps":["trace[249606743] 'process raft request' (duration: 107.853626ms)","trace[249606743] 'compare' (duration: 19.258033ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:42.809259Z","caller":"traceutil/trace.go:171","msg":"trace[542403953] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"126.432336ms","start":"2026-09-18T15:14:42.682778Z","end":"2026-09-18T15:14:42.80921Z","steps":["trace[542403953] 'process raft request' (duration: 125.916799ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:14:42.939767Z","caller":"traceutil/trace.go:171","msg":"trace[1369463216] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"120.085164ms","start":"2026-09-18T15:14:42.819658Z","end":"2026-09-18T15:14:42.939743Z","steps":["trace[1369463216] 'process raft request' (duration: 102.553028ms)","trace[1369463216] 'compare' (duration: 17.342543ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:42.939954Z","caller":"traceutil/trace.go:171","msg":"trace[930400404] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:969; }","duration":"120.224476ms","start":"2026-09-18T15:14:42.819696Z","end":"2026-09-18T15:14:42.93992Z","steps":["trace[930400404] 'read index received' (duration: 17.26081ms)","trace[930400404] 'applied index is now lower than readState.Index' (duration: 102.960716ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:14:42.940036Z","caller":"traceutil/trace.go:171","msg":"trace[1318368388] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"117.356069ms","start":"2026-09-18T15:14:42.822658Z","end":"2026-09-18T15:14:42.940014Z","steps":["trace[1318368388] 'process raft request' (duration: 117.028824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:14:42.94016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.440169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3903"} {"level":"info","ts":"2026-09-18T15:14:42.94019Z","caller":"traceutil/trace.go:171","msg":"trace[149534650] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:933; }","duration":"120.532901ms","start":"2026-09-18T15:14:42.819648Z","end":"2026-09-18T15:14:42.940181Z","steps":["trace[149534650] 'agreement among raft nodes before linearized reading' (duration: 120.367978ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:33.596985Z","caller":"traceutil/trace.go:171","msg":"trace[2109417955] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"255.033178ms","start":"2026-09-18T15:16:33.341935Z","end":"2026-09-18T15:16:33.596968Z","steps":["trace[2109417955] 'read index received' (duration: 254.86028ms)","trace[2109417955] 'applied index is now lower than readState.Index' (duration: 172.098µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:16:33.597089Z","caller":"traceutil/trace.go:171","msg":"trace[721072129] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"412.842009ms","start":"2026-09-18T15:16:33.18424Z","end":"2026-09-18T15:16:33.597082Z","steps":["trace[721072129] 'process raft request' (duration: 412.608969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:33.597164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:33.18421Z","time spent":"412.894621ms","remote":"127.0.0.1:50232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5719,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:33.597286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.363091ms","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-09-18T15:16:33.597355Z","caller":"traceutil/trace.go:171","msg":"trace[1943438769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1380; }","duration":"255.419894ms","start":"2026-09-18T15:16:33.341883Z","end":"2026-09-18T15:16:33.597303Z","steps":["trace[1943438769] 'agreement among raft nodes before linearized reading' (duration: 255.350371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:33.597812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.884183ms","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-09-18T15:16:33.597859Z","caller":"traceutil/trace.go:171","msg":"trace[577511310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1380; }","duration":"157.979278ms","start":"2026-09-18T15:16:33.439869Z","end":"2026-09-18T15:16:33.597849Z","steps":["trace[577511310] 'agreement among raft nodes before linearized reading' (duration: 157.836272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:33.60003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.28961ms","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-09-18T15:16:33.600183Z","caller":"traceutil/trace.go:171","msg":"trace[347436245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1380; }","duration":"144.491147ms","start":"2026-09-18T15:16:33.455672Z","end":"2026-09-18T15:16:33.600163Z","steps":["trace[347436245] 'agreement among raft nodes before linearized reading' (duration: 142.278943ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:36.790455Z","caller":"traceutil/trace.go:171","msg":"trace[1623029169] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"112.641066ms","start":"2026-09-18T15:16:36.677787Z","end":"2026-09-18T15:16:36.790429Z","steps":["trace[1623029169] 'process raft request' (duration: 112.49302ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:39.922705Z","caller":"traceutil/trace.go:171","msg":"trace[1086273689] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"168.640495ms","start":"2026-09-18T15:16:39.754029Z","end":"2026-09-18T15:16:39.922669Z","steps":["trace[1086273689] 'process raft request' (duration: 135.220813ms)","trace[1086273689] 'compare' (duration: 32.941033ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:16:39.922784Z","caller":"traceutil/trace.go:171","msg":"trace[1977870082] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"167.346682ms","start":"2026-09-18T15:16:39.755411Z","end":"2026-09-18T15:16:39.922757Z","steps":["trace[1977870082] 'process raft request' (duration: 167.145454ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:39.922779Z","caller":"traceutil/trace.go:171","msg":"trace[2069671529] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"166.631303ms","start":"2026-09-18T15:16:39.756119Z","end":"2026-09-18T15:16:39.92275Z","steps":["trace[2069671529] 'process raft request' (duration: 166.514398ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:42.051916Z","caller":"traceutil/trace.go:171","msg":"trace[182933992] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"120.749489ms","start":"2026-09-18T15:16:41.931144Z","end":"2026-09-18T15:16:42.051893Z","steps":["trace[182933992] 'process raft request' (duration: 120.549131ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:42.128166Z","caller":"traceutil/trace.go:171","msg":"trace[1061418317] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1476; }","duration":"154.277396ms","start":"2026-09-18T15:16:41.97387Z","end":"2026-09-18T15:16:42.128148Z","steps":["trace[1061418317] 'read index received' (duration: 78.006122ms)","trace[1061418317] 'applied index is now lower than readState.Index' (duration: 76.270594ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:16:42.128261Z","caller":"traceutil/trace.go:171","msg":"trace[1501624002] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"195.089385ms","start":"2026-09-18T15:16:41.933166Z","end":"2026-09-18T15:16:42.128255Z","steps":["trace[1501624002] 'process raft request' (duration: 194.95838ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:42.128299Z","caller":"traceutil/trace.go:171","msg":"trace[2120617317] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"196.534405ms","start":"2026-09-18T15:16:41.931732Z","end":"2026-09-18T15:16:42.128266Z","steps":["trace[2120617317] 'process raft request' (duration: 196.293085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:42.128529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.625531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:42.128634Z","caller":"traceutil/trace.go:171","msg":"trace[589910524] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1414; }","duration":"154.788567ms","start":"2026-09-18T15:16:41.973825Z","end":"2026-09-18T15:16:42.128613Z","steps":["trace[589910524] 'agreement among raft nodes before linearized reading' (duration: 154.512926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:42.260811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.106952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:42.260923Z","caller":"traceutil/trace.go:171","msg":"trace[1264235388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1414; }","duration":"106.278199ms","start":"2026-09-18T15:16:42.154624Z","end":"2026-09-18T15:16:42.260902Z","steps":["trace[1264235388] 'range keys from in-memory index tree' (duration: 106.038269ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:42.98041Z","caller":"traceutil/trace.go:171","msg":"trace[1240514173] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"119.921311ms","start":"2026-09-18T15:16:42.860469Z","end":"2026-09-18T15:16:42.98039Z","steps":["trace[1240514173] 'process raft request' (duration: 119.763364ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:43.683597Z","caller":"traceutil/trace.go:171","msg":"trace[1295819261] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"137.735194ms","start":"2026-09-18T15:16:43.545811Z","end":"2026-09-18T15:16:43.683546Z","steps":["trace[1295819261] 'process raft request' (duration: 137.621639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:45.659821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:16:46.160907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:16:46.661955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:16:46.853212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.698812903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:46.853333Z","caller":"traceutil/trace.go:171","msg":"trace[704664406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1419; }","duration":"2.698974689s","start":"2026-09-18T15:16:44.154331Z","end":"2026-09-18T15:16:46.853305Z","steps":["trace[704664406] 'range keys from in-memory index tree' (duration: 2.698708228s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:46.85332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.720670868s","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":"warn","ts":"2026-09-18T15:16:46.853379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:44.154318Z","time spent":"2.699048711s","remote":"127.0.0.1:50062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T15:16:46.853433Z","caller":"traceutil/trace.go:171","msg":"trace[1241323872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1419; }","duration":"2.720867606s","start":"2026-09-18T15:16:44.132539Z","end":"2026-09-18T15:16:46.853407Z","steps":["trace[1241323872] 'range keys from in-memory index tree' (duration: 2.72045271s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:46.853491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:44.132519Z","time spent":"2.720961139s","remote":"127.0.0.1:50322","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-09-18T15:16:46.85366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.720930568s","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-09-18T15:16:46.853705Z","caller":"traceutil/trace.go:171","msg":"trace[898731834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1419; }","duration":"2.721013081s","start":"2026-09-18T15:16:44.132678Z","end":"2026-09-18T15:16:46.853691Z","steps":["trace[898731834] 'range keys from in-memory index tree' (duration: 2.720626315s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:46.853732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:44.132647Z","time spent":"2.721078224s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T15:16:46.853736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.18740309s","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-09-18T15:16:46.853816Z","caller":"traceutil/trace.go:171","msg":"trace[1404396623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1419; }","duration":"2.187530595s","start":"2026-09-18T15:16:44.666265Z","end":"2026-09-18T15:16:46.853796Z","steps":["trace[1404396623] 'range keys from in-memory index tree' (duration: 2.187014834s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:46.853863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:44.666246Z","time spent":"2.187607798s","remote":"127.0.0.1:50322","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-09-18T15:16:46.854048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.008392561s","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-09-18T15:16:46.854106Z","caller":"traceutil/trace.go:171","msg":"trace[69553228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1419; }","duration":"3.008483574s","start":"2026-09-18T15:16:43.845607Z","end":"2026-09-18T15:16:46.854091Z","steps":["trace[69553228] 'range keys from in-memory index tree' (duration: 3.007636062s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:46.854111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.796832258s","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-09-18T15:16:46.854166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:43.845589Z","time spent":"3.008568698s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T15:16:46.854183Z","caller":"traceutil/trace.go:171","msg":"trace[90115132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1419; }","duration":"2.796960463s","start":"2026-09-18T15:16:44.057211Z","end":"2026-09-18T15:16:46.854172Z","steps":["trace[90115132] 'range keys from in-memory index tree' (duration: 2.796687452s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:46.854209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:44.057181Z","time spent":"2.797021965s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T15:16:46.854391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.950544864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:16:46.854479Z","caller":"traceutil/trace.go:171","msg":"trace[1258854415] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1419; }","duration":"1.950667648s","start":"2026-09-18T15:16:44.903793Z","end":"2026-09-18T15:16:46.854461Z","steps":["trace[1258854415] 'count revisions from in-memory index tree' (duration: 1.950421288s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:46.854541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:44.903777Z","time spent":"1.950748522s","remote":"127.0.0.1:50422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":54,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:16:47.163115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:16:47.66387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:16:48.164744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000707058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"warn","ts":"2026-09-18T15:16:48.164776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473628,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:16:48.164861Z","caller":"traceutil/trace.go:171","msg":"trace[773548287] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000866945s","start":"2026-09-18T15:16:46.163963Z","end":"2026-09-18T15:16:48.16483Z","steps":["trace[773548287] 'agreement among raft nodes before linearized reading' (duration: 2.000608065s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:48.164921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:46.163954Z","time spent":"2.000957848s","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/18 15:16:48 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-18T15:16:48.665214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:16:49.165714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:16:49.661464Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.847642925s","expected-duration":"1s"} {"level":"info","ts":"2026-09-18T15:16:49.662779Z","caller":"traceutil/trace.go:171","msg":"trace[1597213308] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"4.677051611s","start":"2026-09-18T15:16:44.985697Z","end":"2026-09-18T15:16:49.662748Z","steps":["trace[1597213308] 'process raft request' (duration: 4.676100504s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:49.662968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:44.985673Z","time spent":"4.677190767s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:50.104438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000943887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-09-18T15:16:50.104587Z","caller":"traceutil/trace.go:171","msg":"trace[1923410237] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001182276s","start":"2026-09-18T15:16:48.103376Z","end":"2026-09-18T15:16:50.104558Z","steps":["trace[1923410237] 'agreement among raft nodes before linearized reading' (duration: 2.000962657s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:50.104766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:48.103357Z","time spent":"2.0012771s","remote":"127.0.0.1:50058","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-09-18T15:16:50.166246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001153762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-09-18T15:16:50.166549Z","caller":"traceutil/trace.go:171","msg":"trace[1746202337] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001505586s","start":"2026-09-18T15:16:48.165018Z","end":"2026-09-18T15:16:50.166524Z","steps":["trace[1746202337] 'agreement among raft nodes before linearized reading' (duration: 2.001169442s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:50.166613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:48.164999Z","time spent":"2.001601739s","remote":"127.0.0.1:50062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/18 15:16:50 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-18T15:16:51.844799Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.182903813s","expected-duration":"1s"} {"level":"info","ts":"2026-09-18T15:16:51.84555Z","caller":"traceutil/trace.go:171","msg":"trace[268660190] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"5.684640033s","start":"2026-09-18T15:16:46.16086Z","end":"2026-09-18T15:16:51.8455Z","steps":["trace[268660190] 'process raft request' (duration: 5.684343582s)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:51.845651Z","caller":"traceutil/trace.go:171","msg":"trace[1252729971] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"4.983366735s","start":"2026-09-18T15:16:46.862275Z","end":"2026-09-18T15:16:51.845642Z","steps":["trace[1252729971] 'process raft request' (duration: 4.98324239s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:51.845814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:46.862231Z","time spent":"4.983475979s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:16:51.845868Z","caller":"traceutil/trace.go:171","msg":"trace[97208656] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"4.983115915s","start":"2026-09-18T15:16:46.862726Z","end":"2026-09-18T15:16:51.845842Z","steps":["trace[97208656] 'process raft request' (duration: 4.982870155s)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:51.845584Z","caller":"traceutil/trace.go:171","msg":"trace[915676838] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"4.98347642s","start":"2026-09-18T15:16:46.862082Z","end":"2026-09-18T15:16:51.845559Z","steps":["trace[915676838] 'process raft request' (duration: 4.983323364s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:51.845969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:46.862216Z","time spent":"4.983701749s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:51.846076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:46.862047Z","time spent":"4.983872025s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:16:51.846107Z","caller":"traceutil/trace.go:171","msg":"trace[578977978] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"4.983502791s","start":"2026-09-18T15:16:46.86259Z","end":"2026-09-18T15:16:51.846093Z","steps":["trace[578977978] 'process raft request' (duration: 4.98296549s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:51.846208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:46.862554Z","time spent":"4.983615685s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:51.845838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:46.160839Z","time spent":"5.684845462s","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:16:51.84634Z","caller":"traceutil/trace.go:171","msg":"trace[1171001618] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"2.409460453s","start":"2026-09-18T15:16:49.436858Z","end":"2026-09-18T15:16:51.846319Z","steps":["trace[1171001618] 'process raft request' (duration: 2.408774907s)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:51.846329Z","caller":"traceutil/trace.go:171","msg":"trace[615329738] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"4.984197508s","start":"2026-09-18T15:16:46.862114Z","end":"2026-09-18T15:16:51.846312Z","steps":["trace[615329738] 'process raft request' (duration: 4.983365206s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:51.846438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:49.436812Z","time spent":"2.409573078s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:51.846449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:46.862086Z","time spent":"4.984317693s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:16:51.845762Z","caller":"traceutil/trace.go:171","msg":"trace[1835182850] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1484; }","duration":"6.686925794s","start":"2026-09-18T15:16:45.158814Z","end":"2026-09-18T15:16:51.84574Z","steps":["trace[1835182850] 'read index received' (duration: 4.502876787s)","trace[1835182850] 'applied index is now lower than readState.Index' (duration: 2.184047267s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:51.846628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.687775447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:16:51.84677Z","caller":"traceutil/trace.go:171","msg":"trace[1397020512] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1427; }","duration":"6.687990695s","start":"2026-09-18T15:16:45.158759Z","end":"2026-09-18T15:16:51.84675Z","steps":["trace[1397020512] 'agreement among raft nodes before linearized reading' (duration: 6.687728135s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:51.846874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:45.158737Z","time spent":"6.688124831s","remote":"127.0.0.1:50212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:16:52.174037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000584603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-09-18T15:16:52.17434Z","caller":"traceutil/trace.go:171","msg":"trace[1533736218] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000927147s","start":"2026-09-18T15:16:50.173374Z","end":"2026-09-18T15:16:52.174301Z","steps":["trace[1533736218] 'agreement among raft nodes before linearized reading' (duration: 2.000611215s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:52.174429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:50.173359Z","time spent":"2.001060842s","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/18 15:16:52 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-18T15:16:53.084531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.017486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:16:53.084854Z","caller":"traceutil/trace.go:171","msg":"trace[1745413136] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"1.401669298s","start":"2026-09-18T15:16:51.683152Z","end":"2026-09-18T15:16:53.084821Z","steps":["trace[1745413136] 'process raft request' (duration: 538.219145ms)","trace[1745413136] 'compare' (duration: 862.86561ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:53.084971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:51.683115Z","time spent":"1.401802902s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:16:53.085392Z","caller":"traceutil/trace.go:171","msg":"trace[360426510] linearizableReadLoop","detail":"{readStateIndex:1493; appliedIndex:1492; }","duration":"1.238752702s","start":"2026-09-18T15:16:51.846622Z","end":"2026-09-18T15:16:53.085375Z","steps":["trace[360426510] 'read index received' (duration: 374.682567ms)","trace[360426510] 'applied index is now lower than readState.Index' (duration: 864.063895ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:54.174407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998989871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-09-18T15:16:54.174605Z","caller":"traceutil/trace.go:171","msg":"trace[52775440] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999246201s","start":"2026-09-18T15:16:52.175336Z","end":"2026-09-18T15:16:54.174582Z","steps":["trace[52775440] 'agreement among raft nodes before linearized reading' (duration: 1.999015522s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:54.174673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:52.175318Z","time spent":"1.999344514s","remote":"127.0.0.1:50062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/18 15:16:54 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-18T15:16:54.940851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001178611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-09-18T15:16:54.94096Z","caller":"traceutil/trace.go:171","msg":"trace[1929584114] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001364169s","start":"2026-09-18T15:16:52.93957Z","end":"2026-09-18T15:16:54.940935Z","steps":["trace[1929584114] 'agreement among raft nodes before linearized reading' (duration: 2.001216223s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:54.941068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:52.939543Z","time spent":"2.001511614s","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/18 15:16:54 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-18T15:16:55.58339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.17772575s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-18T15:16:55.784857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.700167885s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-09-18T15:16:55.785147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.85651575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:55.785244Z","caller":"traceutil/trace.go:171","msg":"trace[517096054] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1429; }","duration":"7.856714428s","start":"2026-09-18T15:16:47.928508Z","end":"2026-09-18T15:16:55.785222Z","steps":["trace[517096054] 'agreement among raft nodes before linearized reading' (duration: 5.156886776s)","trace[517096054] 'count revisions from in-memory index tree' (duration: 2.699681337s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:55.785293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:47.928476Z","time spent":"7.856806351s","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:16:55.785519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.554055709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:16:55.785555Z","caller":"traceutil/trace.go:171","msg":"trace[132516848] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1429; }","duration":"4.554120811s","start":"2026-09-18T15:16:51.231424Z","end":"2026-09-18T15:16:55.785545Z","steps":["trace[132516848] 'agreement among raft nodes before linearized reading' (duration: 1.853992087s)","trace[132516848] 'count revisions from in-memory index tree' (duration: 2.700079862s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:55.785582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:51.231403Z","time spent":"4.554171594s","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-09-18T15:16:55.785535Z","caller":"traceutil/trace.go:171","msg":"trace[1485834820] linearizableReadLoop","detail":"{readStateIndex:1494; appliedIndex:1493; }","duration":"2.700075152s","start":"2026-09-18T15:16:53.085434Z","end":"2026-09-18T15:16:55.785509Z","steps":["trace[1485834820] 'read index received' (duration: 80.173µs)","trace[1485834820] 'applied index is now lower than readState.Index' (duration: 2.699993639s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:55.786345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.693014216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.113\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:55.786426Z","caller":"traceutil/trace.go:171","msg":"trace[647833108] range","detail":"{range_begin:/registry/masterleases/162.253.55.113; range_end:; response_count:0; response_revision:1429; }","duration":"8.693149171s","start":"2026-09-18T15:16:47.093257Z","end":"2026-09-18T15:16:55.786406Z","steps":["trace[647833108] 'agreement among raft nodes before linearized reading' (duration: 5.992193896s)","trace[647833108] 'range keys from in-memory index tree' (duration: 2.700842931s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:55.786385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.105505243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:16:55.786455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.026644268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:16:55.78649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:47.093231Z","time spent":"8.693249335s","remote":"127.0.0.1:50092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.113\" "} {"level":"info","ts":"2026-09-18T15:16:55.786426Z","caller":"traceutil/trace.go:171","msg":"trace[1121216250] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"2.380888171s","start":"2026-09-18T15:16:53.405496Z","end":"2026-09-18T15:16:55.786384Z","steps":["trace[1121216250] 'process raft request' (duration: 2.379900013s)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:55.786605Z","caller":"traceutil/trace.go:171","msg":"trace[2076777301] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1429; }","duration":"9.026869537s","start":"2026-09-18T15:16:46.759697Z","end":"2026-09-18T15:16:55.786566Z","steps":["trace[2076777301] 'agreement among raft nodes before linearized reading' (duration: 6.325796476s)","trace[2076777301] 'range keys from in-memory index tree' (duration: 2.700861932s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:55.786631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.934012207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18d6733ca0bb9854\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-09-18T15:16:55.786675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:46.759664Z","time spent":"9.026998602s","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-09-18T15:16:55.786553Z","caller":"traceutil/trace.go:171","msg":"trace[1655774807] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1429; }","duration":"8.105727212s","start":"2026-09-18T15:16:47.680799Z","end":"2026-09-18T15:16:55.786526Z","steps":["trace[1655774807] 'agreement among raft nodes before linearized reading' (duration: 5.404641101s)","trace[1655774807] 'count revisions from in-memory index tree' (duration: 2.700890433s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:55.786877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:47.68077Z","time spent":"8.106065375s","remote":"127.0.0.1:45028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-09-18T15:16:55.786768Z","caller":"traceutil/trace.go:171","msg":"trace[249690348] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18d6733ca0bb9854; range_end:; response_count:1; response_revision:1429; }","duration":"3.934238575s","start":"2026-09-18T15:16:51.852499Z","end":"2026-09-18T15:16:55.786738Z","steps":["trace[249690348] 'agreement among raft nodes before linearized reading' (duration: 3.933516277s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:55.789643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:51.852458Z","time spent":"3.937164837s","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18d6733ca0bb9854\" "} {"level":"warn","ts":"2026-09-18T15:16:55.786936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:53.405458Z","time spent":"2.38114024s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:56.18122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999935188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-09-18T15:16:56.181312Z","caller":"traceutil/trace.go:171","msg":"trace[745618008] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000098274s","start":"2026-09-18T15:16:54.181197Z","end":"2026-09-18T15:16:56.181296Z","steps":["trace[745618008] 'agreement among raft nodes before linearized reading' (duration: 1.9999849s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.181349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:54.18113Z","time spent":"2.000212468s","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/18 15:16:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-18T15:16:56.361854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.590675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:16:56.362008Z","caller":"traceutil/trace.go:171","msg":"trace[1302710440] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"576.245943ms","start":"2026-09-18T15:16:55.785733Z","end":"2026-09-18T15:16:56.361979Z","steps":["trace[1302710440] 'read index received' (duration: 231.374277ms)","trace[1302710440] 'applied index is now lower than readState.Index' (duration: 344.869846ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:16:56.362115Z","caller":"traceutil/trace.go:171","msg":"trace[1027068212] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"1.269067597s","start":"2026-09-18T15:16:55.093029Z","end":"2026-09-18T15:16:56.362096Z","steps":["trace[1027068212] 'process raft request' (duration: 924.076267ms)","trace[1027068212] 'compare' (duration: 344.389888ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:56.362258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:55.092999Z","time spent":"1.269199062s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:56.362426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.508853856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-18T15:16:56.362493Z","caller":"traceutil/trace.go:171","msg":"trace[551609766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1431; }","duration":"2.508972561s","start":"2026-09-18T15:16:53.853504Z","end":"2026-09-18T15:16:56.362476Z","steps":["trace[551609766] 'agreement among raft nodes before linearized reading' (duration: 2.508768113s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.362533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184333503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-09-18T15:16:56.36251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.507870138s","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-09-18T15:16:56.362595Z","caller":"traceutil/trace.go:171","msg":"trace[815220999] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1431; }","duration":"2.184430457s","start":"2026-09-18T15:16:54.178149Z","end":"2026-09-18T15:16:56.362579Z","steps":["trace[815220999] 'agreement among raft nodes before linearized reading' (duration: 2.184322983s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.362636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:54.17811Z","time spent":"2.18451542s","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7134,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-09-18T15:16:56.362611Z","caller":"traceutil/trace.go:171","msg":"trace[831522341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1431; }","duration":"2.508042304s","start":"2026-09-18T15:16:53.854551Z","end":"2026-09-18T15:16:56.362594Z","steps":["trace[831522341] 'agreement among raft nodes before linearized reading' (duration: 2.50765149s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.362542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:53.853468Z","time spent":"2.509061814s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-18T15:16:56.36273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:53.854528Z","time spent":"2.508164389s","remote":"127.0.0.1:50322","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-09-18T15:16:56.362829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.508159559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-18T15:16:56.362428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.508792993s","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-09-18T15:16:56.362939Z","caller":"traceutil/trace.go:171","msg":"trace[1779571272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1431; }","duration":"2.508307465s","start":"2026-09-18T15:16:53.854608Z","end":"2026-09-18T15:16:56.362915Z","steps":["trace[1779571272] 'agreement among raft nodes before linearized reading' (duration: 2.507843427s)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:56.362975Z","caller":"traceutil/trace.go:171","msg":"trace[1618097069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1431; }","duration":"2.509338434s","start":"2026-09-18T15:16:53.853614Z","end":"2026-09-18T15:16:56.362953Z","steps":["trace[1618097069] 'agreement among raft nodes before linearized reading' (duration: 2.508675909s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.363016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:53.854605Z","time spent":"2.508385058s","remote":"127.0.0.1:50322","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-09-18T15:16:56.362532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.563305552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:16:56.363045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:53.853567Z","time spent":"2.509459809s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T15:16:56.363126Z","caller":"traceutil/trace.go:171","msg":"trace[1505257402] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1431; }","duration":"2.563906715s","start":"2026-09-18T15:16:53.799162Z","end":"2026-09-18T15:16:56.363069Z","steps":["trace[1505257402] 'agreement among raft nodes before linearized reading' (duration: 2.563312302s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.363203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:53.799136Z","time spent":"2.56404099s","remote":"127.0.0.1:45054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-18T15:16:56.362437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.50765415s","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-09-18T15:16:56.363276Z","caller":"traceutil/trace.go:171","msg":"trace[578509538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1431; }","duration":"2.508510883s","start":"2026-09-18T15:16:53.854745Z","end":"2026-09-18T15:16:56.363255Z","steps":["trace[578509538] 'agreement among raft nodes before linearized reading' (duration: 2.507506314s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.363344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:53.854733Z","time spent":"2.508592265s","remote":"127.0.0.1:50322","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-09-18T15:16:56.413171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:55.788472Z","time spent":"624.692223ms","remote":"127.0.0.1:50092","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-18T15:16:56.413271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.445358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:56.413363Z","caller":"traceutil/trace.go:171","msg":"trace[922701917] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1432; }","duration":"617.630205ms","start":"2026-09-18T15:16:55.795689Z","end":"2026-09-18T15:16:56.413319Z","steps":["trace[922701917] 'agreement among raft nodes before linearized reading' (duration: 617.472409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.413411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:55.795656Z","time spent":"617.74498ms","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-09-18T15:16:56.413402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.744733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:56.413461Z","caller":"traceutil/trace.go:171","msg":"trace[1825120784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1432; }","duration":"231.827046ms","start":"2026-09-18T15:16:56.181616Z","end":"2026-09-18T15:16:56.413443Z","steps":["trace[1825120784] 'agreement among raft nodes before linearized reading' (duration: 231.728082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.413643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.057297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:56.413684Z","caller":"traceutil/trace.go:171","msg":"trace[2006257598] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1432; }","duration":"334.158092ms","start":"2026-09-18T15:16:56.079512Z","end":"2026-09-18T15:16:56.41367Z","steps":["trace[2006257598] 'agreement among raft nodes before linearized reading' (duration: 334.085509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.413771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.079478Z","time spent":"334.280176ms","remote":"127.0.0.1:50528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-09-18T15:16:56.414803Z","caller":"traceutil/trace.go:171","msg":"trace[1400415635] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"620.359419ms","start":"2026-09-18T15:16:55.793316Z","end":"2026-09-18T15:16:56.413676Z","steps":["trace[1400415635] 'process raft request' (duration: 619.699744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.415083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:55.793271Z","time spent":"621.670187ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:16:56.900275Z","caller":"traceutil/trace.go:171","msg":"trace[696784280] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"530.660283ms","start":"2026-09-18T15:16:56.369563Z","end":"2026-09-18T15:16:56.900223Z","steps":["trace[696784280] 'process raft request' (duration: 530.460285ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:56.900327Z","caller":"traceutil/trace.go:171","msg":"trace[1298510675] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"523.94829ms","start":"2026-09-18T15:16:56.376368Z","end":"2026-09-18T15:16:56.900316Z","steps":["trace[1298510675] 'process raft request' (duration: 523.878807ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:56.900349Z","caller":"traceutil/trace.go:171","msg":"trace[923253424] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"531.64909ms","start":"2026-09-18T15:16:56.368649Z","end":"2026-09-18T15:16:56.900298Z","steps":["trace[923253424] 'process raft request' (duration: 531.188612ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:56.900474Z","caller":"traceutil/trace.go:171","msg":"trace[374847635] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"529.159626ms","start":"2026-09-18T15:16:56.371297Z","end":"2026-09-18T15:16:56.900457Z","steps":["trace[374847635] 'process raft request' (duration: 528.845244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.900496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.369521Z","time spent":"530.84509ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:56.900648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.371279Z","time spent":"529.240558ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:16:56.900796Z","caller":"traceutil/trace.go:171","msg":"trace[94981268] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"529.541401ms","start":"2026-09-18T15:16:56.371247Z","end":"2026-09-18T15:16:56.900789Z","steps":["trace[94981268] 'process raft request' (duration: 528.861205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.9008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.368638Z","time spent":"531.806376ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:56.900857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.371006Z","time spent":"529.803991ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:16:56.899948Z","caller":"traceutil/trace.go:171","msg":"trace[936745331] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"531.372109ms","start":"2026-09-18T15:16:56.368528Z","end":"2026-09-18T15:16:56.8999Z","steps":["trace[936745331] 'process raft request' (duration: 531.092008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:56.9011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.376355Z","time spent":"524.023642ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:56.90111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.368495Z","time spent":"532.565344ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:16:57.108932Z","caller":"traceutil/trace.go:171","msg":"trace[230900664] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1498; }","duration":"689.252484ms","start":"2026-09-18T15:16:56.419636Z","end":"2026-09-18T15:16:57.108889Z","steps":["trace[230900664] 'read index received' (duration: 480.00373ms)","trace[230900664] 'applied index is now lower than readState.Index' (duration: 209.246144ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:57.109279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.637258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18d6733ca0bb9854\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-09-18T15:16:57.109328Z","caller":"traceutil/trace.go:171","msg":"trace[325476535] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18d6733ca0bb9854; range_end:; response_count:1; response_revision:1439; }","duration":"689.729331ms","start":"2026-09-18T15:16:56.41958Z","end":"2026-09-18T15:16:57.109309Z","steps":["trace[325476535] 'agreement among raft nodes before linearized reading' (duration: 689.499152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:57.109278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.488246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:16:57.109458Z","caller":"traceutil/trace.go:171","msg":"trace[108059668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1439; }","duration":"687.756337ms","start":"2026-09-18T15:16:56.421684Z","end":"2026-09-18T15:16:57.10944Z","steps":["trace[108059668] 'agreement among raft nodes before linearized reading' (duration: 687.514287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:57.109567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.421653Z","time spent":"687.893242ms","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T15:16:57.109365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.419555Z","time spent":"689.801043ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18d6733ca0bb9854\" "} {"level":"info","ts":"2026-09-18T15:16:57.108965Z","caller":"traceutil/trace.go:171","msg":"trace[1374691133] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"693.787005ms","start":"2026-09-18T15:16:56.415153Z","end":"2026-09-18T15:16:57.10894Z","steps":["trace[1374691133] 'process raft request' (duration: 612.78963ms)","trace[1374691133] 'compare' (duration: 80.580689ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:57.109953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:56.415051Z","time spent":"694.784842ms","remote":"127.0.0.1:50092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:57.616071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034473658,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:16:57.712232Z","caller":"traceutil/trace.go:171","msg":"trace[1163361742] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"596.899427ms","start":"2026-09-18T15:16:57.1153Z","end":"2026-09-18T15:16:57.712199Z","steps":["trace[1163361742] 'read index received' (duration: 531.331572ms)","trace[1163361742] 'applied index is now lower than readState.Index' (duration: 65.566625ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:16:57.712328Z","caller":"traceutil/trace.go:171","msg":"trace[1505476425] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"599.12383ms","start":"2026-09-18T15:16:57.113164Z","end":"2026-09-18T15:16:57.712288Z","steps":["trace[1505476425] 'process raft request' (duration: 533.400869ms)","trace[1505476425] 'compare' (duration: 65.525643ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:57.712518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:57.11309Z","time spent":"599.302037ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:16:57.713133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.770228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-09-18T15:16:57.71326Z","caller":"traceutil/trace.go:171","msg":"trace[386689013] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1440; }","duration":"597.979377ms","start":"2026-09-18T15:16:57.115251Z","end":"2026-09-18T15:16:57.713231Z","steps":["trace[386689013] 'agreement among raft nodes before linearized reading' (duration: 597.102614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:57.713364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.862202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-09-18T15:16:57.713302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:57.115225Z","time spent":"598.06827ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7326,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-09-18T15:16:57.713415Z","caller":"traceutil/trace.go:171","msg":"trace[887131577] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1440; }","duration":"597.959056ms","start":"2026-09-18T15:16:57.115443Z","end":"2026-09-18T15:16:57.713402Z","steps":["trace[887131577] 'agreement among raft nodes before linearized reading' (duration: 597.757869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:57.713451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:16:57.115419Z","time spent":"598.022569ms","remote":"127.0.0.1:50092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-09-18T15:16:57.928789Z","caller":"traceutil/trace.go:171","msg":"trace[2140232197] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"203.715417ms","start":"2026-09-18T15:16:57.724998Z","end":"2026-09-18T15:16:57.928714Z","steps":["trace[2140232197] 'process raft request' (duration: 179.514928ms)","trace[2140232197] 'compare' (duration: 23.946631ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:16:57.960532Z","caller":"traceutil/trace.go:171","msg":"trace[206297296] linearizableReadLoop","detail":"{readStateIndex:1509; appliedIndex:1507; }","duration":"220.507778ms","start":"2026-09-18T15:16:57.739986Z","end":"2026-09-18T15:16:57.960494Z","steps":["trace[206297296] 'read index received' (duration: 164.543495ms)","trace[206297296] 'applied index is now lower than readState.Index' (duration: 55.962923ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:16:57.960746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.708876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18d6733ca0bb9854\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-09-18T15:16:57.960853Z","caller":"traceutil/trace.go:171","msg":"trace[1798904930] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18d6733ca0bb9854; range_end:; response_count:1; response_revision:1442; }","duration":"220.882012ms","start":"2026-09-18T15:16:57.739958Z","end":"2026-09-18T15:16:57.96084Z","steps":["trace[1798904930] 'agreement among raft nodes before linearized reading' (duration: 220.636343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:16:57.961056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.958411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/default/test-pvc-pod\" ","response":"range_response_count:1 size:1872"} {"level":"info","ts":"2026-09-18T15:16:57.961148Z","caller":"traceutil/trace.go:171","msg":"trace[608001210] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/default/test-pvc-pod; range_end:; response_count:1; response_revision:1442; }","duration":"123.117548ms","start":"2026-09-18T15:16:57.838014Z","end":"2026-09-18T15:16:57.961132Z","steps":["trace[608001210] 'agreement among raft nodes before linearized reading' (duration: 122.866708ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:58.12413Z","caller":"traceutil/trace.go:171","msg":"trace[712569811] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"155.546405ms","start":"2026-09-18T15:16:57.968545Z","end":"2026-09-18T15:16:58.124091Z","steps":["trace[712569811] 'process raft request' (duration: 155.451051ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:58.124352Z","caller":"traceutil/trace.go:171","msg":"trace[141157015] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"160.663857ms","start":"2026-09-18T15:16:57.96364Z","end":"2026-09-18T15:16:58.124304Z","steps":["trace[141157015] 'process raft request' (duration: 160.272262ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:16:58.124479Z","caller":"traceutil/trace.go:171","msg":"trace[2090177876] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"161.096274ms","start":"2026-09-18T15:16:57.963353Z","end":"2026-09-18T15:16:58.124449Z","steps":["trace[2090177876] 'process raft request' (duration: 137.428918ms)","trace[2090177876] 'compare' (duration: 22.895056ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:16:58.418687Z","caller":"traceutil/trace.go:171","msg":"trace[905308862] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"228.082502ms","start":"2026-09-18T15:16:58.190569Z","end":"2026-09-18T15:16:58.418651Z","steps":["trace[905308862] 'process raft request' (duration: 227.665347ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:01.249256Z","caller":"traceutil/trace.go:171","msg":"trace[1060670987] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"202.683364ms","start":"2026-09-18T15:17:01.046529Z","end":"2026-09-18T15:17:01.249213Z","steps":["trace[1060670987] 'process raft request' (duration: 176.78145ms)","trace[1060670987] 'compare' (duration: 25.635345ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:01.249532Z","caller":"traceutil/trace.go:171","msg":"trace[950443203] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"202.909083ms","start":"2026-09-18T15:17:01.046601Z","end":"2026-09-18T15:17:01.24951Z","steps":["trace[950443203] 'process raft request' (duration: 202.547369ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:01.249818Z","caller":"traceutil/trace.go:171","msg":"trace[1229553467] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"201.867925ms","start":"2026-09-18T15:17:01.047929Z","end":"2026-09-18T15:17:01.249797Z","steps":["trace[1229553467] 'process raft request' (duration: 201.514192ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:01.249807Z","caller":"traceutil/trace.go:171","msg":"trace[1823224870] linearizableReadLoop","detail":"{readStateIndex:1528; appliedIndex:1525; }","duration":"134.885725ms","start":"2026-09-18T15:17:01.114887Z","end":"2026-09-18T15:17:01.249772Z","steps":["trace[1823224870] 'read index received' (duration: 108.42478ms)","trace[1823224870] 'applied index is now lower than readState.Index' (duration: 26.458395ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:17:01.249956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.042651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:01.250015Z","caller":"traceutil/trace.go:171","msg":"trace[1506625695] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1460; }","duration":"135.169636ms","start":"2026-09-18T15:17:01.114829Z","end":"2026-09-18T15:17:01.249998Z","steps":["trace[1506625695] 'agreement among raft nodes before linearized reading' (duration: 135.042401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:02.227726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.849463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:02.227839Z","caller":"traceutil/trace.go:171","msg":"trace[52042406] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:1461; }","duration":"104.011718ms","start":"2026-09-18T15:17:02.123798Z","end":"2026-09-18T15:17:02.227809Z","steps":["trace[52042406] 'range keys from in-memory index tree' (duration: 103.683016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:02.986208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.764143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-09-18T15:17:02.986322Z","caller":"traceutil/trace.go:171","msg":"trace[713277197] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1462; }","duration":"339.99482ms","start":"2026-09-18T15:17:02.646292Z","end":"2026-09-18T15:17:02.986287Z","steps":["trace[713277197] 'range keys from in-memory index tree' (duration: 339.521473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:02.986394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:02.646265Z","time spent":"340.094594ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-09-18T15:17:03.198762Z","caller":"traceutil/trace.go:171","msg":"trace[1537068742] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"126.98363ms","start":"2026-09-18T15:17:03.071707Z","end":"2026-09-18T15:17:03.19869Z","steps":["trace[1537068742] 'process raft request' (duration: 63.290514ms)","trace[1537068742] 'compare' (duration: 63.444377ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:03.369017Z","caller":"traceutil/trace.go:171","msg":"trace[925130590] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"108.841747ms","start":"2026-09-18T15:17:03.260142Z","end":"2026-09-18T15:17:03.368984Z","steps":["trace[925130590] 'process raft request' (duration: 108.798235ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:03.369189Z","caller":"traceutil/trace.go:171","msg":"trace[1710049026] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"109.533292ms","start":"2026-09-18T15:17:03.259649Z","end":"2026-09-18T15:17:03.369183Z","steps":["trace[1710049026] 'process raft request' (duration: 109.182999ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:04.33919Z","caller":"traceutil/trace.go:171","msg":"trace[1943412875] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1535; }","duration":"182.87472ms","start":"2026-09-18T15:17:04.156282Z","end":"2026-09-18T15:17:04.339156Z","steps":["trace[1943412875] 'read index received' (duration: 182.548488ms)","trace[1943412875] 'applied index is now lower than readState.Index' (duration: 320.622µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:04.33924Z","caller":"traceutil/trace.go:171","msg":"trace[704228985] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"196.866549ms","start":"2026-09-18T15:17:04.142327Z","end":"2026-09-18T15:17:04.339194Z","steps":["trace[704228985] 'process raft request' (duration: 196.62521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:04.339404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.090458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:04.339454Z","caller":"traceutil/trace.go:171","msg":"trace[869794108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1468; }","duration":"183.220533ms","start":"2026-09-18T15:17:04.156222Z","end":"2026-09-18T15:17:04.339442Z","steps":["trace[869794108] 'agreement among raft nodes before linearized reading' (duration: 183.061447ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:04.678996Z","caller":"traceutil/trace.go:171","msg":"trace[1852913539] linearizableReadLoop","detail":"{readStateIndex:1537; appliedIndex:1536; }","duration":"112.017234ms","start":"2026-09-18T15:17:04.566955Z","end":"2026-09-18T15:17:04.678972Z","steps":["trace[1852913539] 'read index received' (duration: 111.780695ms)","trace[1852913539] 'applied index is now lower than readState.Index' (duration: 235.619µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:04.679023Z","caller":"traceutil/trace.go:171","msg":"trace[51880064] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"142.368537ms","start":"2026-09-18T15:17:04.53664Z","end":"2026-09-18T15:17:04.679009Z","steps":["trace[51880064] 'process raft request' (duration: 142.195041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:04.679192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.21698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5912"} {"level":"info","ts":"2026-09-18T15:17:04.679219Z","caller":"traceutil/trace.go:171","msg":"trace[963666642] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1469; }","duration":"112.317614ms","start":"2026-09-18T15:17:04.566892Z","end":"2026-09-18T15:17:04.67921Z","steps":["trace[963666642] 'agreement among raft nodes before linearized reading' (duration: 112.179849ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:06.185651Z","caller":"traceutil/trace.go:171","msg":"trace[46665501] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1542; }","duration":"164.995854ms","start":"2026-09-18T15:17:06.020628Z","end":"2026-09-18T15:17:06.185624Z","steps":["trace[46665501] 'read index received' (duration: 164.749995ms)","trace[46665501] 'applied index is now lower than readState.Index' (duration: 245.249µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:06.18574Z","caller":"traceutil/trace.go:171","msg":"trace[2082478738] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"167.970101ms","start":"2026-09-18T15:17:06.017721Z","end":"2026-09-18T15:17:06.185691Z","steps":["trace[2082478738] 'process raft request' (duration: 167.734083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:06.185958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.235342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:1 size:1930"} {"level":"info","ts":"2026-09-18T15:17:06.185992Z","caller":"traceutil/trace.go:171","msg":"trace[155579736] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:1; response_revision:1475; }","duration":"165.406609ms","start":"2026-09-18T15:17:06.020576Z","end":"2026-09-18T15:17:06.185983Z","steps":["trace[155579736] 'agreement among raft nodes before linearized reading' (duration: 165.179671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:06.186092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.482235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-09-18T15:17:06.186176Z","caller":"traceutil/trace.go:171","msg":"trace[234133571] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1475; }","duration":"164.60716ms","start":"2026-09-18T15:17:06.021553Z","end":"2026-09-18T15:17:06.18616Z","steps":["trace[234133571] 'agreement among raft nodes before linearized reading' (duration: 164.396752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:07.638738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.699637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-09-18T15:17:07.638893Z","caller":"traceutil/trace.go:171","msg":"trace[73522621] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1481; }","duration":"101.910015ms","start":"2026-09-18T15:17:07.536938Z","end":"2026-09-18T15:17:07.638848Z","steps":["trace[73522621] 'range keys from in-memory index tree' (duration: 101.551151ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:07.933894Z","caller":"traceutil/trace.go:171","msg":"trace[505545223] linearizableReadLoop","detail":"{readStateIndex:1551; appliedIndex:1550; }","duration":"149.551653ms","start":"2026-09-18T15:17:07.784306Z","end":"2026-09-18T15:17:07.933858Z","steps":["trace[505545223] 'read index received' (duration: 60.002682ms)","trace[505545223] 'applied index is now lower than readState.Index' (duration: 89.5473ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:07.933989Z","caller":"traceutil/trace.go:171","msg":"trace[1410194748] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"152.81276ms","start":"2026-09-18T15:17:07.781088Z","end":"2026-09-18T15:17:07.933901Z","steps":["trace[1410194748] 'process raft request' (duration: 63.303011ms)","trace[1410194748] 'compare' (duration: 89.26048ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:17:07.934205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.877385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:791"} {"level":"warn","ts":"2026-09-18T15:17:07.934244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.766131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-09-18T15:17:07.934298Z","caller":"traceutil/trace.go:171","msg":"trace[312535913] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1482; }","duration":"150.049871ms","start":"2026-09-18T15:17:07.784229Z","end":"2026-09-18T15:17:07.934279Z","steps":["trace[312535913] 'agreement among raft nodes before linearized reading' (duration: 149.73943ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:07.934308Z","caller":"traceutil/trace.go:171","msg":"trace[1639050469] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1482; }","duration":"149.887825ms","start":"2026-09-18T15:17:07.784401Z","end":"2026-09-18T15:17:07.934289Z","steps":["trace[1639050469] 'agreement among raft nodes before linearized reading' (duration: 149.681538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:08.344032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.529544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-09-18T15:17:08.344241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.142814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:841"} {"level":"warn","ts":"2026-09-18T15:17:08.344251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.843615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:08.34431Z","caller":"traceutil/trace.go:171","msg":"trace[729025072] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1482; }","duration":"302.266488ms","start":"2026-09-18T15:17:08.042024Z","end":"2026-09-18T15:17:08.34429Z","steps":["trace[729025072] 'range keys from in-memory index tree' (duration: 301.947217ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:08.344262Z","caller":"traceutil/trace.go:171","msg":"trace[1098160928] range","detail":"{range_begin:/registry/clusterroles/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1482; }","duration":"303.847324ms","start":"2026-09-18T15:17:08.040388Z","end":"2026-09-18T15:17:08.344236Z","steps":["trace[1098160928] 'range keys from in-memory index tree' (duration: 303.325536ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:08.344369Z","caller":"traceutil/trace.go:171","msg":"trace[1914928281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1482; }","duration":"188.038161ms","start":"2026-09-18T15:17:08.156304Z","end":"2026-09-18T15:17:08.344343Z","steps":["trace[1914928281] 'range keys from in-memory index tree' (duration: 187.70174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:08.344377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:08.041996Z","time spent":"302.365302ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":864,"request content":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" "} {"level":"warn","ts":"2026-09-18T15:17:08.344438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:08.040363Z","time spent":"304.025021ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":782,"request content":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" "} {"level":"warn","ts":"2026-09-18T15:17:09.251156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.862256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:1076"} {"level":"info","ts":"2026-09-18T15:17:09.251261Z","caller":"traceutil/trace.go:171","msg":"trace[1846042729] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1484; }","duration":"152.006921ms","start":"2026-09-18T15:17:09.099233Z","end":"2026-09-18T15:17:09.25124Z","steps":["trace[1846042729] 'range keys from in-memory index tree' (duration: 151.731751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:09.251571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.879346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-09-18T15:17:09.251898Z","caller":"traceutil/trace.go:171","msg":"trace[1285333005] range","detail":"{range_begin:/registry/clusterroles/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1484; }","duration":"105.303512ms","start":"2026-09-18T15:17:09.146565Z","end":"2026-09-18T15:17:09.251868Z","steps":["trace[1285333005] 'range keys from in-memory index tree' (duration: 104.751492ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:09.852143Z","caller":"traceutil/trace.go:171","msg":"trace[1429489773] linearizableReadLoop","detail":"{readStateIndex:1557; appliedIndex:1555; }","duration":"200.943379ms","start":"2026-09-18T15:17:09.651165Z","end":"2026-09-18T15:17:09.852108Z","steps":["trace[1429489773] 'read index received' (duration: 111.238403ms)","trace[1429489773] 'applied index is now lower than readState.Index' (duration: 89.703796ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:09.852174Z","caller":"traceutil/trace.go:171","msg":"trace[1638206131] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"277.797639ms","start":"2026-09-18T15:17:09.574341Z","end":"2026-09-18T15:17:09.852139Z","steps":["trace[1638206131] 'process raft request' (duration: 277.632284ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:09.852161Z","caller":"traceutil/trace.go:171","msg":"trace[1603282832] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"277.749457ms","start":"2026-09-18T15:17:09.57434Z","end":"2026-09-18T15:17:09.852089Z","steps":["trace[1603282832] 'process raft request' (duration: 188.094473ms)","trace[1603282832] 'compare' (duration: 89.173708ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:17:09.852397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.505153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:841"} {"level":"warn","ts":"2026-09-18T15:17:09.852419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.2113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:728"} {"level":"info","ts":"2026-09-18T15:17:09.852449Z","caller":"traceutil/trace.go:171","msg":"trace[1255274079] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1488; }","duration":"180.642338ms","start":"2026-09-18T15:17:09.671789Z","end":"2026-09-18T15:17:09.852431Z","steps":["trace[1255274079] 'agreement among raft nodes before linearized reading' (duration: 180.512063ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:09.852485Z","caller":"traceutil/trace.go:171","msg":"trace[1102273399] range","detail":"{range_begin:/registry/clusterroles/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1488; }","duration":"201.367225ms","start":"2026-09-18T15:17:09.651099Z","end":"2026-09-18T15:17:09.852467Z","steps":["trace[1102273399] 'agreement among raft nodes before linearized reading' (duration: 201.168528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:10.07931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.642925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:828"} {"level":"info","ts":"2026-09-18T15:17:10.079392Z","caller":"traceutil/trace.go:171","msg":"trace[243342508] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:1488; }","duration":"113.770049ms","start":"2026-09-18T15:17:09.965601Z","end":"2026-09-18T15:17:10.079371Z","steps":["trace[243342508] 'range keys from in-memory index tree' (duration: 113.475468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:11.642736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.334081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-09-18T15:17:11.642864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.336145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:1 size:634"} {"level":"info","ts":"2026-09-18T15:17:11.64291Z","caller":"traceutil/trace.go:171","msg":"trace[2041887554] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:1491; }","duration":"115.576199ms","start":"2026-09-18T15:17:11.527287Z","end":"2026-09-18T15:17:11.642863Z","steps":["trace[2041887554] 'range keys from in-memory index tree' (duration: 115.183155ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:11.642955Z","caller":"traceutil/trace.go:171","msg":"trace[1065199819] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:1; response_revision:1491; }","duration":"112.507221ms","start":"2026-09-18T15:17:11.530426Z","end":"2026-09-18T15:17:11.642933Z","steps":["trace[1065199819] 'range keys from in-memory index tree' (duration: 112.21941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:12.103006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.036591ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-18T15:17:12.103149Z","caller":"traceutil/trace.go:171","msg":"trace[801976023] linearizableReadLoop","detail":"{readStateIndex:1562; appliedIndex:1561; }","duration":"293.362741ms","start":"2026-09-18T15:17:11.809747Z","end":"2026-09-18T15:17:12.10311Z","steps":["trace[801976023] 'read index received' (duration: 163.123703ms)","trace[801976023] 'applied index is now lower than readState.Index' (duration: 130.237368ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:17:12.103373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.593219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" ","response":"range_response_count:1 size:1486"} {"level":"warn","ts":"2026-09-18T15:17:12.103473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.068761ms","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-09-18T15:17:12.103491Z","caller":"traceutil/trace.go:171","msg":"trace[366453361] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:1; response_revision:1492; }","duration":"293.769766ms","start":"2026-09-18T15:17:11.809692Z","end":"2026-09-18T15:17:12.103462Z","steps":["trace[366453361] 'agreement among raft nodes before linearized reading' (duration: 293.531437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:12.103881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.635651ms","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-09-18T15:17:12.103944Z","caller":"traceutil/trace.go:171","msg":"trace[524848820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1492; }","duration":"247.755275ms","start":"2026-09-18T15:17:11.856171Z","end":"2026-09-18T15:17:12.103926Z","steps":["trace[524848820] 'agreement among raft nodes before linearized reading' (duration: 247.151364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:12.104029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.301019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:633"} {"level":"info","ts":"2026-09-18T15:17:12.10351Z","caller":"traceutil/trace.go:171","msg":"trace[1214021321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1492; }","duration":"247.174595ms","start":"2026-09-18T15:17:11.85632Z","end":"2026-09-18T15:17:12.103495Z","steps":["trace[1214021321] 'agreement among raft nodes before linearized reading' (duration: 246.976358ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:12.104192Z","caller":"traceutil/trace.go:171","msg":"trace[785166836] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:1492; }","duration":"293.370082ms","start":"2026-09-18T15:17:11.81071Z","end":"2026-09-18T15:17:12.10408Z","steps":["trace[785166836] 'agreement among raft nodes before linearized reading' (duration: 292.532812ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:15.302837Z","caller":"traceutil/trace.go:171","msg":"trace[1447236092] linearizableReadLoop","detail":"{readStateIndex:1574; appliedIndex:1573; }","duration":"148.763261ms","start":"2026-09-18T15:17:15.154049Z","end":"2026-09-18T15:17:15.302812Z","steps":["trace[1447236092] 'read index received' (duration: 148.500713ms)","trace[1447236092] 'applied index is now lower than readState.Index' (duration: 261.228µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:17:15.303056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.97942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:15.30312Z","caller":"traceutil/trace.go:171","msg":"trace[20763918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1503; }","duration":"149.090044ms","start":"2026-09-18T15:17:15.154016Z","end":"2026-09-18T15:17:15.303106Z","steps":["trace[20763918] 'agreement among raft nodes before linearized reading' (duration: 148.965419ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:15.302927Z","caller":"traceutil/trace.go:171","msg":"trace[1144474002] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"162.642141ms","start":"2026-09-18T15:17:15.140255Z","end":"2026-09-18T15:17:15.302897Z","steps":["trace[1144474002] 'process raft request' (duration: 162.372053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:15.303393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.624678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-09-18T15:17:15.303501Z","caller":"traceutil/trace.go:171","msg":"trace[2139122912] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1503; }","duration":"100.790184ms","start":"2026-09-18T15:17:15.202694Z","end":"2026-09-18T15:17:15.303485Z","steps":["trace[2139122912] 'agreement among raft nodes before linearized reading' (duration: 100.605807ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:16.427115Z","caller":"traceutil/trace.go:171","msg":"trace[528065725] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"144.584016ms","start":"2026-09-18T15:17:16.282492Z","end":"2026-09-18T15:17:16.427076Z","steps":["trace[528065725] 'process raft request' (duration: 52.613431ms)","trace[528065725] 'compare' (duration: 91.610492ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:17.104118Z","caller":"traceutil/trace.go:171","msg":"trace[119946898] linearizableReadLoop","detail":"{readStateIndex:1580; appliedIndex:1579; }","duration":"175.910879ms","start":"2026-09-18T15:17:16.928171Z","end":"2026-09-18T15:17:17.104082Z","steps":["trace[119946898] 'read index received' (duration: 175.489294ms)","trace[119946898] 'applied index is now lower than readState.Index' (duration: 419.825µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:17.10419Z","caller":"traceutil/trace.go:171","msg":"trace[1726817015] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"180.247488ms","start":"2026-09-18T15:17:16.923904Z","end":"2026-09-18T15:17:17.104152Z","steps":["trace[1726817015] 'process raft request' (duration: 179.901936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:17.104428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.185198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:771"} {"level":"warn","ts":"2026-09-18T15:17:17.104477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.135163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-09-18T15:17:17.104585Z","caller":"traceutil/trace.go:171","msg":"trace[781753125] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1509; }","duration":"176.403986ms","start":"2026-09-18T15:17:16.928146Z","end":"2026-09-18T15:17:17.104549Z","steps":["trace[781753125] 'agreement among raft nodes before linearized reading' (duration: 176.071925ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:17.104596Z","caller":"traceutil/trace.go:171","msg":"trace[1468262763] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1509; }","duration":"105.33573ms","start":"2026-09-18T15:17:16.999237Z","end":"2026-09-18T15:17:17.104572Z","steps":["trace[1468262763] 'agreement among raft nodes before linearized reading' (duration: 105.032799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:18.03683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.357801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:17:18.03704Z","caller":"traceutil/trace.go:171","msg":"trace[759769876] linearizableReadLoop","detail":"{readStateIndex:1584; appliedIndex:1583; }","duration":"165.505186ms","start":"2026-09-18T15:17:17.871517Z","end":"2026-09-18T15:17:18.037022Z","steps":["trace[759769876] 'read index received' (duration: 60.805433ms)","trace[759769876] 'applied index is now lower than readState.Index' (duration: 104.697633ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:18.037168Z","caller":"traceutil/trace.go:171","msg":"trace[1899155528] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"188.004857ms","start":"2026-09-18T15:17:17.849151Z","end":"2026-09-18T15:17:18.037155Z","steps":["trace[1899155528] 'process raft request' (duration: 83.239622ms)","trace[1899155528] 'compare' (duration: 104.207976ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:17:18.037486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.211058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:coredns\" ","response":"range_response_count:1 size:415"} {"level":"info","ts":"2026-09-18T15:17:18.037547Z","caller":"traceutil/trace.go:171","msg":"trace[368362373] range","detail":"{range_begin:/registry/clusterrolebindings/system:coredns; range_end:; response_count:1; response_revision:1512; }","duration":"156.299941ms","start":"2026-09-18T15:17:17.881233Z","end":"2026-09-18T15:17:18.037532Z","steps":["trace[368362373] 'agreement among raft nodes before linearized reading' (duration: 156.151806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:18.037615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.885238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:1 size:694"} {"level":"warn","ts":"2026-09-18T15:17:18.037682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.138847ms","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-09-18T15:17:18.03774Z","caller":"traceutil/trace.go:171","msg":"trace[1840110221] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:1; response_revision:1512; }","duration":"147.029293ms","start":"2026-09-18T15:17:17.890686Z","end":"2026-09-18T15:17:18.037715Z","steps":["trace[1840110221] 'agreement among raft nodes before linearized reading' (duration: 146.759514ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:18.037776Z","caller":"traceutil/trace.go:171","msg":"trace[1844863831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1512; }","duration":"166.301073ms","start":"2026-09-18T15:17:17.871454Z","end":"2026-09-18T15:17:18.037755Z","steps":["trace[1844863831] 'agreement among raft nodes before linearized reading' (duration: 165.985463ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:18.688361Z","caller":"traceutil/trace.go:171","msg":"trace[775050309] linearizableReadLoop","detail":"{readStateIndex:1587; appliedIndex:1586; }","duration":"222.774007ms","start":"2026-09-18T15:17:18.465551Z","end":"2026-09-18T15:17:18.688325Z","steps":["trace[775050309] 'read index received' (duration: 222.476177ms)","trace[775050309] 'applied index is now lower than readState.Index' (duration: 295.89µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:18.688481Z","caller":"traceutil/trace.go:171","msg":"trace[91371687] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"250.828264ms","start":"2026-09-18T15:17:18.437612Z","end":"2026-09-18T15:17:18.68844Z","steps":["trace[91371687] 'process raft request' (duration: 250.492743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:18.688678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.087299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-scheduler\" ","response":"range_response_count:1 size:709"} {"level":"warn","ts":"2026-09-18T15:17:18.689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.514747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:18.689048Z","caller":"traceutil/trace.go:171","msg":"trace[1399879502] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1515; }","duration":"185.649652ms","start":"2026-09-18T15:17:18.503387Z","end":"2026-09-18T15:17:18.689037Z","steps":["trace[1399879502] 'agreement among raft nodes before linearized reading' (duration: 185.544798ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:18.689039Z","caller":"traceutil/trace.go:171","msg":"trace[472168401] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-scheduler; range_end:; response_count:1; response_revision:1515; }","duration":"223.516733ms","start":"2026-09-18T15:17:18.465497Z","end":"2026-09-18T15:17:18.689013Z","steps":["trace[472168401] 'agreement among raft nodes before linearized reading' (duration: 223.027306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:18.688851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.827439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-09-18T15:17:18.689175Z","caller":"traceutil/trace.go:171","msg":"trace[205037170] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:1; response_revision:1515; }","duration":"218.228923ms","start":"2026-09-18T15:17:18.470926Z","end":"2026-09-18T15:17:18.689155Z","steps":["trace[205037170] 'agreement among raft nodes before linearized reading' (duration: 217.726206ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:20.833473Z","caller":"traceutil/trace.go:171","msg":"trace[1730315704] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"135.386887ms","start":"2026-09-18T15:17:20.698058Z","end":"2026-09-18T15:17:20.833445Z","steps":["trace[1730315704] 'process raft request' (duration: 135.153129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:21.920992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.004568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-vmr8s\" ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-09-18T15:17:21.921094Z","caller":"traceutil/trace.go:171","msg":"trace[516667399] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-vmr8s; range_end:; response_count:1; response_revision:1524; }","duration":"147.189434ms","start":"2026-09-18T15:17:21.773881Z","end":"2026-09-18T15:17:21.92107Z","steps":["trace[516667399] 'range keys from in-memory index tree' (duration: 146.754319ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:22.43773Z","caller":"traceutil/trace.go:171","msg":"trace[1643793218] linearizableReadLoop","detail":"{readStateIndex:1597; appliedIndex:1596; }","duration":"275.542263ms","start":"2026-09-18T15:17:22.162161Z","end":"2026-09-18T15:17:22.437703Z","steps":["trace[1643793218] 'read index received' (duration: 275.474821ms)","trace[1643793218] 'applied index is now lower than readState.Index' (duration: 66.352µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:22.437854Z","caller":"traceutil/trace.go:171","msg":"trace[1162625936] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"277.843449ms","start":"2026-09-18T15:17:22.159988Z","end":"2026-09-18T15:17:22.437831Z","steps":["trace[1162625936] 'process raft request' (duration: 277.526109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.437889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.704468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:22.437984Z","caller":"traceutil/trace.go:171","msg":"trace[871236611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1525; }","duration":"275.860123ms","start":"2026-09-18T15:17:22.162112Z","end":"2026-09-18T15:17:22.437972Z","steps":["trace[871236611] 'agreement among raft nodes before linearized reading' (duration: 275.717668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.508759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.229733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:22.508851Z","caller":"traceutil/trace.go:171","msg":"trace[162199079] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:1526; }","duration":"315.385738ms","start":"2026-09-18T15:17:22.193451Z","end":"2026-09-18T15:17:22.508837Z","steps":["trace[162199079] 'agreement among raft nodes before linearized reading' (duration: 315.235393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.50888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:22.193425Z","time spent":"315.44883ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 "} {"level":"warn","ts":"2026-09-18T15:17:22.508937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.072623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-18T15:17:22.509013Z","caller":"traceutil/trace.go:171","msg":"trace[705847456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1526; }","duration":"234.247898ms","start":"2026-09-18T15:17:22.274749Z","end":"2026-09-18T15:17:22.508997Z","steps":["trace[705847456] 'agreement among raft nodes before linearized reading' (duration: 233.925288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.509262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.160642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-09-18T15:17:22.509282Z","caller":"traceutil/trace.go:171","msg":"trace[1321247966] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"193.225485ms","start":"2026-09-18T15:17:22.316047Z","end":"2026-09-18T15:17:22.509273Z","steps":["trace[1321247966] 'process raft request' (duration: 192.47677ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:22.509288Z","caller":"traceutil/trace.go:171","msg":"trace[333303564] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1526; }","duration":"327.187873ms","start":"2026-09-18T15:17:22.182094Z","end":"2026-09-18T15:17:22.509282Z","steps":["trace[333303564] 'agreement among raft nodes before linearized reading' (duration: 327.09811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.509508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:22.182069Z","time spent":"327.38846ms","remote":"127.0.0.1:50212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1488,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-09-18T15:17:22.509678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.392458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3302"} {"level":"info","ts":"2026-09-18T15:17:22.509746Z","caller":"traceutil/trace.go:171","msg":"trace[1752522368] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/; range_end:/registry/rolebindings/envoy-gateway-system0; response_count:3; response_revision:1526; }","duration":"338.626396ms","start":"2026-09-18T15:17:22.171097Z","end":"2026-09-18T15:17:22.509723Z","steps":["trace[1752522368] 'agreement among raft nodes before linearized reading' (duration: 338.217152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.509788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:22.171072Z","time spent":"338.705309ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":3,"response size":3325,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 "} {"level":"info","ts":"2026-09-18T15:17:22.735961Z","caller":"traceutil/trace.go:171","msg":"trace[1975641942] linearizableReadLoop","detail":"{readStateIndex:1599; appliedIndex:1598; }","duration":"220.680014ms","start":"2026-09-18T15:17:22.515217Z","end":"2026-09-18T15:17:22.735897Z","steps":["trace[1975641942] 'read index received' (duration: 220.393545ms)","trace[1975641942] 'applied index is now lower than readState.Index' (duration: 284.979µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:22.736025Z","caller":"traceutil/trace.go:171","msg":"trace[237693018] transaction","detail":"{read_only:false; response_revision:1527; number_of_response:1; }","duration":"221.935915ms","start":"2026-09-18T15:17:22.514064Z","end":"2026-09-18T15:17:22.735999Z","steps":["trace[237693018] 'process raft request' (duration: 221.622306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.736307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.089558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-09-18T15:17:22.736371Z","caller":"traceutil/trace.go:171","msg":"trace[476680958] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1527; }","duration":"221.210352ms","start":"2026-09-18T15:17:22.515144Z","end":"2026-09-18T15:17:22.736354Z","steps":["trace[476680958] 'agreement among raft nodes before linearized reading' (duration: 220.964004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.736464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.029577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T15:17:22.736585Z","caller":"traceutil/trace.go:171","msg":"trace[901475868] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1527; }","duration":"133.225354ms","start":"2026-09-18T15:17:22.603332Z","end":"2026-09-18T15:17:22.736558Z","steps":["trace[901475868] 'agreement among raft nodes before linearized reading' (duration: 132.951815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.736974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.244394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:17:22.737003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.247437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-09-18T15:17:22.737077Z","caller":"traceutil/trace.go:171","msg":"trace[166778083] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1527; }","duration":"131.363752ms","start":"2026-09-18T15:17:22.605694Z","end":"2026-09-18T15:17:22.737058Z","steps":["trace[166778083] 'agreement among raft nodes before linearized reading' (duration: 131.179416ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:22.737101Z","caller":"traceutil/trace.go:171","msg":"trace[2136510241] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:1527; }","duration":"116.38942ms","start":"2026-09-18T15:17:22.620659Z","end":"2026-09-18T15:17:22.737048Z","steps":["trace[2136510241] 'agreement among raft nodes before linearized reading' (duration: 116.197783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.959977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.020995ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-18T15:17:22.960103Z","caller":"traceutil/trace.go:171","msg":"trace[800007697] linearizableReadLoop","detail":"{readStateIndex:1600; appliedIndex:1599; }","duration":"121.631675ms","start":"2026-09-18T15:17:22.838448Z","end":"2026-09-18T15:17:22.96008Z","steps":["trace[800007697] 'read index received' (duration: 14.459615ms)","trace[800007697] 'applied index is now lower than readState.Index' (duration: 107.17057ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:17:22.960244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.917544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:22.96028Z","caller":"traceutil/trace.go:171","msg":"trace[1939416559] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1527; }","duration":"102.017468ms","start":"2026-09-18T15:17:22.858253Z","end":"2026-09-18T15:17:22.96027Z","steps":["trace[1939416559] 'agreement among raft nodes before linearized reading' (duration: 101.937685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.960775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.320408ms","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-09-18T15:17:22.960817Z","caller":"traceutil/trace.go:171","msg":"trace[157203343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1527; }","duration":"122.411171ms","start":"2026-09-18T15:17:22.838395Z","end":"2026-09-18T15:17:22.960807Z","steps":["trace[157203343] 'agreement among raft nodes before linearized reading' (duration: 121.784199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:22.961154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.825035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-09-18T15:17:22.961202Z","caller":"traceutil/trace.go:171","msg":"trace[1710967632] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1527; }","duration":"102.873406ms","start":"2026-09-18T15:17:22.85831Z","end":"2026-09-18T15:17:22.961183Z","steps":["trace[1710967632] 'agreement among raft nodes before linearized reading' (duration: 101.952935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:23.74453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034474376,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:17:24.245504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290034474376,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T15:17:24.609934Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.459632934s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-18T15:17:24.840473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754971231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-09-18T15:17:24.840553Z","caller":"traceutil/trace.go:171","msg":"trace[860464652] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:1528; }","duration":"1.755061724s","start":"2026-09-18T15:17:23.08547Z","end":"2026-09-18T15:17:24.840531Z","steps":["trace[860464652] 'range keys from in-memory index tree' (duration: 1.754764315s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:24.840594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:23.085367Z","time spent":"1.75521619s","remote":"127.0.0.1:50276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":3,"response size":1885,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:500 "} {"level":"warn","ts":"2026-09-18T15:17:24.840822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746228671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"warn","ts":"2026-09-18T15:17:24.840813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751747754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-09-18T15:17:24.840855Z","caller":"traceutil/trace.go:171","msg":"trace[1121322097] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1528; }","duration":"1.751791656s","start":"2026-09-18T15:17:23.089055Z","end":"2026-09-18T15:17:24.840847Z","steps":["trace[1121322097] 'range keys from in-memory index tree' (duration: 1.751640111s)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:24.84085Z","caller":"traceutil/trace.go:171","msg":"trace[1617404361] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:1528; }","duration":"1.746277882s","start":"2026-09-18T15:17:23.094563Z","end":"2026-09-18T15:17:24.840841Z","steps":["trace[1617404361] 'range keys from in-memory index tree' (duration: 1.746127367s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:24.840877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:23.089Z","time spent":"1.751870169s","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":1134,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" "} {"level":"warn","ts":"2026-09-18T15:17:24.840882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:23.094551Z","time spent":"1.746326574s","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":1169,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" "} {"level":"warn","ts":"2026-09-18T15:17:24.84185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.53751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:17:24.842085Z","caller":"traceutil/trace.go:171","msg":"trace[1617808859] linearizableReadLoop","detail":"{readStateIndex:1602; appliedIndex:1601; }","duration":"1.59786767s","start":"2026-09-18T15:17:23.2442Z","end":"2026-09-18T15:17:24.842067Z","steps":["trace[1617808859] 'read index received' (duration: 1.365883875s)","trace[1617808859] 'applied index is now lower than readState.Index' (duration: 231.876201ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:24.842219Z","caller":"traceutil/trace.go:171","msg":"trace[1160022362] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"1.692015962s","start":"2026-09-18T15:17:23.150194Z","end":"2026-09-18T15:17:24.84221Z","steps":["trace[1160022362] 'process raft request' (duration: 1.460053668s)","trace[1160022362] 'compare' (duration: 230.542777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:17:24.842285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:23.150165Z","time spent":"1.692094944s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:17:24.842584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452353216s","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-09-18T15:17:24.842642Z","caller":"traceutil/trace.go:171","msg":"trace[777997525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1529; }","duration":"1.45245812s","start":"2026-09-18T15:17:23.390176Z","end":"2026-09-18T15:17:24.842634Z","steps":["trace[777997525] 'agreement among raft nodes before linearized reading' (duration: 1.452299434s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:24.84266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:23.390104Z","time spent":"1.452550753s","remote":"127.0.0.1:50322","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-09-18T15:17:24.842636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.134229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:17:24.842703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.537982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:17:24.842708Z","caller":"traceutil/trace.go:171","msg":"trace[1122020048] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1529; }","duration":"104.273514ms","start":"2026-09-18T15:17:24.738417Z","end":"2026-09-18T15:17:24.84269Z","steps":["trace[1122020048] 'agreement among raft nodes before linearized reading' (duration: 104.15127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:24.842718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598500922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/\" range_end:\"/registry/serviceaccounts/default0\" limit:500 ","response":"range_response_count:1 size:171"} {"level":"warn","ts":"2026-09-18T15:17:24.84275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084975042s","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-09-18T15:17:24.842766Z","caller":"traceutil/trace.go:171","msg":"trace[1719838060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1529; }","duration":"1.085038244s","start":"2026-09-18T15:17:23.757724Z","end":"2026-09-18T15:17:24.842762Z","steps":["trace[1719838060] 'agreement among raft nodes before linearized reading' (duration: 1.084986152s)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:24.842779Z","caller":"traceutil/trace.go:171","msg":"trace[2000185368] range","detail":"{range_begin:/registry/serviceaccounts/default/; range_end:/registry/serviceaccounts/default0; response_count:1; response_revision:1529; }","duration":"1.598612375s","start":"2026-09-18T15:17:23.24415Z","end":"2026-09-18T15:17:24.842763Z","steps":["trace[2000185368] 'agreement among raft nodes before linearized reading' (duration: 1.598481381s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:24.842791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:23.757699Z","time spent":"1.085089665s","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T15:17:24.842817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:23.244127Z","time spent":"1.598680488s","remote":"127.0.0.1:50276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":194,"request content":"key:\"/registry/serviceaccounts/default/\" range_end:\"/registry/serviceaccounts/default0\" limit:500 "} {"level":"info","ts":"2026-09-18T15:17:24.842737Z","caller":"traceutil/trace.go:171","msg":"trace[698365040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1529; }","duration":"686.633065ms","start":"2026-09-18T15:17:24.156096Z","end":"2026-09-18T15:17:24.84273Z","steps":["trace[698365040] 'agreement among raft nodes before linearized reading' (duration: 686.561513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:24.84282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.351323ms","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-09-18T15:17:24.84285Z","caller":"traceutil/trace.go:171","msg":"trace[336601376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1529; }","duration":"100.406665ms","start":"2026-09-18T15:17:24.742437Z","end":"2026-09-18T15:17:24.842843Z","steps":["trace[336601376] 'agreement among raft nodes before linearized reading' (duration: 100.319622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:24.842843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:24.15607Z","time spent":"686.76995ms","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T15:17:24.842866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.344006ms","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-09-18T15:17:24.842901Z","caller":"traceutil/trace.go:171","msg":"trace[314536384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1529; }","duration":"400.413049ms","start":"2026-09-18T15:17:24.442477Z","end":"2026-09-18T15:17:24.842891Z","steps":["trace[314536384] 'agreement among raft nodes before linearized reading' (duration: 400.276125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:24.843765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:17:24.442458Z","time spent":"401.298629ms","remote":"127.0.0.1:50322","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-09-18T15:17:25.075785Z","caller":"traceutil/trace.go:171","msg":"trace[1059452843] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"227.244158ms","start":"2026-09-18T15:17:24.848508Z","end":"2026-09-18T15:17:25.075752Z","steps":["trace[1059452843] 'process raft request' (duration: 171.196174ms)","trace[1059452843] 'compare' (duration: 55.65531ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:25.075797Z","caller":"traceutil/trace.go:171","msg":"trace[1979577857] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"225.711007ms","start":"2026-09-18T15:17:24.850058Z","end":"2026-09-18T15:17:25.075769Z","steps":["trace[1979577857] 'process raft request' (duration: 225.577853ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:25.075989Z","caller":"traceutil/trace.go:171","msg":"trace[1605742795] linearizableReadLoop","detail":"{readStateIndex:1606; appliedIndex:1603; }","duration":"130.403412ms","start":"2026-09-18T15:17:24.945561Z","end":"2026-09-18T15:17:25.075964Z","steps":["trace[1605742795] 'read index received' (duration: 74.158772ms)","trace[1605742795] 'applied index is now lower than readState.Index' (duration: 56.24347ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:25.076094Z","caller":"traceutil/trace.go:171","msg":"trace[49165059] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"225.81709ms","start":"2026-09-18T15:17:24.85026Z","end":"2026-09-18T15:17:25.076077Z","steps":["trace[49165059] 'process raft request' (duration: 225.466979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:25.076339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.753532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"info","ts":"2026-09-18T15:17:25.076386Z","caller":"traceutil/trace.go:171","msg":"trace[532315127] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:1533; }","duration":"130.839685ms","start":"2026-09-18T15:17:24.945532Z","end":"2026-09-18T15:17:25.076371Z","steps":["trace[532315127] 'agreement among raft nodes before linearized reading' (duration: 130.665959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:25.07645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.784243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/\" range_end:\"/registry/services/specs/envoy-gateway-system0\" limit:500 ","response":"range_response_count:1 size:1914"} {"level":"warn","ts":"2026-09-18T15:17:25.076469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.596073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/\" range_end:\"/registry/services/specs/default0\" limit:500 ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-09-18T15:17:25.076487Z","caller":"traceutil/trace.go:171","msg":"trace[716882968] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/; range_end:/registry/services/specs/envoy-gateway-system0; response_count:1; response_revision:1533; }","duration":"130.844906ms","start":"2026-09-18T15:17:24.945631Z","end":"2026-09-18T15:17:25.076476Z","steps":["trace[716882968] 'agreement among raft nodes before linearized reading' (duration: 130.690391ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:25.076495Z","caller":"traceutil/trace.go:171","msg":"trace[957662695] range","detail":"{range_begin:/registry/services/specs/default/; range_end:/registry/services/specs/default0; response_count:1; response_revision:1533; }","duration":"126.636205ms","start":"2026-09-18T15:17:24.949851Z","end":"2026-09-18T15:17:25.076487Z","steps":["trace[957662695] 'agreement among raft nodes before linearized reading' (duration: 126.578353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:25.076715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.569247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:912"} {"level":"info","ts":"2026-09-18T15:17:25.076754Z","caller":"traceutil/trace.go:171","msg":"trace[2069966211] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1533; }","duration":"127.640419ms","start":"2026-09-18T15:17:24.949104Z","end":"2026-09-18T15:17:25.076744Z","steps":["trace[2069966211] 'agreement among raft nodes before linearized reading' (duration: 127.548516ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:17:29.017975Z","caller":"traceutil/trace.go:171","msg":"trace[337027313] linearizableReadLoop","detail":"{readStateIndex:1618; appliedIndex:1617; }","duration":"119.324052ms","start":"2026-09-18T15:17:28.898633Z","end":"2026-09-18T15:17:29.017957Z","steps":["trace[337027313] 'read index received' (duration: 112.728959ms)","trace[337027313] 'applied index is now lower than readState.Index' (duration: 6.565313ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:17:29.018068Z","caller":"traceutil/trace.go:171","msg":"trace[527540124] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"121.080079ms","start":"2026-09-18T15:17:28.896976Z","end":"2026-09-18T15:17:29.018056Z","steps":["trace[527540124] 'process raft request' (duration: 114.433784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:29.018618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.19137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:89 size:66288"} {"level":"info","ts":"2026-09-18T15:17:29.018692Z","caller":"traceutil/trace.go:171","msg":"trace[1210471415] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:89; response_revision:1544; }","duration":"116.257073ms","start":"2026-09-18T15:17:28.902393Z","end":"2026-09-18T15:17:29.01865Z","steps":["trace[1210471415] 'agreement among raft nodes before linearized reading' (duration: 115.704715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:17:29.018912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.271373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:89 size:66288"} {"level":"info","ts":"2026-09-18T15:17:29.01898Z","caller":"traceutil/trace.go:171","msg":"trace[153469684] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:89; response_revision:1544; }","duration":"120.354516ms","start":"2026-09-18T15:17:28.898616Z","end":"2026-09-18T15:17:29.01897Z","steps":["trace[153469684] 'agreement among raft nodes before linearized reading' (duration: 119.533489ms)"],"step_count":1}