{"level":"warn","ts":"2026-04-20T14:19:03.234626Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:19:03.234759Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.219:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.219:2380","--initial-cluster=instance=https://199.19.213.219:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.219:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.219: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-04-20T14:19:03.23483Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:19:03.234839Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-04-20T14:19:03.234859Z","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-04-20T14:19:03.235495Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"]} {"level":"info","ts":"2026-04-20T14:19:03.235571Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.219: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-04-20T14:19:03.239722Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.893064ms"} {"level":"info","ts":"2026-04-20T14:19:03.245782Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e142186203fa167","cluster-id":"e7586aea7e662ee6"} {"level":"info","ts":"2026-04-20T14:19:03.245865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:19:03.245893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 0"} {"level":"info","ts":"2026-04-20T14:19:03.245915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e142186203fa167 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:19:03.245934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 1"} {"level":"info","ts":"2026-04-20T14:19:03.245987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"warn","ts":"2026-04-20T14:19:03.251903Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:19:03.253279Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:19:03.254814Z","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-04-20T14:19:03.256989Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e142186203fa167","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:19:03.257094Z","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-04-20T14:19:03.257166Z","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-04-20T14:19:03.257179Z","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-04-20T14:19:03.257151Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e142186203fa167","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:19:03.258827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"info","ts":"2026-04-20T14:19:03.2606Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","added-peer-id":"7e142186203fa167","added-peer-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-04-20T14:19:03.261635Z","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-04-20T14:19:03.261785Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-04-20T14:19:03.26182Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-04-20T14:19:03.261841Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e142186203fa167","initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:19:03.261859Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:19:04.147006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:19:04.14706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:19:04.147098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgPreVoteResp from 7e142186203fa167 at term 1"} {"level":"info","ts":"2026-04-20T14:19:04.147113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:19:04.147122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgVoteResp from 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-04-20T14:19:04.147143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became leader at term 2"} {"level":"info","ts":"2026-04-20T14:19:04.147153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e142186203fa167 elected leader 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-04-20T14:19:04.148174Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e142186203fa167","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.219:2379]}","request-path":"/0/members/7e142186203fa167/attributes","cluster-id":"e7586aea7e662ee6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:19:04.148244Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:19:04.148321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:19:04.148383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:19:04.148717Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:19:04.148777Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:19:04.149525Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:19:04.149675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:19:04.149719Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:19:04.150681Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T14:19:04.151801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.219:2379"} {"level":"info","ts":"2026-04-20T14:19:15.72164Z","caller":"traceutil/trace.go:171","msg":"trace[854135152] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"148.689108ms","start":"2026-04-20T14:19:15.57291Z","end":"2026-04-20T14:19:15.721599Z","steps":["trace[854135152] 'read index received' (duration: 148.565606ms)","trace[854135152] 'applied index is now lower than readState.Index' (duration: 122.622µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:15.721757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.829531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-xzx52\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-04-20T14:19:15.721743Z","caller":"traceutil/trace.go:171","msg":"trace[445583151] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"155.737247ms","start":"2026-04-20T14:19:15.56597Z","end":"2026-04-20T14:19:15.721707Z","steps":["trace[445583151] 'process raft request' (duration: 155.523873ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:15.721814Z","caller":"traceutil/trace.go:171","msg":"trace[688024758] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-xzx52; range_end:; response_count:1; response_revision:352; }","duration":"148.914292ms","start":"2026-04-20T14:19:15.572889Z","end":"2026-04-20T14:19:15.721803Z","steps":["trace[688024758] 'agreement among raft nodes before linearized reading' (duration: 148.82306ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:19.184378Z","caller":"traceutil/trace.go:171","msg":"trace[728987350] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"219.812041ms","start":"2026-04-20T14:19:18.964533Z","end":"2026-04-20T14:19:19.184345Z","steps":["trace[728987350] 'read index received' (duration: 188.920447ms)","trace[728987350] 'applied index is now lower than readState.Index' (duration: 30.890294ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:19.184452Z","caller":"traceutil/trace.go:171","msg":"trace[1825020576] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"299.423362ms","start":"2026-04-20T14:19:18.88499Z","end":"2026-04-20T14:19:19.184413Z","steps":["trace[1825020576] 'process raft request' (duration: 268.451326ms)","trace[1825020576] 'compare' (duration: 30.765322ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:19.184571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.018515ms","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-04-20T14:19:19.184766Z","caller":"traceutil/trace.go:171","msg":"trace[607046842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"220.233059ms","start":"2026-04-20T14:19:18.964489Z","end":"2026-04-20T14:19:19.184722Z","steps":["trace[607046842] 'agreement among raft nodes before linearized reading' (duration: 219.984144ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:19.411255Z","caller":"traceutil/trace.go:171","msg":"trace[1184210336] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"221.603379ms","start":"2026-04-20T14:19:19.189629Z","end":"2026-04-20T14:19:19.411232Z","steps":["trace[1184210336] 'process raft request' (duration: 140.814377ms)","trace[1184210336] 'compare' (duration: 80.66741ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:23.436293Z","caller":"traceutil/trace.go:171","msg":"trace[866778909] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"158.219182ms","start":"2026-04-20T14:19:23.278046Z","end":"2026-04-20T14:19:23.436265Z","steps":["trace[866778909] 'process raft request' (duration: 158.008379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.22355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.510267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:19:32.224122Z","caller":"traceutil/trace.go:171","msg":"trace[1989571573] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"352.645425ms","start":"2026-04-20T14:19:31.87135Z","end":"2026-04-20T14:19:32.223996Z","steps":["trace[1989571573] 'process raft request' (duration: 59.615391ms)","trace[1989571573] 'compare' (duration: 290.963707ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:32.224298Z","caller":"traceutil/trace.go:171","msg":"trace[1397187985] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:476; }","duration":"345.627549ms","start":"2026-04-20T14:19:31.878649Z","end":"2026-04-20T14:19:32.224276Z","steps":["trace[1397187985] 'read index received' (duration: 52.355072ms)","trace[1397187985] 'applied index is now lower than readState.Index' (duration: 293.271417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:32.224394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:31.871337Z","time spent":"352.904269ms","remote":"127.0.0.1:33178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:19:32.224615Z","caller":"traceutil/trace.go:171","msg":"trace[699932691] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"353.163994ms","start":"2026-04-20T14:19:31.871438Z","end":"2026-04-20T14:19:32.224602Z","steps":["trace[699932691] 'process raft request' (duration: 352.300638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.224705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:31.871418Z","time spent":"353.239274ms","remote":"127.0.0.1:33440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4656,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:19:32.224954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.413485ms","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-04-20T14:19:32.224988Z","caller":"traceutil/trace.go:171","msg":"trace[1859641052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"263.481307ms","start":"2026-04-20T14:19:31.961495Z","end":"2026-04-20T14:19:32.224977Z","steps":["trace[1859641052] 'agreement among raft nodes before linearized reading' (duration: 263.409715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.224986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.318541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-04-20T14:19:32.225203Z","caller":"traceutil/trace.go:171","msg":"trace[875153439] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:461; }","duration":"346.493715ms","start":"2026-04-20T14:19:31.878626Z","end":"2026-04-20T14:19:32.22512Z","steps":["trace[875153439] 'agreement among raft nodes before linearized reading' (duration: 346.084708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.225331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:31.878616Z","time spent":"346.678419ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14486,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-04-20T14:19:32.225697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.557005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-20T14:19:32.225747Z","caller":"traceutil/trace.go:171","msg":"trace[655147828] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"322.753652ms","start":"2026-04-20T14:19:31.902976Z","end":"2026-04-20T14:19:32.225729Z","steps":["trace[655147828] 'process raft request' (duration: 321.023122ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.225786Z","caller":"traceutil/trace.go:171","msg":"trace[79199684] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:461; }","duration":"228.681756ms","start":"2026-04-20T14:19:31.997083Z","end":"2026-04-20T14:19:32.225764Z","steps":["trace[79199684] 'agreement among raft nodes before linearized reading' (duration: 228.472553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.225912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:31.902741Z","time spent":"323.086167ms","remote":"127.0.0.1:33090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:19:32.226033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.980063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-20T14:19:32.226074Z","caller":"traceutil/trace.go:171","msg":"trace[1289894202] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:461; }","duration":"229.080624ms","start":"2026-04-20T14:19:31.996981Z","end":"2026-04-20T14:19:32.226061Z","steps":["trace[1289894202] 'agreement among raft nodes before linearized reading' (duration: 229.019903ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:33.773982Z","caller":"traceutil/trace.go:171","msg":"trace[1706781667] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"272.006915ms","start":"2026-04-20T14:19:33.501949Z","end":"2026-04-20T14:19:33.773956Z","steps":["trace[1706781667] 'read index received' (duration: 271.827821ms)","trace[1706781667] 'applied index is now lower than readState.Index' (duration: 178.214µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:33.774124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.152767ms","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-04-20T14:19:33.77407Z","caller":"traceutil/trace.go:171","msg":"trace[1503937854] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"291.039419ms","start":"2026-04-20T14:19:33.483004Z","end":"2026-04-20T14:19:33.774043Z","steps":["trace[1503937854] 'process raft request' (duration: 290.826966ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:33.774157Z","caller":"traceutil/trace.go:171","msg":"trace[650894653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:466; }","duration":"272.232029ms","start":"2026-04-20T14:19:33.501915Z","end":"2026-04-20T14:19:33.774147Z","steps":["trace[650894653] 'agreement among raft nodes before linearized reading' (duration: 272.152157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:33.774402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.426573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:33.774553Z","caller":"traceutil/trace.go:171","msg":"trace[187590163] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:466; }","duration":"205.651058ms","start":"2026-04-20T14:19:33.568875Z","end":"2026-04-20T14:19:33.774526Z","steps":["trace[187590163] 'agreement among raft nodes before linearized reading' (duration: 205.399363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:34.293883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585156632,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:19:34.385809Z","caller":"traceutil/trace.go:171","msg":"trace[479871810] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"592.973081ms","start":"2026-04-20T14:19:33.792805Z","end":"2026-04-20T14:19:34.385778Z","steps":["trace[479871810] 'read index received' (duration: 578.693261ms)","trace[479871810] 'applied index is now lower than readState.Index' (duration: 14.27854ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:34.385964Z","caller":"traceutil/trace.go:171","msg":"trace[1045109305] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"608.482124ms","start":"2026-04-20T14:19:33.777466Z","end":"2026-04-20T14:19:34.385948Z","steps":["trace[1045109305] 'process raft request' (duration: 594.114052ms)","trace[1045109305] 'compare' (duration: 14.073356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:34.386118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:33.777449Z","time spent":"608.582775ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:19:34.386165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.431102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:19:34.386218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.537022ms","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-04-20T14:19:34.386255Z","caller":"traceutil/trace.go:171","msg":"trace[697796726] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:467; }","duration":"110.556533ms","start":"2026-04-20T14:19:34.275679Z","end":"2026-04-20T14:19:34.386235Z","steps":["trace[697796726] 'agreement among raft nodes before linearized reading' (duration: 110.413051ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:34.386283Z","caller":"traceutil/trace.go:171","msg":"trace[1147411068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"142.650364ms","start":"2026-04-20T14:19:34.243613Z","end":"2026-04-20T14:19:34.386263Z","steps":["trace[1147411068] 'agreement among raft nodes before linearized reading' (duration: 142.40414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:34.386356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.53569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:19:34.386461Z","caller":"traceutil/trace.go:171","msg":"trace[706148327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"593.661413ms","start":"2026-04-20T14:19:33.79278Z","end":"2026-04-20T14:19:34.386442Z","steps":["trace[706148327] 'agreement among raft nodes before linearized reading' (duration: 593.287046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:34.38661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:33.792771Z","time spent":"593.792795ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:19:40.916955Z","caller":"traceutil/trace.go:171","msg":"trace[2048323959] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"125.956588ms","start":"2026-04-20T14:19:40.790975Z","end":"2026-04-20T14:19:40.916932Z","steps":["trace[2048323959] 'read index received' (duration: 125.178345ms)","trace[2048323959] 'applied index is now lower than readState.Index' (duration: 777.403µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:40.917008Z","caller":"traceutil/trace.go:171","msg":"trace[29224522] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"220.650889ms","start":"2026-04-20T14:19:40.696335Z","end":"2026-04-20T14:19:40.916986Z","steps":["trace[29224522] 'process raft request' (duration: 220.092149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:40.917097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.100671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:40.917169Z","caller":"traceutil/trace.go:171","msg":"trace[1014112223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"126.221522ms","start":"2026-04-20T14:19:40.790924Z","end":"2026-04-20T14:19:40.917146Z","steps":["trace[1014112223] 'agreement among raft nodes before linearized reading' (duration: 126.09529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:41.547822Z","caller":"traceutil/trace.go:171","msg":"trace[917972522] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"578.691318ms","start":"2026-04-20T14:19:40.96911Z","end":"2026-04-20T14:19:41.547802Z","steps":["trace[917972522] 'process raft request' (duration: 512.280382ms)","trace[917972522] 'compare' (duration: 66.318425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:41.547932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:40.969092Z","time spent":"578.78861ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:19:42.916736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585156819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:19:43.417467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585156819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:19:43.675325Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.712885513s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T14:19:43.686952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.898615881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:43.687003Z","caller":"traceutil/trace.go:171","msg":"trace[1776136512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"1.898714903s","start":"2026-04-20T14:19:41.788275Z","end":"2026-04-20T14:19:43.68699Z","steps":["trace[1776136512] 'range keys from in-memory index tree' (duration: 1.898509339s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:43.68703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:41.788245Z","time spent":"1.898779704s","remote":"127.0.0.1:33008","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-04-20T14:19:43.687367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.873108593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T14:19:43.687396Z","caller":"traceutil/trace.go:171","msg":"trace[566426053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"1.873162564s","start":"2026-04-20T14:19:41.814225Z","end":"2026-04-20T14:19:43.687388Z","steps":["trace[566426053] 'range keys from in-memory index tree' (duration: 1.872985311s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:43.687414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:41.814209Z","time spent":"1.873199125s","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:19:43.687647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.866109ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:43.687699Z","caller":"traceutil/trace.go:171","msg":"trace[210753409] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:579; }","duration":"357.92124ms","start":"2026-04-20T14:19:43.329771Z","end":"2026-04-20T14:19:43.687692Z","steps":["trace[210753409] 'range keys from in-memory index tree' (duration: 357.857739ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:43.688519Z","caller":"traceutil/trace.go:171","msg":"trace[1714346384] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.727242182s","start":"2026-04-20T14:19:41.961254Z","end":"2026-04-20T14:19:43.688496Z","steps":["trace[1714346384] 'process raft request' (duration: 1.714979668s)","trace[1714346384] 'compare' (duration: 11.417029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:43.688598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:41.961233Z","time spent":"1.727319433s","remote":"127.0.0.1:33090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:19:43.703047Z","caller":"traceutil/trace.go:171","msg":"trace[445404906] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"1.287006668s","start":"2026-04-20T14:19:42.416008Z","end":"2026-04-20T14:19:43.703015Z","steps":["trace[445404906] 'read index received' (duration: 1.259575352s)","trace[445404906] 'applied index is now lower than readState.Index' (duration: 27.429386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:43.703331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266431754s","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-04-20T14:19:43.703359Z","caller":"traceutil/trace.go:171","msg":"trace[1550071509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"1.266503305s","start":"2026-04-20T14:19:42.436848Z","end":"2026-04-20T14:19:43.703352Z","steps":["trace[1550071509] 'agreement among raft nodes before linearized reading' (duration: 1.266391763s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:43.703377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:42.436831Z","time spent":"1.266541356s","remote":"127.0.0.1:33258","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-04-20T14:19:43.703551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287551977s","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-04-20T14:19:43.70357Z","caller":"traceutil/trace.go:171","msg":"trace[1246085586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.287605448s","start":"2026-04-20T14:19:42.41596Z","end":"2026-04-20T14:19:43.703566Z","steps":["trace[1246085586] 'agreement among raft nodes before linearized reading' (duration: 1.28716631s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:43.703583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:42.41594Z","time spent":"1.287639548s","remote":"127.0.0.1:33258","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-04-20T14:19:43.703816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22253158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:19:43.703835Z","caller":"traceutil/trace.go:171","msg":"trace[943080325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.222584771s","start":"2026-04-20T14:19:42.481246Z","end":"2026-04-20T14:19:43.703831Z","steps":["trace[943080325] 'agreement among raft nodes before linearized reading' (duration: 1.22253947s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:43.704428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:42.481226Z","time spent":"1.223194701s","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:19:45.244307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585156899,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:19:45.353721Z","caller":"traceutil/trace.go:171","msg":"trace[1969728178] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"609.970275ms","start":"2026-04-20T14:19:44.743731Z","end":"2026-04-20T14:19:45.353701Z","steps":["trace[1969728178] 'read index received' (duration: 609.757972ms)","trace[1969728178] 'applied index is now lower than readState.Index' (duration: 211.493µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:45.353798Z","caller":"traceutil/trace.go:171","msg":"trace[1093197303] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"610.216729ms","start":"2026-04-20T14:19:44.743529Z","end":"2026-04-20T14:19:45.353746Z","steps":["trace[1093197303] 'process raft request' (duration: 610.037836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:45.353951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.337974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:19:45.353989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.24578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:45.354022Z","caller":"traceutil/trace.go:171","msg":"trace[256079536] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:594; }","duration":"610.314471ms","start":"2026-04-20T14:19:44.743698Z","end":"2026-04-20T14:19:45.354012Z","steps":["trace[256079536] 'agreement among raft nodes before linearized reading' (duration: 610.115287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:45.354053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.743681Z","time spent":"610.364522ms","remote":"127.0.0.1:57572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-20T14:19:45.354113Z","caller":"traceutil/trace.go:171","msg":"trace[80461625] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"589.520277ms","start":"2026-04-20T14:19:44.764569Z","end":"2026-04-20T14:19:45.35409Z","steps":["trace[80461625] 'agreement among raft nodes before linearized reading' (duration: 589.328993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:45.354161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.148215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:45.354235Z","caller":"traceutil/trace.go:171","msg":"trace[243727282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"562.235016ms","start":"2026-04-20T14:19:44.791983Z","end":"2026-04-20T14:19:45.354218Z","steps":["trace[243727282] 'agreement among raft nodes before linearized reading' (duration: 562.116124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:45.35424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.600868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:19:45.354254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.850805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:45.354295Z","caller":"traceutil/trace.go:171","msg":"trace[1476860015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"589.658819ms","start":"2026-04-20T14:19:44.764621Z","end":"2026-04-20T14:19:45.35428Z","steps":["trace[1476860015] 'agreement among raft nodes before linearized reading' (duration: 589.591158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:45.354348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.764618Z","time spent":"589.71621ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:19:45.354368Z","caller":"traceutil/trace.go:171","msg":"trace[600551774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"574.924046ms","start":"2026-04-20T14:19:44.779396Z","end":"2026-04-20T14:19:45.35432Z","steps":["trace[600551774] 'agreement among raft nodes before linearized reading' (duration: 574.835574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:45.35419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.764558Z","time spent":"589.619748ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T14:19:45.354478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.779368Z","time spent":"575.072688ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T14:19:45.354288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.791959Z","time spent":"562.317637ms","remote":"127.0.0.1:33008","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-04-20T14:19:45.353943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.743518Z","time spent":"610.354471ms","remote":"127.0.0.1:33080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:19:45.354172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.771677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:45.354861Z","caller":"traceutil/trace.go:171","msg":"trace[2070993910] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"574.469548ms","start":"2026-04-20T14:19:44.780374Z","end":"2026-04-20T14:19:45.354844Z","steps":["trace[2070993910] 'agreement among raft nodes before linearized reading' (duration: 573.753927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:45.35522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.780369Z","time spent":"574.831184ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-20T14:19:45.354964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.384428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:45.355939Z","caller":"traceutil/trace.go:171","msg":"trace[1394635537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"576.227838ms","start":"2026-04-20T14:19:44.779684Z","end":"2026-04-20T14:19:45.355912Z","steps":["trace[1394635537] 'agreement among raft nodes before linearized reading' (duration: 574.331057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:45.355993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.779662Z","time spent":"576.307818ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:19:48.980814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.056875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-20T14:19:48.980901Z","caller":"traceutil/trace.go:171","msg":"trace[322037289] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"133.189006ms","start":"2026-04-20T14:19:48.847699Z","end":"2026-04-20T14:19:48.980888Z","steps":["trace[322037289] 'range keys from in-memory index tree' (duration: 132.918873ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:57.927695Z","caller":"traceutil/trace.go:171","msg":"trace[926174133] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"168.466222ms","start":"2026-04-20T14:19:57.759207Z","end":"2026-04-20T14:19:57.927673Z","steps":["trace[926174133] 'read index received' (duration: 168.302019ms)","trace[926174133] 'applied index is now lower than readState.Index' (duration: 162.963µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:57.927751Z","caller":"traceutil/trace.go:171","msg":"trace[1842919164] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"224.663394ms","start":"2026-04-20T14:19:57.703054Z","end":"2026-04-20T14:19:57.927718Z","steps":["trace[1842919164] 'process raft request' (duration: 224.481451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:57.927978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.06294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T14:19:57.928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.073552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:19:57.928086Z","caller":"traceutil/trace.go:171","msg":"trace[657506195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"160.199304ms","start":"2026-04-20T14:19:57.767867Z","end":"2026-04-20T14:19:57.928066Z","steps":["trace[657506195] 'agreement among raft nodes before linearized reading' (duration: 159.96402ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:57.928033Z","caller":"traceutil/trace.go:171","msg":"trace[2123318203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:729; }","duration":"147.142491ms","start":"2026-04-20T14:19:57.780868Z","end":"2026-04-20T14:19:57.928011Z","steps":["trace[2123318203] 'agreement among raft nodes before linearized reading' (duration: 146.994239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:57.928242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.022635ms","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-04-20T14:19:57.92835Z","caller":"traceutil/trace.go:171","msg":"trace[1597035407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"148.174197ms","start":"2026-04-20T14:19:57.780151Z","end":"2026-04-20T14:19:57.928326Z","steps":["trace[1597035407] 'agreement among raft nodes before linearized reading' (duration: 147.878462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:57.928488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.274364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T14:19:57.928562Z","caller":"traceutil/trace.go:171","msg":"trace[1461595313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"169.381376ms","start":"2026-04-20T14:19:57.759161Z","end":"2026-04-20T14:19:57.928542Z","steps":["trace[1461595313] 'agreement among raft nodes before linearized reading' (duration: 168.645595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:01.506382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.16607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r2m84\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-20T14:20:01.506463Z","caller":"traceutil/trace.go:171","msg":"trace[1149142869] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r2m84; range_end:; response_count:1; response_revision:747; }","duration":"754.280532ms","start":"2026-04-20T14:20:00.752162Z","end":"2026-04-20T14:20:01.506443Z","steps":["trace[1149142869] 'range keys from in-memory index tree' (duration: 753.997828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:01.506505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:00.752149Z","time spent":"754.346643ms","remote":"127.0.0.1:33178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r2m84\" "} {"level":"info","ts":"2026-04-20T14:20:15.95788Z","caller":"traceutil/trace.go:171","msg":"trace[823931605] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"127.849339ms","start":"2026-04-20T14:20:15.830011Z","end":"2026-04-20T14:20:15.95786Z","steps":["trace[823931605] 'process raft request' (duration: 127.434723ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:16.165602Z","caller":"traceutil/trace.go:171","msg":"trace[1088908973] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"126.089386ms","start":"2026-04-20T14:20:16.039488Z","end":"2026-04-20T14:20:16.165577Z","steps":["trace[1088908973] 'process raft request' (duration: 37.427485ms)","trace[1088908973] 'compare' (duration: 88.539999ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:37.266604Z","caller":"traceutil/trace.go:171","msg":"trace[181270965] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"126.023286ms","start":"2026-04-20T14:20:37.140552Z","end":"2026-04-20T14:20:37.266575Z","steps":["trace[181270965] 'process raft request' (duration: 70.289471ms)","trace[181270965] 'compare' (duration: 55.599904ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:37.326126Z","caller":"traceutil/trace.go:171","msg":"trace[1114065004] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"113.833243ms","start":"2026-04-20T14:20:37.212251Z","end":"2026-04-20T14:20:37.326084Z","steps":["trace[1114065004] 'process raft request' (duration: 113.523679ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:38.339382Z","caller":"traceutil/trace.go:171","msg":"trace[936933766] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"173.866311ms","start":"2026-04-20T14:20:38.165493Z","end":"2026-04-20T14:20:38.33936Z","steps":["trace[936933766] 'read index received' (duration: 173.533276ms)","trace[936933766] 'applied index is now lower than readState.Index' (duration: 332.135µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:38.33948Z","caller":"traceutil/trace.go:171","msg":"trace[2116446816] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"175.235219ms","start":"2026-04-20T14:20:38.164209Z","end":"2026-04-20T14:20:38.339445Z","steps":["trace[2116446816] 'process raft request' (duration: 174.994545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:38.339567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.055113ms","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-04-20T14:20:38.339597Z","caller":"traceutil/trace.go:171","msg":"trace[378123144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"174.126803ms","start":"2026-04-20T14:20:38.165461Z","end":"2026-04-20T14:20:38.339588Z","steps":["trace[378123144] 'agreement among raft nodes before linearized reading' (duration: 173.993202ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:38.808837Z","caller":"traceutil/trace.go:171","msg":"trace[1577172384] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"643.08893ms","start":"2026-04-20T14:20:38.165719Z","end":"2026-04-20T14:20:38.808808Z","steps":["trace[1577172384] 'process raft request' (duration: 577.189211ms)","trace[1577172384] 'compare' (duration: 65.567245ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:38.808862Z","caller":"traceutil/trace.go:171","msg":"trace[2047034101] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"469.405723ms","start":"2026-04-20T14:20:38.339433Z","end":"2026-04-20T14:20:38.808839Z","steps":["trace[2047034101] 'read index received' (duration: 403.429332ms)","trace[2047034101] 'applied index is now lower than readState.Index' (duration: 65.975521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:20:38.808971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:38.165704Z","time spent":"643.198753ms","remote":"127.0.0.1:33258","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-04-20T14:20:38.808992Z","caller":"traceutil/trace.go:171","msg":"trace[1632190929] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"460.754408ms","start":"2026-04-20T14:20:38.348219Z","end":"2026-04-20T14:20:38.808974Z","steps":["trace[1632190929] 'process raft request' (duration: 460.586966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:38.80908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.692998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T14:20:38.809096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:38.348201Z","time spent":"460.838939ms","remote":"127.0.0.1:33258","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-04-20T14:20:38.809151Z","caller":"traceutil/trace.go:171","msg":"trace[316810801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"547.79967ms","start":"2026-04-20T14:20:38.261336Z","end":"2026-04-20T14:20:38.809136Z","steps":["trace[316810801] 'agreement among raft nodes before linearized reading' (duration: 547.539516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:38.809178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:38.261322Z","time spent":"547.850021ms","remote":"127.0.0.1:33258","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-04-20T14:20:38.809252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.341811ms","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-04-20T14:20:38.809344Z","caller":"traceutil/trace.go:171","msg":"trace[1568138765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"636.457613ms","start":"2026-04-20T14:20:38.172862Z","end":"2026-04-20T14:20:38.80932Z","steps":["trace[1568138765] 'agreement among raft nodes before linearized reading' (duration: 636.102537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:38.809394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:38.172855Z","time spent":"636.527513ms","remote":"127.0.0.1:33258","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-04-20T14:20:39.052783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.710175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:20:39.052933Z","caller":"traceutil/trace.go:171","msg":"trace[1482541637] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"235.993718ms","start":"2026-04-20T14:20:38.816902Z","end":"2026-04-20T14:20:39.052896Z","steps":["trace[1482541637] 'process raft request' (duration: 122.082779ms)","trace[1482541637] 'compare' (duration: 113.590134ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:40.455915Z","caller":"traceutil/trace.go:171","msg":"trace[1414493331] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"108.47385ms","start":"2026-04-20T14:20:40.347422Z","end":"2026-04-20T14:20:40.455896Z","steps":["trace[1414493331] 'process raft request' (duration: 108.332418ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:42.650255Z","caller":"traceutil/trace.go:171","msg":"trace[1531885221] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"190.222211ms","start":"2026-04-20T14:20:42.459994Z","end":"2026-04-20T14:20:42.650217Z","steps":["trace[1531885221] 'read index received' (duration: 110.906485ms)","trace[1531885221] 'applied index is now lower than readState.Index' (duration: 79.314536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:20:42.650465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.487185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T14:20:42.650531Z","caller":"traceutil/trace.go:171","msg":"trace[769050066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"190.594807ms","start":"2026-04-20T14:20:42.459921Z","end":"2026-04-20T14:20:42.650516Z","steps":["trace[769050066] 'agreement among raft nodes before linearized reading' (duration: 190.401654ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:42.978689Z","caller":"traceutil/trace.go:171","msg":"trace[356016908] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"111.37524ms","start":"2026-04-20T14:20:42.867293Z","end":"2026-04-20T14:20:42.978668Z","steps":["trace[356016908] 'read index received' (duration: 23.40548ms)","trace[356016908] 'applied index is now lower than readState.Index' (duration: 87.96896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:20:42.978867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.572413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:20:42.978897Z","caller":"traceutil/trace.go:171","msg":"trace[2049184774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"111.664915ms","start":"2026-04-20T14:20:42.867224Z","end":"2026-04-20T14:20:42.978889Z","steps":["trace[2049184774] 'agreement among raft nodes before linearized reading' (duration: 111.524512ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:42.978888Z","caller":"traceutil/trace.go:171","msg":"trace[853569765] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"150.74043ms","start":"2026-04-20T14:20:42.828093Z","end":"2026-04-20T14:20:42.978834Z","steps":["trace[853569765] 'process raft request' (duration: 150.154512ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:44.874913Z","caller":"traceutil/trace.go:171","msg":"trace[949464108] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"122.549199ms","start":"2026-04-20T14:20:44.752347Z","end":"2026-04-20T14:20:44.874896Z","steps":["trace[949464108] 'read index received' (duration: 122.362137ms)","trace[949464108] 'applied index is now lower than readState.Index' (duration: 186.262µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:44.87511Z","caller":"traceutil/trace.go:171","msg":"trace[1357535959] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"132.973187ms","start":"2026-04-20T14:20:44.742127Z","end":"2026-04-20T14:20:44.875101Z","steps":["trace[1357535959] 'process raft request' (duration: 132.629443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:44.875364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.008026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-04-20T14:20:44.875397Z","caller":"traceutil/trace.go:171","msg":"trace[353423875] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1019; }","duration":"123.078706ms","start":"2026-04-20T14:20:44.75231Z","end":"2026-04-20T14:20:44.875388Z","steps":["trace[353423875] 'agreement among raft nodes before linearized reading' (duration: 123.008925ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:07.223239Z","caller":"traceutil/trace.go:171","msg":"trace[777701516] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"215.039821ms","start":"2026-04-20T14:21:07.008175Z","end":"2026-04-20T14:21:07.223215Z","steps":["trace[777701516] 'process raft request' (duration: 214.688157ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:07.223678Z","caller":"traceutil/trace.go:171","msg":"trace[1987331273] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"207.387685ms","start":"2026-04-20T14:21:07.015764Z","end":"2026-04-20T14:21:07.223151Z","steps":["trace[1987331273] 'read index received' (duration: 207.008391ms)","trace[1987331273] 'applied index is now lower than readState.Index' (duration: 378.034µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:07.223698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.889152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:21:07.22408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.418237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:21:07.224381Z","caller":"traceutil/trace.go:171","msg":"trace[1952229930] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:1091; }","duration":"106.799922ms","start":"2026-04-20T14:21:07.117567Z","end":"2026-04-20T14:21:07.224367Z","steps":["trace[1952229930] 'agreement among raft nodes before linearized reading' (duration: 106.304175ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:07.224327Z","caller":"traceutil/trace.go:171","msg":"trace[765576821] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1091; }","duration":"208.555011ms","start":"2026-04-20T14:21:07.015745Z","end":"2026-04-20T14:21:07.2243Z","steps":["trace[765576821] 'agreement among raft nodes before linearized reading' (duration: 207.830712ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:09.451309Z","caller":"traceutil/trace.go:171","msg":"trace[1497845785] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"196.605292ms","start":"2026-04-20T14:21:09.25468Z","end":"2026-04-20T14:21:09.451286Z","steps":["trace[1497845785] 'process raft request' (duration: 196.552412ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:09.451379Z","caller":"traceutil/trace.go:171","msg":"trace[663124245] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"196.853365ms","start":"2026-04-20T14:21:09.254483Z","end":"2026-04-20T14:21:09.451336Z","steps":["trace[663124245] 'process raft request' (duration: 192.026395ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:09.451594Z","caller":"traceutil/trace.go:171","msg":"trace[1818206310] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1143; }","duration":"194.416436ms","start":"2026-04-20T14:21:09.257157Z","end":"2026-04-20T14:21:09.451574Z","steps":["trace[1818206310] 'read index received' (duration: 51.734213ms)","trace[1818206310] 'applied index is now lower than readState.Index' (duration: 142.680613ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:09.451657Z","caller":"traceutil/trace.go:171","msg":"trace[99509928] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"192.996847ms","start":"2026-04-20T14:21:09.258627Z","end":"2026-04-20T14:21:09.451624Z","steps":["trace[99509928] 'process raft request' (duration: 192.632793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:09.451702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.530977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:21:09.451727Z","caller":"traceutil/trace.go:171","msg":"trace[1214292617] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1103; }","duration":"194.600047ms","start":"2026-04-20T14:21:09.257119Z","end":"2026-04-20T14:21:09.451719Z","steps":["trace[1214292617] 'agreement among raft nodes before linearized reading' (duration: 194.512466ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:13.621404Z","caller":"traceutil/trace.go:171","msg":"trace[1139809916] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"154.545736ms","start":"2026-04-20T14:21:13.466825Z","end":"2026-04-20T14:21:13.621371Z","steps":["trace[1139809916] 'process raft request' (duration: 154.352464ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:13.627865Z","caller":"traceutil/trace.go:171","msg":"trace[499119603] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1158; }","duration":"135.925527ms","start":"2026-04-20T14:21:13.49192Z","end":"2026-04-20T14:21:13.627845Z","steps":["trace[499119603] 'read index received' (duration: 129.416906ms)","trace[499119603] 'applied index is now lower than readState.Index' (duration: 6.507901ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:13.627984Z","caller":"traceutil/trace.go:171","msg":"trace[1818837365] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"159.000821ms","start":"2026-04-20T14:21:13.468934Z","end":"2026-04-20T14:21:13.627935Z","steps":["trace[1818837365] 'process raft request' (duration: 158.786018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:13.62798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.047439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:13.628134Z","caller":"traceutil/trace.go:171","msg":"trace[1493840684] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1116; }","duration":"136.244571ms","start":"2026-04-20T14:21:13.491879Z","end":"2026-04-20T14:21:13.628124Z","steps":["trace[1493840684] 'agreement among raft nodes before linearized reading' (duration: 136.043729ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:13.628Z","caller":"traceutil/trace.go:171","msg":"trace[768099593] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"158.798019ms","start":"2026-04-20T14:21:13.46918Z","end":"2026-04-20T14:21:13.627978Z","steps":["trace[768099593] 'process raft request' (duration: 158.633597ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:29.162679Z","caller":"traceutil/trace.go:171","msg":"trace[583764957] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"373.558357ms","start":"2026-04-20T14:21:28.789102Z","end":"2026-04-20T14:21:29.162661Z","steps":["trace[583764957] 'read index received' (duration: 373.365595ms)","trace[583764957] 'applied index is now lower than readState.Index' (duration: 191.812µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:29.162798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.676939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:29.162816Z","caller":"traceutil/trace.go:171","msg":"trace[463449944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"373.738669ms","start":"2026-04-20T14:21:28.789071Z","end":"2026-04-20T14:21:29.16281Z","steps":["trace[463449944] 'agreement among raft nodes before linearized reading' (duration: 373.667398ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:29.162794Z","caller":"traceutil/trace.go:171","msg":"trace[961194251] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"755.58927ms","start":"2026-04-20T14:21:28.407177Z","end":"2026-04-20T14:21:29.162766Z","steps":["trace[961194251] 'process raft request' (duration: 755.365316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:29.162839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:28.789055Z","time spent":"373.778739ms","remote":"127.0.0.1:33008","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-04-20T14:21:29.162942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:28.407158Z","time spent":"755.703731ms","remote":"127.0.0.1:33258","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-04-20T14:21:31.336178Z","caller":"traceutil/trace.go:171","msg":"trace[39466500] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"166.65773ms","start":"2026-04-20T14:21:31.169496Z","end":"2026-04-20T14:21:31.336153Z","steps":["trace[39466500] 'process raft request' (duration: 166.537858ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:31.867736Z","caller":"traceutil/trace.go:171","msg":"trace[605785690] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"151.076221ms","start":"2026-04-20T14:21:31.716629Z","end":"2026-04-20T14:21:31.867705Z","steps":["trace[605785690] 'process raft request' (duration: 70.06152ms)","trace[605785690] 'compare' (duration: 80.818559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:32.640955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.121453ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T14:21:33.464272Z","caller":"traceutil/trace.go:171","msg":"trace[2072696051] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"122.418255ms","start":"2026-04-20T14:21:33.341831Z","end":"2026-04-20T14:21:33.464249Z","steps":["trace[2072696051] 'process raft request' (duration: 122.227113ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:33.649692Z","caller":"traceutil/trace.go:171","msg":"trace[2055632357] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"110.797766ms","start":"2026-04-20T14:21:33.53886Z","end":"2026-04-20T14:21:33.649657Z","steps":["trace[2055632357] 'process raft request' (duration: 109.914335ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:33.754765Z","caller":"traceutil/trace.go:171","msg":"trace[544866915] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"100.548164ms","start":"2026-04-20T14:21:33.654198Z","end":"2026-04-20T14:21:33.754746Z","steps":["trace[544866915] 'process raft request' (duration: 95.26131ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:34.075087Z","caller":"traceutil/trace.go:171","msg":"trace[1672943129] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"145.908444ms","start":"2026-04-20T14:21:33.929124Z","end":"2026-04-20T14:21:34.075033Z","steps":["trace[1672943129] 'process raft request' (duration: 116.568153ms)","trace[1672943129] 'compare' (duration: 28.344279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:52.109254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585158430,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:21:52.295335Z","caller":"traceutil/trace.go:171","msg":"trace[1274056603] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"707.382885ms","start":"2026-04-20T14:21:51.587923Z","end":"2026-04-20T14:21:52.295306Z","steps":["trace[1274056603] 'process raft request' (duration: 707.207613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:52.295524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:51.587897Z","time spent":"707.531876ms","remote":"127.0.0.1:33258","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-04-20T14:21:52.32988Z","caller":"traceutil/trace.go:171","msg":"trace[1032613443] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1320; }","duration":"721.522139ms","start":"2026-04-20T14:21:51.608269Z","end":"2026-04-20T14:21:52.329791Z","steps":["trace[1032613443] 'read index received' (duration: 686.985005ms)","trace[1032613443] 'applied index is now lower than readState.Index' (duration: 34.536304ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:52.329902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.626381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:21:52.330386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.802753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:21:52.330459Z","caller":"traceutil/trace.go:171","msg":"trace[722144196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"465.899265ms","start":"2026-04-20T14:21:51.864537Z","end":"2026-04-20T14:21:52.330437Z","steps":["trace[722144196] 'agreement among raft nodes before linearized reading' (duration: 465.729703ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:52.330092Z","caller":"traceutil/trace.go:171","msg":"trace[1401056038] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"439.555776ms","start":"2026-04-20T14:21:51.890508Z","end":"2026-04-20T14:21:52.330064Z","steps":["trace[1401056038] 'process raft request' (duration: 436.999226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:52.330199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.801933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T14:21:52.330628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:51.890483Z","time spent":"440.084822ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:52.33069Z","caller":"traceutil/trace.go:171","msg":"trace[1747217013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1269; }","duration":"582.325349ms","start":"2026-04-20T14:21:51.748344Z","end":"2026-04-20T14:21:52.330669Z","steps":["trace[1747217013] 'agreement among raft nodes before linearized reading' (duration: 581.760432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:52.330778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:51.748323Z","time spent":"582.43351ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:21:52.330296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.168116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:21:52.330278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.728327ms","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-04-20T14:21:52.330995Z","caller":"traceutil/trace.go:171","msg":"trace[1873519741] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1269; }","duration":"558.824364ms","start":"2026-04-20T14:21:51.772116Z","end":"2026-04-20T14:21:52.33094Z","steps":["trace[1873519741] 'agreement among raft nodes before linearized reading' (duration: 558.144936ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:52.331065Z","caller":"traceutil/trace.go:171","msg":"trace[837264521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1269; }","duration":"123.547636ms","start":"2026-04-20T14:21:52.207502Z","end":"2026-04-20T14:21:52.331049Z","steps":["trace[837264521] 'agreement among raft nodes before linearized reading' (duration: 122.642976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:52.330317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.048895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T14:21:52.331145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:51.772035Z","time spent":"559.049466ms","remote":"127.0.0.1:57506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:21:52.331196Z","caller":"traceutil/trace.go:171","msg":"trace[47086386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1269; }","duration":"390.012076ms","start":"2026-04-20T14:21:51.941171Z","end":"2026-04-20T14:21:52.331183Z","steps":["trace[47086386] 'agreement among raft nodes before linearized reading' (duration: 388.972654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:52.330324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.248835ms","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-04-20T14:21:52.331283Z","caller":"traceutil/trace.go:171","msg":"trace[1354563027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1269; }","duration":"167.263687ms","start":"2026-04-20T14:21:52.164002Z","end":"2026-04-20T14:21:52.331265Z","steps":["trace[1354563027] 'agreement among raft nodes before linearized reading' (duration: 166.165715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:52.330508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:51.864518Z","time spent":"465.981655ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:21:52.330417Z","caller":"traceutil/trace.go:171","msg":"trace[1455926433] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1269; }","duration":"722.201777ms","start":"2026-04-20T14:21:51.6082Z","end":"2026-04-20T14:21:52.330401Z","steps":["trace[1455926433] 'agreement among raft nodes before linearized reading' (duration: 721.59844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:52.331769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:51.608178Z","time spent":"723.533213ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:21:52.332134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:51.941149Z","time spent":"390.106816ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T14:21:52.525457Z","caller":"traceutil/trace.go:171","msg":"trace[1095190113] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"189.947625ms","start":"2026-04-20T14:21:52.335469Z","end":"2026-04-20T14:21:52.525417Z","steps":["trace[1095190113] 'process raft request' (duration: 143.726024ms)","trace[1095190113] 'compare' (duration: 45.990247ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:52.527944Z","caller":"traceutil/trace.go:171","msg":"trace[1515658021] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"192.32234ms","start":"2026-04-20T14:21:52.335595Z","end":"2026-04-20T14:21:52.527917Z","steps":["trace[1515658021] 'process raft request' (duration: 191.572401ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:52.528142Z","caller":"traceutil/trace.go:171","msg":"trace[87373042] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"192.324611ms","start":"2026-04-20T14:21:52.335779Z","end":"2026-04-20T14:21:52.528104Z","steps":["trace[87373042] 'process raft request' (duration: 191.592783ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:52.528513Z","caller":"traceutil/trace.go:171","msg":"trace[1713576257] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"190.822783ms","start":"2026-04-20T14:21:52.337667Z","end":"2026-04-20T14:21:52.528489Z","steps":["trace[1713576257] 'process raft request' (duration: 189.941703ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:52.528891Z","caller":"traceutil/trace.go:171","msg":"trace[963531204] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"191.036376ms","start":"2026-04-20T14:21:52.337736Z","end":"2026-04-20T14:21:52.528773Z","steps":["trace[963531204] 'process raft request' (duration: 189.948713ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:00.835165Z","caller":"traceutil/trace.go:171","msg":"trace[52382271] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"292.861813ms","start":"2026-04-20T14:22:00.542286Z","end":"2026-04-20T14:22:00.835148Z","steps":["trace[52382271] 'read index received' (duration: 292.744912ms)","trace[52382271] 'applied index is now lower than readState.Index' (duration: 116.001µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:00.835257Z","caller":"traceutil/trace.go:171","msg":"trace[834612177] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"509.616702ms","start":"2026-04-20T14:22:00.325635Z","end":"2026-04-20T14:22:00.835251Z","steps":["trace[834612177] 'process raft request' (duration: 509.367269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:00.835354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:00.325586Z","time spent":"509.689384ms","remote":"127.0.0.1:33258","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-04-20T14:22:00.835499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.516893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T14:22:00.835523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.737291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T14:22:00.835573Z","caller":"traceutil/trace.go:171","msg":"trace[1926860146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"251.641385ms","start":"2026-04-20T14:22:00.583912Z","end":"2026-04-20T14:22:00.835553Z","steps":["trace[1926860146] 'agreement among raft nodes before linearized reading' (duration: 251.378182ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:00.83559Z","caller":"traceutil/trace.go:171","msg":"trace[770441129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"205.832482ms","start":"2026-04-20T14:22:00.629743Z","end":"2026-04-20T14:22:00.835575Z","steps":["trace[770441129] 'agreement among raft nodes before linearized reading' (duration: 205.598809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:00.835615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.740733ms","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-04-20T14:22:00.835657Z","caller":"traceutil/trace.go:171","msg":"trace[714547877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1299; }","duration":"212.783243ms","start":"2026-04-20T14:22:00.622861Z","end":"2026-04-20T14:22:00.835645Z","steps":["trace[714547877] 'agreement among raft nodes before linearized reading' (duration: 212.668812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:00.835668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.38853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2569"} {"level":"info","ts":"2026-04-20T14:22:00.83569Z","caller":"traceutil/trace.go:171","msg":"trace[1588442532] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1299; }","duration":"293.440431ms","start":"2026-04-20T14:22:00.542244Z","end":"2026-04-20T14:22:00.835684Z","steps":["trace[1588442532] 'agreement among raft nodes before linearized reading' (duration: 293.35817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:00.835784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.617961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:22:00.835817Z","caller":"traceutil/trace.go:171","msg":"trace[1204302374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1299; }","duration":"212.688541ms","start":"2026-04-20T14:22:00.623119Z","end":"2026-04-20T14:22:00.835808Z","steps":["trace[1204302374] 'agreement among raft nodes before linearized reading' (duration: 212.61645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:00.835839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.268023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T14:22:00.835855Z","caller":"traceutil/trace.go:171","msg":"trace[856473623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1299; }","duration":"216.320865ms","start":"2026-04-20T14:22:00.619529Z","end":"2026-04-20T14:22:00.83585Z","steps":["trace[856473623] 'agreement among raft nodes before linearized reading' (duration: 216.280314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:02.754392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.201141ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T14:22:02.772547Z","caller":"traceutil/trace.go:171","msg":"trace[1060338893] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"151.978363ms","start":"2026-04-20T14:22:02.620548Z","end":"2026-04-20T14:22:02.772527Z","steps":["trace[1060338893] 'process raft request' (duration: 151.593918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:09.384382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585158617,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:22:09.526153Z","caller":"traceutil/trace.go:171","msg":"trace[1817638650] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"725.451906ms","start":"2026-04-20T14:22:08.800672Z","end":"2026-04-20T14:22:09.526124Z","steps":["trace[1817638650] 'process raft request' (duration: 725.264013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:09.526547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:08.800654Z","time spent":"725.597097ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:22:09.527521Z","caller":"traceutil/trace.go:171","msg":"trace[1565185261] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1387; }","duration":"643.354308ms","start":"2026-04-20T14:22:08.884148Z","end":"2026-04-20T14:22:09.527502Z","steps":["trace[1565185261] 'read index received' (duration: 641.824109ms)","trace[1565185261] 'applied index is now lower than readState.Index' (duration: 1.529319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:22:09.527971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.805392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T14:22:09.528055Z","caller":"traceutil/trace.go:171","msg":"trace[518937688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1332; }","duration":"643.958764ms","start":"2026-04-20T14:22:08.884087Z","end":"2026-04-20T14:22:09.528046Z","steps":["trace[518937688] 'agreement among raft nodes before linearized reading' (duration: 643.680482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:09.528102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:08.884051Z","time spent":"644.041966ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:22:09.528247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.914074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T14:22:09.528396Z","caller":"traceutil/trace.go:171","msg":"trace[1573253774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"644.092397ms","start":"2026-04-20T14:22:08.884207Z","end":"2026-04-20T14:22:09.5283Z","steps":["trace[1573253774] 'agreement among raft nodes before linearized reading' (duration: 643.603681ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:09.527762Z","caller":"traceutil/trace.go:171","msg":"trace[1447746418] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"668.252305ms","start":"2026-04-20T14:22:08.859475Z","end":"2026-04-20T14:22:09.527728Z","steps":["trace[1447746418] 'process raft request' (duration: 667.652838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:09.528568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:08.884199Z","time spent":"644.250248ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:22:09.528615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:08.859443Z","time spent":"669.063065ms","remote":"127.0.0.1:33258","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-04-20T14:22:09.528708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.367844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:22:09.52879Z","caller":"traceutil/trace.go:171","msg":"trace[1917605532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"294.514195ms","start":"2026-04-20T14:22:09.234259Z","end":"2026-04-20T14:22:09.528773Z","steps":["trace[1917605532] 'agreement among raft nodes before linearized reading' (duration: 294.256722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:09.528872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.458442ms","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-04-20T14:22:09.528929Z","caller":"traceutil/trace.go:171","msg":"trace[563865168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"644.515562ms","start":"2026-04-20T14:22:08.884399Z","end":"2026-04-20T14:22:09.528914Z","steps":["trace[563865168] 'agreement among raft nodes before linearized reading' (duration: 644.38308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:09.529032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.768454ms","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-04-20T14:22:09.528972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:08.884373Z","time spent":"644.589053ms","remote":"127.0.0.1:33258","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-04-20T14:22:09.529076Z","caller":"traceutil/trace.go:171","msg":"trace[262656619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"644.826685ms","start":"2026-04-20T14:22:08.884239Z","end":"2026-04-20T14:22:09.529066Z","steps":["trace[262656619] 'agreement among raft nodes before linearized reading' (duration: 644.709544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:09.529106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:08.884232Z","time spent":"644.866136ms","remote":"127.0.0.1:33258","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":"info","ts":"2026-04-20T14:22:11.729596Z","caller":"traceutil/trace.go:171","msg":"trace[1232773976] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"168.54012ms","start":"2026-04-20T14:22:11.561031Z","end":"2026-04-20T14:22:11.729572Z","steps":["trace[1232773976] 'process raft request' (duration: 168.286108ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:11.729648Z","caller":"traceutil/trace.go:171","msg":"trace[1983740097] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"168.586821ms","start":"2026-04-20T14:22:11.561042Z","end":"2026-04-20T14:22:11.729629Z","steps":["trace[1983740097] 'process raft request' (duration: 168.420359ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:11.729595Z","caller":"traceutil/trace.go:171","msg":"trace[6443463] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"168.247496ms","start":"2026-04-20T14:22:11.561326Z","end":"2026-04-20T14:22:11.729573Z","steps":["trace[6443463] 'process raft request' (duration: 168.177806ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:11.729743Z","caller":"traceutil/trace.go:171","msg":"trace[538465357] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1395; }","duration":"151.246271ms","start":"2026-04-20T14:22:11.578469Z","end":"2026-04-20T14:22:11.729715Z","steps":["trace[538465357] 'read index received' (duration: 5.17989ms)","trace[538465357] 'applied index is now lower than readState.Index' (duration: 146.064561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:22:11.729908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.413052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-20T14:22:11.729953Z","caller":"traceutil/trace.go:171","msg":"trace[1481607879] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1343; }","duration":"151.508975ms","start":"2026-04-20T14:22:11.57843Z","end":"2026-04-20T14:22:11.729939Z","steps":["trace[1481607879] 'agreement among raft nodes before linearized reading' (duration: 151.400913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:11.729805Z","caller":"traceutil/trace.go:171","msg":"trace[1231417689] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"168.177446ms","start":"2026-04-20T14:22:11.561587Z","end":"2026-04-20T14:22:11.729765Z","steps":["trace[1231417689] 'process raft request' (duration: 167.946984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:11.730071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.796193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:950"} {"level":"info","ts":"2026-04-20T14:22:11.730152Z","caller":"traceutil/trace.go:171","msg":"trace[1678625070] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1343; }","duration":"128.935894ms","start":"2026-04-20T14:22:11.6012Z","end":"2026-04-20T14:22:11.730136Z","steps":["trace[1678625070] 'agreement among raft nodes before linearized reading' (duration: 128.765702ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:12.773636Z","caller":"traceutil/trace.go:171","msg":"trace[311414010] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"216.275529ms","start":"2026-04-20T14:22:12.557298Z","end":"2026-04-20T14:22:12.773573Z","steps":["trace[311414010] 'read index received' (duration: 149.869575ms)","trace[311414010] 'applied index is now lower than readState.Index' (duration: 66.404104ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:22:12.773858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.472494ms","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-04-20T14:22:12.773857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.533272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:841"} {"level":"info","ts":"2026-04-20T14:22:12.773919Z","caller":"traceutil/trace.go:171","msg":"trace[2024609695] range","detail":"{range_begin:/registry/clusterroles/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1343; }","duration":"211.561404ms","start":"2026-04-20T14:22:12.562329Z","end":"2026-04-20T14:22:12.773891Z","steps":["trace[2024609695] 'agreement among raft nodes before linearized reading' (duration: 211.470653ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:12.773919Z","caller":"traceutil/trace.go:171","msg":"trace[1748992256] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1343; }","duration":"216.634153ms","start":"2026-04-20T14:22:12.557266Z","end":"2026-04-20T14:22:12.7739Z","steps":["trace[1748992256] 'agreement among raft nodes before linearized reading' (duration: 216.497892ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:29.99716Z","caller":"traceutil/trace.go:171","msg":"trace[1755312308] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1462; }","duration":"111.410123ms","start":"2026-04-20T14:22:29.885723Z","end":"2026-04-20T14:22:29.997133Z","steps":["trace[1755312308] 'read index received' (duration: 102.206482ms)","trace[1755312308] 'applied index is now lower than readState.Index' (duration: 9.202871ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:29.997244Z","caller":"traceutil/trace.go:171","msg":"trace[722792485] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"174.43023ms","start":"2026-04-20T14:22:29.822801Z","end":"2026-04-20T14:22:29.997231Z","steps":["trace[722792485] 'process raft request' (duration: 165.176287ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:29.997315Z","caller":"traceutil/trace.go:171","msg":"trace[1955419850] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"173.842567ms","start":"2026-04-20T14:22:29.823462Z","end":"2026-04-20T14:22:29.997304Z","steps":["trace[1955419850] 'process raft request' (duration: 173.626999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:29.997567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.82953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T14:22:29.997592Z","caller":"traceutil/trace.go:171","msg":"trace[874893172] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1403; }","duration":"111.886522ms","start":"2026-04-20T14:22:29.885697Z","end":"2026-04-20T14:22:29.997584Z","steps":["trace[874893172] 'agreement among raft nodes before linearized reading' (duration: 111.83601ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:31.916244Z","caller":"traceutil/trace.go:171","msg":"trace[1742019432] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"207.799306ms","start":"2026-04-20T14:22:31.708427Z","end":"2026-04-20T14:22:31.916227Z","steps":["trace[1742019432] 'read index received' (duration: 207.623669ms)","trace[1742019432] 'applied index is now lower than readState.Index' (duration: 174.987µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:22:31.91638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.92816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:22:31.916426Z","caller":"traceutil/trace.go:171","msg":"trace[439028590] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1405; }","duration":"208.021865ms","start":"2026-04-20T14:22:31.708395Z","end":"2026-04-20T14:22:31.916417Z","steps":["trace[439028590] 'agreement among raft nodes before linearized reading' (duration: 207.93346ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:31.916575Z","caller":"traceutil/trace.go:171","msg":"trace[1976464767] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"240.940649ms","start":"2026-04-20T14:22:31.675337Z","end":"2026-04-20T14:22:31.916277Z","steps":["trace[1976464767] 'process raft request' (duration: 240.757712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:31.91674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.360039ms","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-04-20T14:22:31.916784Z","caller":"traceutil/trace.go:171","msg":"trace[2023207171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1405; }","duration":"198.438302ms","start":"2026-04-20T14:22:31.718337Z","end":"2026-04-20T14:22:31.916775Z","steps":["trace[2023207171] 'agreement among raft nodes before linearized reading' (duration: 198.301307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:31.916753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.281062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:22:31.916855Z","caller":"traceutil/trace.go:171","msg":"trace[2123335835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1405; }","duration":"127.412316ms","start":"2026-04-20T14:22:31.789436Z","end":"2026-04-20T14:22:31.916849Z","steps":["trace[2123335835] 'agreement among raft nodes before linearized reading' (duration: 127.294932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:31.916859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.902298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-e1s709\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-20T14:22:31.916886Z","caller":"traceutil/trace.go:171","msg":"trace[678029389] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-e1s709; range_end:; response_count:1; response_revision:1405; }","duration":"194.942678ms","start":"2026-04-20T14:22:31.721937Z","end":"2026-04-20T14:22:31.91688Z","steps":["trace[678029389] 'agreement among raft nodes before linearized reading' (duration: 194.901257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:31.916992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.223217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-fzf9gk\" ","response":"range_response_count:1 size:692"} {"level":"info","ts":"2026-04-20T14:22:31.917015Z","caller":"traceutil/trace.go:171","msg":"trace[1990420927] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-fzf9gk; range_end:; response_count:1; response_revision:1405; }","duration":"160.275149ms","start":"2026-04-20T14:22:31.756734Z","end":"2026-04-20T14:22:31.917009Z","steps":["trace[1990420927] 'agreement among raft nodes before linearized reading' (duration: 160.235148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:34.416738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585159711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:34.917053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585159711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:35.41782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585159711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:35.918294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585159711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:36.419195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585159711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:36.920062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585159711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:37.420972Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585159711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:37.79195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99798783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T14:22:37.792018Z","caller":"traceutil/trace.go:171","msg":"trace[420597958] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.998092644s","start":"2026-04-20T14:22:35.793914Z","end":"2026-04-20T14:22:37.792006Z","steps":["trace[420597958] 'agreement among raft nodes before linearized reading' (duration: 1.998013521s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:37.792047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:35.7939Z","time spent":"1.998141755s","remote":"127.0.0.1:33008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 14:22:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:22:37.921748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437921585159711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T14:22:38.064535Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.227081657s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T14:22:38.06508Z","caller":"traceutil/trace.go:171","msg":"trace[914456729] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"4.227723611s","start":"2026-04-20T14:22:33.837333Z","end":"2026-04-20T14:22:38.065057Z","steps":["trace[914456729] 'process raft request' (duration: 4.227558435s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.065258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:33.837307Z","time spent":"4.227836355s","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:22:38.191104Z","caller":"traceutil/trace.go:171","msg":"trace[885807101] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1472; }","duration":"4.275129224s","start":"2026-04-20T14:22:33.915954Z","end":"2026-04-20T14:22:38.191084Z","steps":["trace[885807101] 'read index received' (duration: 4.148841799s)","trace[885807101] 'applied index is now lower than readState.Index' (duration: 126.286515ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:38.191214Z","caller":"traceutil/trace.go:171","msg":"trace[454843407] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"4.269161321s","start":"2026-04-20T14:22:33.922012Z","end":"2026-04-20T14:22:38.191173Z","steps":["trace[454843407] 'process raft request' (duration: 4.267996568s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.191302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.084771517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:22:38.191303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.893636134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:22:38.191329Z","caller":"traceutil/trace.go:171","msg":"trace[1078030656] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1412; }","duration":"3.084827139s","start":"2026-04-20T14:22:35.106494Z","end":"2026-04-20T14:22:38.191321Z","steps":["trace[1078030656] 'agreement among raft nodes before linearized reading' (duration: 3.084776257s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:38.191347Z","caller":"traceutil/trace.go:171","msg":"trace[174967522] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1412; }","duration":"3.893698616s","start":"2026-04-20T14:22:34.297637Z","end":"2026-04-20T14:22:38.191336Z","steps":["trace[174967522] 'agreement among raft nodes before linearized reading' (duration: 3.893639814s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.191452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.051819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:22:38.191454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:34.297629Z","time spent":"3.893814321s","remote":"127.0.0.1:33306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:22:38.191481Z","caller":"traceutil/trace.go:171","msg":"trace[1032049773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1412; }","duration":"399.111721ms","start":"2026-04-20T14:22:37.792363Z","end":"2026-04-20T14:22:38.191475Z","steps":["trace[1032049773] 'agreement among raft nodes before linearized reading' (duration: 399.0729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.191497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:37.792323Z","time spent":"399.170825ms","remote":"127.0.0.1:33002","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-04-20T14:22:38.191647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068618965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:22:38.191705Z","caller":"traceutil/trace.go:171","msg":"trace[2106368392] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:1412; }","duration":"1.068709088s","start":"2026-04-20T14:22:37.122988Z","end":"2026-04-20T14:22:38.191697Z","steps":["trace[2106368392] 'agreement among raft nodes before linearized reading' (duration: 1.068560113s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.191754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.978790337s","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-04-20T14:22:38.191776Z","caller":"traceutil/trace.go:171","msg":"trace[1153029941] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1412; }","duration":"2.97884284s","start":"2026-04-20T14:22:35.212927Z","end":"2026-04-20T14:22:38.19177Z","steps":["trace[1153029941] 'agreement among raft nodes before linearized reading' (duration: 2.978812859s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.191793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:35.21291Z","time spent":"2.97887911s","remote":"127.0.0.1:33188","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-04-20T14:22:38.191787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.178882763s","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-04-20T14:22:38.191752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:37.122971Z","time spent":"1.068773441s","remote":"127.0.0.1:33028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.219\" "} {"level":"warn","ts":"2026-04-20T14:22:38.191883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.258121766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:22:38.191908Z","caller":"traceutil/trace.go:171","msg":"trace[499562487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1412; }","duration":"4.258167478s","start":"2026-04-20T14:22:33.933735Z","end":"2026-04-20T14:22:38.191903Z","steps":["trace[499562487] 'agreement among raft nodes before linearized reading' (duration: 4.258103926s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.191927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:33.933722Z","time spent":"4.258200229s","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:22:38.191908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:33.921999Z","time spent":"4.269795874s","remote":"127.0.0.1:33258","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-04-20T14:22:38.191245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.27261613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-20T14:22:38.192035Z","caller":"traceutil/trace.go:171","msg":"trace[1672358705] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:1412; }","duration":"4.27344225s","start":"2026-04-20T14:22:33.918584Z","end":"2026-04-20T14:22:38.192026Z","steps":["trace[1672358705] 'agreement among raft nodes before linearized reading' (duration: 4.27262479s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.192053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.259184305s","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-04-20T14:22:38.192076Z","caller":"traceutil/trace.go:171","msg":"trace[1201011114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1412; }","duration":"4.259244507s","start":"2026-04-20T14:22:33.932826Z","end":"2026-04-20T14:22:38.19207Z","steps":["trace[1201011114] 'agreement among raft nodes before linearized reading' (duration: 4.259191905s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.192093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:33.932812Z","time spent":"4.259276779s","remote":"127.0.0.1:33258","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-04-20T14:22:38.19211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.266757489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T14:22:38.192128Z","caller":"traceutil/trace.go:171","msg":"trace[1722231402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1412; }","duration":"4.266789541s","start":"2026-04-20T14:22:33.925334Z","end":"2026-04-20T14:22:38.192123Z","steps":["trace[1722231402] 'agreement among raft nodes before linearized reading' (duration: 4.266735288s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.192142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:33.925327Z","time spent":"4.266812052s","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:22:38.191353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:35.10648Z","time spent":"3.08486667s","remote":"127.0.0.1:33090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":10,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:22:38.191712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.162853346s","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-04-20T14:22:38.192192Z","caller":"traceutil/trace.go:171","msg":"trace[1285192988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1412; }","duration":"4.163353995s","start":"2026-04-20T14:22:34.028833Z","end":"2026-04-20T14:22:38.192187Z","steps":["trace[1285192988] 'agreement among raft nodes before linearized reading' (duration: 4.162797975s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.19221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:34.028825Z","time spent":"4.163380396s","remote":"127.0.0.1:33258","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-04-20T14:22:38.192199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.494022ms","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-04-20T14:22:38.192261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.903024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:22:38.19228Z","caller":"traceutil/trace.go:171","msg":"trace[2090714284] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1412; }","duration":"861.947845ms","start":"2026-04-20T14:22:37.330327Z","end":"2026-04-20T14:22:38.192275Z","steps":["trace[2090714284] 'agreement among raft nodes before linearized reading' (duration: 861.918254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.192296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:37.330314Z","time spent":"861.977507ms","remote":"127.0.0.1:57430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:22:38.1923Z","caller":"traceutil/trace.go:171","msg":"trace[538181037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1412; }","duration":"406.680069ms","start":"2026-04-20T14:22:37.785589Z","end":"2026-04-20T14:22:38.192269Z","steps":["trace[538181037] 'agreement among raft nodes before linearized reading' (duration: 406.431009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.192361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:37.785576Z","time spent":"406.776603ms","remote":"127.0.0.1:57540","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":"warn","ts":"2026-04-20T14:22:38.191727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.044689702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:22:38.191824Z","caller":"traceutil/trace.go:171","msg":"trace[1713427222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1412; }","duration":"4.178940444s","start":"2026-04-20T14:22:34.012875Z","end":"2026-04-20T14:22:38.191815Z","steps":["trace[1713427222] 'agreement among raft nodes before linearized reading' (duration: 4.178857511s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:38.192458Z","caller":"traceutil/trace.go:171","msg":"trace[2010789067] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1412; }","duration":"2.045453139s","start":"2026-04-20T14:22:36.146992Z","end":"2026-04-20T14:22:38.192446Z","steps":["trace[2010789067] 'agreement among raft nodes before linearized reading' (duration: 2.044612199s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.192483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:36.146974Z","time spent":"2.045501021s","remote":"127.0.0.1:33320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:22:38.191242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.275277088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-20T14:22:38.192576Z","caller":"traceutil/trace.go:171","msg":"trace[1185336094] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:1412; }","duration":"4.276649979s","start":"2026-04-20T14:22:33.915916Z","end":"2026-04-20T14:22:38.192566Z","steps":["trace[1185336094] 'agreement among raft nodes before linearized reading' (duration: 4.275270028s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:38.192607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:33.91588Z","time spent":"4.276719332s","remote":"127.0.0.1:33186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-20T14:22:38.192057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:33.91857Z","time spent":"4.273481952s","remote":"127.0.0.1:33186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-04-20T14:22:38.192474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:34.012864Z","time spent":"4.179591019s","remote":"127.0.0.1:33258","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":"info","ts":"2026-04-20T14:22:40.42435Z","caller":"traceutil/trace.go:171","msg":"trace[1729634265] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1484; }","duration":"144.987507ms","start":"2026-04-20T14:22:40.279339Z","end":"2026-04-20T14:22:40.424327Z","steps":["trace[1729634265] 'read index received' (duration: 142.653322ms)","trace[1729634265] 'applied index is now lower than readState.Index' (duration: 2.333325ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:40.424506Z","caller":"traceutil/trace.go:171","msg":"trace[949519506] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"156.977051ms","start":"2026-04-20T14:22:40.267513Z","end":"2026-04-20T14:22:40.424491Z","steps":["trace[949519506] 'process raft request' (duration: 156.740763ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:40.424506Z","caller":"traceutil/trace.go:171","msg":"trace[306428920] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"167.637276ms","start":"2026-04-20T14:22:40.256782Z","end":"2026-04-20T14:22:40.424419Z","steps":["trace[306428920] 'process raft request' (duration: 165.217229ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:40.424487Z","caller":"traceutil/trace.go:171","msg":"trace[795948435] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"155.267649ms","start":"2026-04-20T14:22:40.269192Z","end":"2026-04-20T14:22:40.424459Z","steps":["trace[795948435] 'process raft request' (duration: 155.099223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:40.424515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.158693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-04-20T14:22:40.424775Z","caller":"traceutil/trace.go:171","msg":"trace[737005420] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:1; response_revision:1425; }","duration":"145.459835ms","start":"2026-04-20T14:22:40.279299Z","end":"2026-04-20T14:22:40.424759Z","steps":["trace[737005420] 'agreement among raft nodes before linearized reading' (duration: 145.156953ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:40.42436Z","caller":"traceutil/trace.go:171","msg":"trace[548229171] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"167.503641ms","start":"2026-04-20T14:22:40.25684Z","end":"2026-04-20T14:22:40.424343Z","steps":["trace[548229171] 'process raft request' (duration: 167.313685ms)"],"step_count":1}