{"level":"warn","ts":"2026-08-14T09:29:00.562696Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:29:00.562792Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.119: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.119:2380","--initial-cluster=instance=https://199.19.213.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.119:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-14T09:29:00.562868Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:29:00.562881Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-08-14T09:29:00.562898Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-14T09:29:00.563599Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"]} {"level":"info","ts":"2026-08-14T09:29:00.563671Z","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.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.119:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-14T09:29:00.568888Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.027434ms"} {"level":"info","ts":"2026-08-14T09:29:00.598053Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ec9077c81c499df2","cluster-id":"7e82d3f5f740d3fb"} {"level":"info","ts":"2026-08-14T09:29:00.598166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:29:00.598196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 0"} {"level":"info","ts":"2026-08-14T09:29:00.59821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ec9077c81c499df2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:29:00.598219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 1"} {"level":"info","ts":"2026-08-14T09:29:00.598397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"warn","ts":"2026-08-14T09:29:00.615893Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:29:00.618804Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:29:00.621278Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-14T09:29:00.623486Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ec9077c81c499df2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:29:00.624046Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ec9077c81c499df2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:29:00.624223Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-14T09:29:00.624353Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-14T09:29:00.62437Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-14T09:29:00.62618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"info","ts":"2026-08-14T09:29:00.626306Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","added-peer-id":"ec9077c81c499df2","added-peer-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-08-14T09:29:00.629717Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-14T09:29:00.630228Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ec9077c81c499df2","initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:29:00.629869Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-08-14T09:29:00.631242Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-08-14T09:29:00.63044Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:29:00.698865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:29:00.699024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:29:00.699188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgPreVoteResp from ec9077c81c499df2 at term 1"} {"level":"info","ts":"2026-08-14T09:29:00.699282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:29:00.69934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgVoteResp from ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-08-14T09:29:00.699506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became leader at term 2"} {"level":"info","ts":"2026-08-14T09:29:00.699588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ec9077c81c499df2 elected leader ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-08-14T09:29:00.704121Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ec9077c81c499df2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.119:2379]}","request-path":"/0/members/ec9077c81c499df2/attributes","cluster-id":"7e82d3f5f740d3fb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:29:00.704161Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:29:00.70421Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:29:00.704349Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:29:00.704698Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:29:00.704802Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:29:00.706848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.119:2379"} {"level":"info","ts":"2026-08-14T09:29:00.707196Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:29:00.707426Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:29:00.707528Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:29:00.707751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T09:29:08.649899Z","caller":"traceutil/trace.go:171","msg":"trace[258942607] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:297; }","duration":"170.941704ms","start":"2026-08-14T09:29:08.478924Z","end":"2026-08-14T09:29:08.649866Z","steps":["trace[258942607] 'read index received' (duration: 78.219536ms)","trace[258942607] 'applied index is now lower than readState.Index' (duration: 92.717558ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:29:08.649868Z","caller":"traceutil/trace.go:171","msg":"trace[50914349] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"161.66278ms","start":"2026-08-14T09:29:08.48818Z","end":"2026-08-14T09:29:08.649843Z","steps":["trace[50914349] 'process raft request' (duration: 161.586798ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:08.649987Z","caller":"traceutil/trace.go:171","msg":"trace[351301341] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"262.387943ms","start":"2026-08-14T09:29:08.387579Z","end":"2026-08-14T09:29:08.649967Z","steps":["trace[351301341] 'process raft request' (duration: 169.548771ms)","trace[351301341] 'compare' (duration: 92.380758ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:08.650104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.518952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-08-14T09:29:08.650156Z","caller":"traceutil/trace.go:171","msg":"trace[162240710] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:288; }","duration":"211.622975ms","start":"2026-08-14T09:29:08.438526Z","end":"2026-08-14T09:29:08.650149Z","steps":["trace[162240710] 'agreement among raft nodes before linearized reading' (duration: 211.520881ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:09.642801Z","caller":"traceutil/trace.go:171","msg":"trace[1037768732] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"104.966168ms","start":"2026-08-14T09:29:09.53782Z","end":"2026-08-14T09:29:09.642786Z","steps":["trace[1037768732] 'process raft request' (duration: 104.867785ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:10.5465Z","caller":"traceutil/trace.go:171","msg":"trace[465011386] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"309.506213ms","start":"2026-08-14T09:29:10.236967Z","end":"2026-08-14T09:29:10.546473Z","steps":["trace[465011386] 'read index received' (duration: 259.215702ms)","trace[465011386] 'applied index is now lower than readState.Index' (duration: 50.289611ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:29:10.546714Z","caller":"traceutil/trace.go:171","msg":"trace[1805833303] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"315.752173ms","start":"2026-08-14T09:29:10.230866Z","end":"2026-08-14T09:29:10.546619Z","steps":["trace[1805833303] 'process raft request' (duration: 265.330718ms)","trace[1805833303] 'compare' (duration: 50.161628ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:10.546668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.679639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:10.546856Z","caller":"traceutil/trace.go:171","msg":"trace[1308842941] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:300; }","duration":"309.907605ms","start":"2026-08-14T09:29:10.236932Z","end":"2026-08-14T09:29:10.546839Z","steps":["trace[1308842941] 'agreement among raft nodes before linearized reading' (duration: 309.681008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:10.546908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:10.236919Z","time spent":"309.973538ms","remote":"127.0.0.1:48302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"warn","ts":"2026-08-14T09:29:10.548412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:10.230854Z","time spent":"315.98501ms","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:29:10.75883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.333029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-08-14T09:29:10.758872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.477625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-14T09:29:10.758921Z","caller":"traceutil/trace.go:171","msg":"trace[579001959] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:302; }","duration":"110.454763ms","start":"2026-08-14T09:29:10.648447Z","end":"2026-08-14T09:29:10.758902Z","steps":["trace[579001959] 'range keys from in-memory index tree' (duration: 110.258667ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:10.758961Z","caller":"traceutil/trace.go:171","msg":"trace[965430782] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:302; }","duration":"111.616709ms","start":"2026-08-14T09:29:10.647327Z","end":"2026-08-14T09:29:10.758944Z","steps":["trace[965430782] 'range keys from in-memory index tree' (duration: 111.403572ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:10.80137Z","caller":"traceutil/trace.go:171","msg":"trace[636225186] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"148.037477ms","start":"2026-08-14T09:29:10.653303Z","end":"2026-08-14T09:29:10.80134Z","steps":["trace[636225186] 'process raft request' (duration: 147.869782ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:10.83993Z","caller":"traceutil/trace.go:171","msg":"trace[90167259] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"186.332034ms","start":"2026-08-14T09:29:10.65356Z","end":"2026-08-14T09:29:10.839892Z","steps":["trace[90167259] 'process raft request' (duration: 186.055905ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:10.840022Z","caller":"traceutil/trace.go:171","msg":"trace[770607803] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"186.23073ms","start":"2026-08-14T09:29:10.653721Z","end":"2026-08-14T09:29:10.839952Z","steps":["trace[770607803] 'process raft request' (duration: 186.029304ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:10.83998Z","caller":"traceutil/trace.go:171","msg":"trace[372785598] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:313; }","duration":"181.022932ms","start":"2026-08-14T09:29:10.658935Z","end":"2026-08-14T09:29:10.839958Z","steps":["trace[372785598] 'read index received' (duration: 142.346264ms)","trace[372785598] 'applied index is now lower than readState.Index' (duration: 38.675457ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:10.84015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.194577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:29:10.840216Z","caller":"traceutil/trace.go:171","msg":"trace[308221655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:306; }","duration":"181.28751ms","start":"2026-08-14T09:29:10.658913Z","end":"2026-08-14T09:29:10.840201Z","steps":["trace[308221655] 'agreement among raft nodes before linearized reading' (duration: 181.152146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:10.840262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.907657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-08-14T09:29:10.840323Z","caller":"traceutil/trace.go:171","msg":"trace[1595853860] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:306; }","duration":"180.00631ms","start":"2026-08-14T09:29:10.660301Z","end":"2026-08-14T09:29:10.840307Z","steps":["trace[1595853860] 'agreement among raft nodes before linearized reading' (duration: 179.910737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:10.840481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.316118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-14T09:29:10.840526Z","caller":"traceutil/trace.go:171","msg":"trace[1763074359] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:306; }","duration":"153.443211ms","start":"2026-08-14T09:29:10.68707Z","end":"2026-08-14T09:29:10.840513Z","steps":["trace[1763074359] 'agreement among raft nodes before linearized reading' (duration: 153.327468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:10.840977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.610844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-08-14T09:29:10.841049Z","caller":"traceutil/trace.go:171","msg":"trace[149833063] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:306; }","duration":"103.708957ms","start":"2026-08-14T09:29:10.737321Z","end":"2026-08-14T09:29:10.84103Z","steps":["trace[149833063] 'agreement among raft nodes before linearized reading' (duration: 103.056407ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:11.546397Z","caller":"traceutil/trace.go:171","msg":"trace[1003133537] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"301.134521ms","start":"2026-08-14T09:29:11.245225Z","end":"2026-08-14T09:29:11.546359Z","steps":["trace[1003133537] 'process raft request' (duration: 241.894079ms)","trace[1003133537] 'compare' (duration: 59.115878ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:11.546553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:11.245207Z","time spent":"301.256534ms","remote":"127.0.0.1:48590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:29:11.594397Z","caller":"traceutil/trace.go:171","msg":"trace[1111227951] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:331; }","duration":"306.919406ms","start":"2026-08-14T09:29:11.287449Z","end":"2026-08-14T09:29:11.594368Z","steps":["trace[1111227951] 'read index received' (duration: 199.641353ms)","trace[1111227951] 'applied index is now lower than readState.Index' (duration: 107.276843ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:29:11.594513Z","caller":"traceutil/trace.go:171","msg":"trace[1145032051] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"348.204012ms","start":"2026-08-14T09:29:11.246261Z","end":"2026-08-14T09:29:11.594465Z","steps":["trace[1145032051] 'process raft request' (duration: 347.965055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:11.594562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.089641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-08-14T09:29:11.594613Z","caller":"traceutil/trace.go:171","msg":"trace[26107656] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:322; }","duration":"307.184044ms","start":"2026-08-14T09:29:11.287416Z","end":"2026-08-14T09:29:11.5946Z","steps":["trace[26107656] 'agreement among raft nodes before linearized reading' (duration: 307.081341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:11.594658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:11.287399Z","time spent":"307.248757ms","remote":"127.0.0.1:48302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-08-14T09:29:11.59467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:11.246237Z","time spent":"348.349627ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:29:11.604453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.69626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-14T09:29:11.604553Z","caller":"traceutil/trace.go:171","msg":"trace[326396726] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:323; }","duration":"116.843595ms","start":"2026-08-14T09:29:11.487688Z","end":"2026-08-14T09:29:11.604531Z","steps":["trace[326396726] 'agreement among raft nodes before linearized reading' (duration: 116.659869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:11.604588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.154095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-08-14T09:29:11.60465Z","caller":"traceutil/trace.go:171","msg":"trace[831620594] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:323; }","duration":"167.240078ms","start":"2026-08-14T09:29:11.437392Z","end":"2026-08-14T09:29:11.604632Z","steps":["trace[831620594] 'agreement among raft nodes before linearized reading' (duration: 167.100823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:11.604932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.393403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-14T09:29:11.604998Z","caller":"traceutil/trace.go:171","msg":"trace[952225746] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:323; }","duration":"217.466516ms","start":"2026-08-14T09:29:11.387492Z","end":"2026-08-14T09:29:11.604959Z","steps":["trace[952225746] 'agreement among raft nodes before linearized reading' (duration: 217.399043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:11.6053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.215789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-14T09:29:11.605366Z","caller":"traceutil/trace.go:171","msg":"trace[1092415350] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:323; }","duration":"267.310922ms","start":"2026-08-14T09:29:11.338033Z","end":"2026-08-14T09:29:11.605344Z","steps":["trace[1092415350] 'agreement among raft nodes before linearized reading' (duration: 267.194449ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:22.783498Z","caller":"traceutil/trace.go:171","msg":"trace[1419966542] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:400; }","duration":"164.683454ms","start":"2026-08-14T09:29:22.618781Z","end":"2026-08-14T09:29:22.783465Z","steps":["trace[1419966542] 'read index received' (duration: 164.674303ms)","trace[1419966542] 'applied index is now lower than readState.Index' (duration: 7.28µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:29:22.783497Z","caller":"traceutil/trace.go:171","msg":"trace[1335537914] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"175.683466ms","start":"2026-08-14T09:29:22.607787Z","end":"2026-08-14T09:29:22.78347Z","steps":["trace[1335537914] 'process raft request' (duration: 175.539741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:22.783642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.833729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-14T09:29:22.783762Z","caller":"traceutil/trace.go:171","msg":"trace[812384258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:386; }","duration":"165.000424ms","start":"2026-08-14T09:29:22.618739Z","end":"2026-08-14T09:29:22.78374Z","steps":["trace[812384258] 'agreement among raft nodes before linearized reading' (duration: 164.776257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:22.783801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.135604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:29:22.783854Z","caller":"traceutil/trace.go:171","msg":"trace[88426138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:386; }","duration":"139.237057ms","start":"2026-08-14T09:29:22.6446Z","end":"2026-08-14T09:29:22.783837Z","steps":["trace[88426138] 'agreement among raft nodes before linearized reading' (duration: 139.104083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.559625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335128475548248,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:29:41.655519Z","caller":"traceutil/trace.go:171","msg":"trace[1682784229] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"601.134343ms","start":"2026-08-14T09:29:41.054357Z","end":"2026-08-14T09:29:41.655492Z","steps":["trace[1682784229] 'process raft request' (duration: 587.609331ms)","trace[1682784229] 'compare' (duration: 13.379707ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:41.655767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.05414Z","time spent":"601.549975ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:29:41.659231Z","caller":"traceutil/trace.go:171","msg":"trace[1245562957] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:542; }","duration":"600.73971ms","start":"2026-08-14T09:29:41.058469Z","end":"2026-08-14T09:29:41.659209Z","steps":["trace[1245562957] 'read index received' (duration: 583.58113ms)","trace[1245562957] 'applied index is now lower than readState.Index' (duration: 17.15539ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:29:41.659335Z","caller":"traceutil/trace.go:171","msg":"trace[1519360889] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"603.907935ms","start":"2026-08-14T09:29:41.055418Z","end":"2026-08-14T09:29:41.659325Z","steps":["trace[1519360889] 'process raft request' (duration: 603.332238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.659427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.055404Z","time spent":"603.953227ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10537,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:29:41.659608Z","caller":"traceutil/trace.go:171","msg":"trace[1597178913] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"550.281501ms","start":"2026-08-14T09:29:41.109299Z","end":"2026-08-14T09:29:41.65958Z","steps":["trace[1597178913] 'process raft request' (duration: 549.737765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.659795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.109271Z","time spent":"550.459337ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:29:41.659834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.113277ms","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-08-14T09:29:41.659874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.096701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:41.659904Z","caller":"traceutil/trace.go:171","msg":"trace[1454555453] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:524; }","duration":"392.140883ms","start":"2026-08-14T09:29:41.267755Z","end":"2026-08-14T09:29:41.659896Z","steps":["trace[1454555453] 'agreement among raft nodes before linearized reading' (duration: 392.098331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.659922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.262115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T09:29:41.659966Z","caller":"traceutil/trace.go:171","msg":"trace[652378800] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"473.480169ms","start":"2026-08-14T09:29:41.186472Z","end":"2026-08-14T09:29:41.659952Z","steps":["trace[652378800] 'process raft request' (duration: 472.663415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.443992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:41.660007Z","caller":"traceutil/trace.go:171","msg":"trace[916319608] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"582.993173ms","start":"2026-08-14T09:29:41.077006Z","end":"2026-08-14T09:29:41.659999Z","steps":["trace[916319608] 'process raft request' (duration: 581.89771ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:41.660044Z","caller":"traceutil/trace.go:171","msg":"trace[685410737] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:524; }","duration":"355.525474ms","start":"2026-08-14T09:29:41.304499Z","end":"2026-08-14T09:29:41.660025Z","steps":["trace[685410737] 'agreement among raft nodes before linearized reading' (duration: 355.496434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.304466Z","time spent":"355.595486ms","remote":"127.0.0.1:57132","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-08-14T09:29:41.660059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.186437Z","time spent":"473.567762ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:29:41.660109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.076986Z","time spent":"583.086066ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:29:41.660157Z","caller":"traceutil/trace.go:171","msg":"trace[2137054408] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"581.438987ms","start":"2026-08-14T09:29:41.078708Z","end":"2026-08-14T09:29:41.660147Z","steps":["trace[2137054408] 'process raft request' (duration: 580.272992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.078698Z","time spent":"581.476448ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:29:41.659803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.456491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:29:41.66023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.764551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-08-14T09:29:41.660246Z","caller":"traceutil/trace.go:171","msg":"trace[936463450] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:524; }","duration":"392.937076ms","start":"2026-08-14T09:29:41.267302Z","end":"2026-08-14T09:29:41.660239Z","steps":["trace[936463450] 'agreement among raft nodes before linearized reading' (duration: 392.471062ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:41.66025Z","caller":"traceutil/trace.go:171","msg":"trace[895616051] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:524; }","duration":"601.808202ms","start":"2026-08-14T09:29:41.058435Z","end":"2026-08-14T09:29:41.660243Z","steps":["trace[895616051] 'agreement among raft nodes before linearized reading' (duration: 601.73217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.267281Z","time spent":"392.979107ms","remote":"127.0.0.1:57126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:29:41.660269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.058422Z","time spent":"601.842374ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-14T09:29:41.660284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.73385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:41.66033Z","caller":"traceutil/trace.go:171","msg":"trace[481395041] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:524; }","duration":"356.818413ms","start":"2026-08-14T09:29:41.303497Z","end":"2026-08-14T09:29:41.660316Z","steps":["trace[481395041] 'agreement among raft nodes before linearized reading' (duration: 356.746881ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:41.660351Z","caller":"traceutil/trace.go:171","msg":"trace[1583653827] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"600.70317ms","start":"2026-08-14T09:29:41.059607Z","end":"2026-08-14T09:29:41.66031Z","steps":["trace[1583653827] 'process raft request' (duration: 599.222226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.303475Z","time spent":"356.889324ms","remote":"127.0.0.1:57138","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-08-14T09:29:41.659861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.541694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:41.660472Z","caller":"traceutil/trace.go:171","msg":"trace[1625842428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:524; }","duration":"393.191203ms","start":"2026-08-14T09:29:41.267271Z","end":"2026-08-14T09:29:41.660462Z","steps":["trace[1625842428] 'agreement among raft nodes before linearized reading' (duration: 392.558885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.059596Z","time spent":"600.784972ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:29:41.660543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.028179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:41.660565Z","caller":"traceutil/trace.go:171","msg":"trace[892873952] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:524; }","duration":"357.08778ms","start":"2026-08-14T09:29:41.303472Z","end":"2026-08-14T09:29:41.66056Z","steps":["trace[892873952] 'agreement among raft nodes before linearized reading' (duration: 357.054019ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:41.659972Z","caller":"traceutil/trace.go:171","msg":"trace[1784884896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"570.321006ms","start":"2026-08-14T09:29:41.089637Z","end":"2026-08-14T09:29:41.659958Z","steps":["trace[1784884896] 'agreement among raft nodes before linearized reading' (duration: 570.246834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.303453Z","time spent":"357.129162ms","remote":"127.0.0.1:57138","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-08-14T09:29:41.660512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.26725Z","time spent":"393.251436ms","remote":"127.0.0.1:57126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-08-14T09:29:41.660615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.089629Z","time spent":"570.974907ms","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:29:41.659798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.279886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-08-14T09:29:41.660746Z","caller":"traceutil/trace.go:171","msg":"trace[901456300] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:524; }","duration":"602.236385ms","start":"2026-08-14T09:29:41.058499Z","end":"2026-08-14T09:29:41.660735Z","steps":["trace[901456300] 'agreement among raft nodes before linearized reading' (duration: 601.093472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.058481Z","time spent":"602.311867ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-14T09:29:41.65984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.316844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:41.659917Z","caller":"traceutil/trace.go:171","msg":"trace[978995722] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:524; }","duration":"371.338755ms","start":"2026-08-14T09:29:41.288555Z","end":"2026-08-14T09:29:41.659893Z","steps":["trace[978995722] 'agreement among raft nodes before linearized reading' (duration: 371.071817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.288533Z","time spent":"372.384635ms","remote":"127.0.0.1:57132","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-08-14T09:29:41.660927Z","caller":"traceutil/trace.go:171","msg":"trace[435001754] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:524; }","duration":"372.411836ms","start":"2026-08-14T09:29:41.288501Z","end":"2026-08-14T09:29:41.660912Z","steps":["trace[435001754] 'agreement among raft nodes before linearized reading' (duration: 371.325844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.660962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.288488Z","time spent":"372.461958ms","remote":"127.0.0.1:57132","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-08-14T09:29:41.659976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.82556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-14T09:29:41.659937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.267744Z","time spent":"392.185554ms","remote":"127.0.0.1:57126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-08-14T09:29:41.661007Z","caller":"traceutil/trace.go:171","msg":"trace[1458645186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"577.865231ms","start":"2026-08-14T09:29:41.083129Z","end":"2026-08-14T09:29:41.660995Z","steps":["trace[1458645186] 'agreement among raft nodes before linearized reading' (duration: 576.82367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:41.661057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:41.083091Z","time spent":"577.954464ms","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:29:41.942812Z","caller":"traceutil/trace.go:171","msg":"trace[2063216004] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"274.746444ms","start":"2026-08-14T09:29:41.668026Z","end":"2026-08-14T09:29:41.942773Z","steps":["trace[2063216004] 'process raft request' (duration: 251.121002ms)","trace[2063216004] 'compare' (duration: 23.046165ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:29:41.942837Z","caller":"traceutil/trace.go:171","msg":"trace[835204767] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"266.89323ms","start":"2026-08-14T09:29:41.67592Z","end":"2026-08-14T09:29:41.942813Z","steps":["trace[835204767] 'process raft request' (duration: 266.772527ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:41.943021Z","caller":"traceutil/trace.go:171","msg":"trace[929007008] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"269.616941ms","start":"2026-08-14T09:29:41.673396Z","end":"2026-08-14T09:29:41.943013Z","steps":["trace[929007008] 'process raft request' (duration: 269.114056ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:41.943568Z","caller":"traceutil/trace.go:171","msg":"trace[33825616] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"263.84961ms","start":"2026-08-14T09:29:41.679708Z","end":"2026-08-14T09:29:41.943558Z","steps":["trace[33825616] 'process raft request' (duration: 263.381226ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:41.943548Z","caller":"traceutil/trace.go:171","msg":"trace[1107079786] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"200.273781ms","start":"2026-08-14T09:29:41.743245Z","end":"2026-08-14T09:29:41.943519Z","steps":["trace[1107079786] 'process raft request' (duration: 200.033234ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:41.943738Z","caller":"traceutil/trace.go:171","msg":"trace[1979693737] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"212.550615ms","start":"2026-08-14T09:29:41.731179Z","end":"2026-08-14T09:29:41.943729Z","steps":["trace[1979693737] 'process raft request' (duration: 212.01928ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:45.626882Z","caller":"traceutil/trace.go:171","msg":"trace[1240978026] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"192.818154ms","start":"2026-08-14T09:29:45.43404Z","end":"2026-08-14T09:29:45.626859Z","steps":["trace[1240978026] 'read index received' (duration: 191.152564ms)","trace[1240978026] 'applied index is now lower than readState.Index' (duration: 1.66466ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:45.627004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.948627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:45.627034Z","caller":"traceutil/trace.go:171","msg":"trace[967974459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"192.994509ms","start":"2026-08-14T09:29:45.434029Z","end":"2026-08-14T09:29:45.627024Z","steps":["trace[967974459] 'agreement among raft nodes before linearized reading' (duration: 192.928107ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:45.627403Z","caller":"traceutil/trace.go:171","msg":"trace[184861876] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"256.222222ms","start":"2026-08-14T09:29:45.371167Z","end":"2026-08-14T09:29:45.62739Z","steps":["trace[184861876] 'process raft request' (duration: 254.209003ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:47.446112Z","caller":"traceutil/trace.go:171","msg":"trace[793514538] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"212.369684ms","start":"2026-08-14T09:29:47.233705Z","end":"2026-08-14T09:29:47.446075Z","steps":["trace[793514538] 'process raft request' (duration: 210.926161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:48.814295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.759157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:29:48.814439Z","caller":"traceutil/trace.go:171","msg":"trace[182786038] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"242.251874ms","start":"2026-08-14T09:29:48.572166Z","end":"2026-08-14T09:29:48.814418Z","steps":["trace[182786038] 'process raft request' (duration: 64.964951ms)","trace[182786038] 'compare' (duration: 176.588983ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:49.522723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.580194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-14T09:29:49.522821Z","caller":"traceutil/trace.go:171","msg":"trace[1312741154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"498.719829ms","start":"2026-08-14T09:29:49.02408Z","end":"2026-08-14T09:29:49.5228Z","steps":["trace[1312741154] 'range keys from in-memory index tree' (duration: 498.344457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:49.522869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:49.024066Z","time spent":"498.792841ms","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:29:49.522754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.192227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tjqjl\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-14T09:29:49.523203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.592335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-14T09:29:49.523201Z","caller":"traceutil/trace.go:171","msg":"trace[1590787793] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tjqjl; range_end:; response_count:1; response_revision:604; }","duration":"399.685372ms","start":"2026-08-14T09:29:49.123479Z","end":"2026-08-14T09:29:49.523164Z","steps":["trace[1590787793] 'range keys from in-memory index tree' (duration: 398.98324ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:49.523272Z","caller":"traceutil/trace.go:171","msg":"trace[833163315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"499.721578ms","start":"2026-08-14T09:29:49.023531Z","end":"2026-08-14T09:29:49.523252Z","steps":["trace[833163315] 'range keys from in-memory index tree' (duration: 499.392438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:49.523293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:49.123438Z","time spent":"399.830546ms","remote":"127.0.0.1:48286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-tjqjl\" "} {"level":"warn","ts":"2026-08-14T09:29:49.523307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:49.023508Z","time spent":"499.790871ms","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:29:49.567638Z","caller":"traceutil/trace.go:171","msg":"trace[1589294999] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"134.139484ms","start":"2026-08-14T09:29:49.433475Z","end":"2026-08-14T09:29:49.567614Z","steps":["trace[1589294999] 'read index received' (duration: 133.474414ms)","trace[1589294999] 'applied index is now lower than readState.Index' (duration: 663.82µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:49.567788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.295538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:49.568001Z","caller":"traceutil/trace.go:171","msg":"trace[1541919699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"134.549416ms","start":"2026-08-14T09:29:49.433439Z","end":"2026-08-14T09:29:49.567989Z","steps":["trace[1541919699] 'agreement among raft nodes before linearized reading' (duration: 134.270408ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:49.568141Z","caller":"traceutil/trace.go:171","msg":"trace[644584993] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"328.89346ms","start":"2026-08-14T09:29:49.238988Z","end":"2026-08-14T09:29:49.567881Z","steps":["trace[644584993] 'process raft request' (duration: 328.081536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:49.56844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:49.23896Z","time spent":"329.355794ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:29:50.236739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335128475548443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:29:50.720494Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.002984865s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:29:50.7904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.49792ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:50.790539Z","caller":"traceutil/trace.go:171","msg":"trace[2031362872] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:608; }","duration":"542.645435ms","start":"2026-08-14T09:29:50.24787Z","end":"2026-08-14T09:29:50.790516Z","steps":["trace[2031362872] 'range keys from in-memory index tree' (duration: 542.47713ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:29:50.79116Z","caller":"traceutil/trace.go:171","msg":"trace[1405359644] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.155319396s","start":"2026-08-14T09:29:49.635795Z","end":"2026-08-14T09:29:50.791115Z","steps":["trace[1405359644] 'process raft request' (duration: 1.084895006s)","trace[1405359644] 'compare' (duration: 69.572655ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:50.791383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:49.635767Z","time spent":"1.155527833s","remote":"127.0.0.1:48340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:29:50.806143Z","caller":"traceutil/trace.go:171","msg":"trace[1613851176] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"1.070055276s","start":"2026-08-14T09:29:49.736063Z","end":"2026-08-14T09:29:50.806118Z","steps":["trace[1613851176] 'read index received' (duration: 984.640464ms)","trace[1613851176] 'applied index is now lower than readState.Index' (duration: 85.413482ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:50.80641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070327514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T09:29:50.806443Z","caller":"traceutil/trace.go:171","msg":"trace[864154319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.070424848s","start":"2026-08-14T09:29:49.736009Z","end":"2026-08-14T09:29:50.806434Z","steps":["trace[864154319] 'agreement among raft nodes before linearized reading' (duration: 1.070237662s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:50.806473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:49.735988Z","time spent":"1.070479179s","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T09:29:50.806683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.103188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-14T09:29:50.806775Z","caller":"traceutil/trace.go:171","msg":"trace[542475537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"824.258292ms","start":"2026-08-14T09:29:49.98249Z","end":"2026-08-14T09:29:50.806748Z","steps":["trace[542475537] 'agreement among raft nodes before linearized reading' (duration: 823.976484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:50.806829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:49.982457Z","time spent":"824.358555ms","remote":"127.0.0.1:48382","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-08-14T09:29:50.807034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.52236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:50.807069Z","caller":"traceutil/trace.go:171","msg":"trace[1082783063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"373.594272ms","start":"2026-08-14T09:29:50.433464Z","end":"2026-08-14T09:29:50.807059Z","steps":["trace[1082783063] 'agreement among raft nodes before linearized reading' (duration: 373.540101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:50.807098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:29:50.433439Z","time spent":"373.651534ms","remote":"127.0.0.1:48108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:29:56.233988Z","caller":"traceutil/trace.go:171","msg":"trace[326386517] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"108.304856ms","start":"2026-08-14T09:29:56.125658Z","end":"2026-08-14T09:29:56.233963Z","steps":["trace[326386517] 'read index received' (duration: 107.994396ms)","trace[326386517] 'applied index is now lower than readState.Index' (duration: 309.51µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:29:56.234378Z","caller":"traceutil/trace.go:171","msg":"trace[907631462] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"211.481832ms","start":"2026-08-14T09:29:56.022857Z","end":"2026-08-14T09:29:56.234338Z","steps":["trace[907631462] 'process raft request' (duration: 210.865584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:56.234574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.893904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-08-14T09:29:56.234642Z","caller":"traceutil/trace.go:171","msg":"trace[2086766919] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"109.005747ms","start":"2026-08-14T09:29:56.125614Z","end":"2026-08-14T09:29:56.23462Z","steps":["trace[2086766919] 'agreement among raft nodes before linearized reading' (duration: 108.75609ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:30:03.145257Z","caller":"traceutil/trace.go:171","msg":"trace[667599692] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"255.259829ms","start":"2026-08-14T09:30:02.889955Z","end":"2026-08-14T09:30:03.145215Z","steps":["trace[667599692] 'read index received' (duration: 255.016012ms)","trace[667599692] 'applied index is now lower than readState.Index' (duration: 242.457µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:30:03.145415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.440115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-14T09:30:03.145516Z","caller":"traceutil/trace.go:171","msg":"trace[843793293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"255.501926ms","start":"2026-08-14T09:30:02.889934Z","end":"2026-08-14T09:30:03.145436Z","steps":["trace[843793293] 'agreement among raft nodes before linearized reading' (duration: 255.380533ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:30:03.145554Z","caller":"traceutil/trace.go:171","msg":"trace[1188661004] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"257.630959ms","start":"2026-08-14T09:30:02.887727Z","end":"2026-08-14T09:30:03.145358Z","steps":["trace[1188661004] 'process raft request' (duration: 257.304719ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:30:05.848153Z","caller":"traceutil/trace.go:171","msg":"trace[526891150] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"136.670379ms","start":"2026-08-14T09:30:05.711455Z","end":"2026-08-14T09:30:05.848125Z","steps":["trace[526891150] 'process raft request' (duration: 132.405624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.726554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115202507s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:30:08.726773Z","caller":"traceutil/trace.go:171","msg":"trace[1480361967] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.354199941s","start":"2026-08-14T09:30:07.372558Z","end":"2026-08-14T09:30:08.726758Z","steps":["trace[1480361967] 'process raft request' (duration: 1.354089408s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.726844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:07.372538Z","time spent":"1.354274323s","remote":"127.0.0.1:48580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:30:08.72683Z","caller":"traceutil/trace.go:171","msg":"trace[1692888447] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"1.356939832s","start":"2026-08-14T09:30:07.369862Z","end":"2026-08-14T09:30:08.726802Z","steps":["trace[1692888447] 'process raft request' (duration: 241.406365ms)","trace[1692888447] 'compare' (duration: 1.114895648s)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:30:08.726982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:07.369848Z","time spent":"1.357049964s","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:30:08.738259Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.127008071s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T09:30:08.738668Z","caller":"traceutil/trace.go:171","msg":"trace[810302113] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"1.332635341s","start":"2026-08-14T09:30:07.406019Z","end":"2026-08-14T09:30:08.738655Z","steps":["trace[810302113] 'process raft request' (duration: 1.332436535s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:30:08.738735Z","caller":"traceutil/trace.go:171","msg":"trace[1757171082] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.328036457s","start":"2026-08-14T09:30:07.410681Z","end":"2026-08-14T09:30:08.738717Z","steps":["trace[1757171082] 'process raft request' (duration: 1.327912733s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.738757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303644533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:30:08.738833Z","caller":"traceutil/trace.go:171","msg":"trace[1328627593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"1.303768517s","start":"2026-08-14T09:30:07.435053Z","end":"2026-08-14T09:30:08.738821Z","steps":["trace[1328627593] 'agreement among raft nodes before linearized reading' (duration: 1.303639403s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.738777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:07.405954Z","time spent":"1.332769155s","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:30:08.738895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:07.435029Z","time spent":"1.303828179s","remote":"127.0.0.1:48108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:30:08.738661Z","caller":"traceutil/trace.go:171","msg":"trace[1473913113] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:804; }","duration":"1.30352088s","start":"2026-08-14T09:30:07.435113Z","end":"2026-08-14T09:30:08.738634Z","steps":["trace[1473913113] 'read index received' (duration: 176.168749ms)","trace[1473913113] 'applied index is now lower than readState.Index' (duration: 1.127351291s)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:30:08.738856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:07.410653Z","time spent":"1.32813739s","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:30:08.77145Z","caller":"traceutil/trace.go:171","msg":"trace[568491802] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"519.871873ms","start":"2026-08-14T09:30:08.251555Z","end":"2026-08-14T09:30:08.771426Z","steps":["trace[568491802] 'process raft request' (duration: 519.477411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.771687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:08.251529Z","time spent":"520.083668ms","remote":"127.0.0.1:48340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:30:08.771949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.521927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:30:08.771993Z","caller":"traceutil/trace.go:171","msg":"trace[6495175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"920.602519ms","start":"2026-08-14T09:30:07.85138Z","end":"2026-08-14T09:30:08.771982Z","steps":["trace[6495175] 'agreement among raft nodes before linearized reading' (duration: 920.259299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.772019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:07.851361Z","time spent":"920.65209ms","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T09:30:08.772099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.662916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:30:08.772172Z","caller":"traceutil/trace.go:171","msg":"trace[1509801629] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:777; }","duration":"318.780219ms","start":"2026-08-14T09:30:08.453378Z","end":"2026-08-14T09:30:08.772158Z","steps":["trace[1509801629] 'agreement among raft nodes before linearized reading' (duration: 318.327746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.77227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:08.453354Z","time spent":"318.893933ms","remote":"127.0.0.1:48272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-14T09:30:08.772332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.341435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-14T09:30:08.772394Z","caller":"traceutil/trace.go:171","msg":"trace[1112021731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"471.439718ms","start":"2026-08-14T09:30:08.300938Z","end":"2026-08-14T09:30:08.772378Z","steps":["trace[1112021731] 'agreement among raft nodes before linearized reading' (duration: 470.788709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.772433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:08.300925Z","time spent":"471.49861ms","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T09:30:08.772447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060957911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-14T09:30:08.772492Z","caller":"traceutil/trace.go:171","msg":"trace[1630953951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"1.061041264s","start":"2026-08-14T09:30:07.711442Z","end":"2026-08-14T09:30:08.772483Z","steps":["trace[1630953951] 'agreement among raft nodes before linearized reading' (duration: 1.060312362s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:30:08.77252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:30:07.71142Z","time spent":"1.061092385s","remote":"127.0.0.1:48382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T09:30:23.60551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.459019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:30:23.606077Z","caller":"traceutil/trace.go:171","msg":"trace[678935370] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"259.589669ms","start":"2026-08-14T09:30:23.34646Z","end":"2026-08-14T09:30:23.606049Z","steps":["trace[678935370] 'process raft request' (duration: 109.45ms)","trace[678935370] 'compare' (duration: 149.092019ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:30:23.606094Z","caller":"traceutil/trace.go:171","msg":"trace[906522155] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:985; }","duration":"192.115675ms","start":"2026-08-14T09:30:23.413944Z","end":"2026-08-14T09:30:23.60606Z","steps":["trace[906522155] 'read index received' (duration: 41.977006ms)","trace[906522155] 'applied index is now lower than readState.Index' (duration: 150.136369ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:30:23.6065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.538597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-14T09:30:23.606562Z","caller":"traceutil/trace.go:171","msg":"trace[897712072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"192.653311ms","start":"2026-08-14T09:30:23.413893Z","end":"2026-08-14T09:30:23.606547Z","steps":["trace[897712072] 'agreement among raft nodes before linearized reading' (duration: 192.324811ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:30:23.606827Z","caller":"traceutil/trace.go:171","msg":"trace[500801962] transaction","detail":"{read_only:false; number_of_response:1; response_revision:951; }","duration":"243.829843ms","start":"2026-08-14T09:30:23.362983Z","end":"2026-08-14T09:30:23.606813Z","steps":["trace[500801962] 'process raft request' (duration: 242.645849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:32:10.097499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.361899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:32:10.097644Z","caller":"traceutil/trace.go:171","msg":"trace[637232977] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"148.063269ms","start":"2026-08-14T09:32:09.949557Z","end":"2026-08-14T09:32:10.09762Z","steps":["trace[637232977] 'process raft request' (duration: 19.504675ms)","trace[637232977] 'compare' (duration: 128.242985ms)"],"step_count":2}