{"level":"warn","ts":"2026-04-28T07:15:24.847543Z","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-28T07:15:24.847656Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.146: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.146:2380","--initial-cluster=instance=https://199.19.213.146:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.146:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.146: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-28T07:15:24.847742Z","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-28T07:15:24.847754Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-04-28T07:15:24.847773Z","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-28T07:15:24.848473Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"]} {"level":"info","ts":"2026-04-28T07:15:24.848546Z","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.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.146: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-28T07:15:24.85269Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.895844ms"} {"level":"info","ts":"2026-04-28T07:15:24.863743Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c8e9c1aa9bb537b7","cluster-id":"933c0a8774274c15"} {"level":"info","ts":"2026-04-28T07:15:24.863818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T07:15:24.863834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 0"} {"level":"info","ts":"2026-04-28T07:15:24.863844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c8e9c1aa9bb537b7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T07:15:24.86385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 1"} {"level":"info","ts":"2026-04-28T07:15:24.863885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"warn","ts":"2026-04-28T07:15:24.87118Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T07:15:24.872529Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T07:15:24.873736Z","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-28T07:15:24.876021Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c8e9c1aa9bb537b7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T07:15:24.883111Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c8e9c1aa9bb537b7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T07:15:24.883229Z","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-28T07:15:24.88328Z","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-28T07:15:24.883288Z","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-28T07:15:24.884166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"info","ts":"2026-04-28T07:15:24.884273Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","added-peer-id":"c8e9c1aa9bb537b7","added-peer-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-04-28T07:15:24.884402Z","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-28T07:15:24.884802Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c8e9c1aa9bb537b7","initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T07:15:24.884838Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T07:15:24.884916Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-04-28T07:15:24.884939Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-04-28T07:15:25.764303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T07:15:25.764366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T07:15:25.764392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgPreVoteResp from c8e9c1aa9bb537b7 at term 1"} {"level":"info","ts":"2026-04-28T07:15:25.764405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became candidate at term 2"} {"level":"info","ts":"2026-04-28T07:15:25.76441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgVoteResp from c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-04-28T07:15:25.76442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became leader at term 2"} {"level":"info","ts":"2026-04-28T07:15:25.764429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c8e9c1aa9bb537b7 elected leader c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-04-28T07:15:25.766607Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c8e9c1aa9bb537b7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.146:2379]}","request-path":"/0/members/c8e9c1aa9bb537b7/attributes","cluster-id":"933c0a8774274c15","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T07:15:25.766616Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:15:25.766837Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:15:25.767266Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T07:15:25.76729Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T07:15:25.770001Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T07:15:25.77019Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:15:25.776187Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:15:25.776339Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:15:25.77639Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:15:25.782214Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.146:2379"} {"level":"info","ts":"2026-04-28T07:15:27.518628Z","caller":"traceutil/trace.go:171","msg":"trace[1359743026] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"136.792584ms","start":"2026-04-28T07:15:27.381804Z","end":"2026-04-28T07:15:27.518597Z","steps":["trace[1359743026] 'process raft request' (duration: 85.560431ms)","trace[1359743026] 'compare' (duration: 50.997227ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:15:27.518989Z","caller":"traceutil/trace.go:171","msg":"trace[1299299875] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"136.558008ms","start":"2026-04-28T07:15:27.38239Z","end":"2026-04-28T07:15:27.518948Z","steps":["trace[1299299875] 'process raft request' (duration: 136.163701ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:27.519438Z","caller":"traceutil/trace.go:171","msg":"trace[438925112] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"133.737024ms","start":"2026-04-28T07:15:27.385669Z","end":"2026-04-28T07:15:27.519406Z","steps":["trace[438925112] 'process raft request' (duration: 133.204508ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:28.984306Z","caller":"traceutil/trace.go:171","msg":"trace[526006924] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"128.748557ms","start":"2026-04-28T07:15:28.855527Z","end":"2026-04-28T07:15:28.984275Z","steps":["trace[526006924] 'process raft request' (duration: 128.581835ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:28.985195Z","caller":"traceutil/trace.go:171","msg":"trace[891574663] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"127.065678ms","start":"2026-04-28T07:15:28.858104Z","end":"2026-04-28T07:15:28.98517Z","steps":["trace[891574663] 'process raft request' (duration: 126.800801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:34.846984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.301217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-28T07:15:34.847018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.978517ms","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":"warn","ts":"2026-04-28T07:15:34.847016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.612847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-28T07:15:34.847083Z","caller":"traceutil/trace.go:171","msg":"trace[237730419] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:337; }","duration":"312.442392ms","start":"2026-04-28T07:15:34.534618Z","end":"2026-04-28T07:15:34.84706Z","steps":["trace[237730419] 'range keys from in-memory index tree' (duration: 312.208121ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:34.847121Z","caller":"traceutil/trace.go:171","msg":"trace[1852393628] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:337; }","duration":"261.786371ms","start":"2026-04-28T07:15:34.585311Z","end":"2026-04-28T07:15:34.847098Z","steps":["trace[1852393628] 'range keys from in-memory index tree' (duration: 261.444763ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:34.847126Z","caller":"traceutil/trace.go:171","msg":"trace[1388401279] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:337; }","duration":"112.125032ms","start":"2026-04-28T07:15:34.734964Z","end":"2026-04-28T07:15:34.847089Z","steps":["trace[1388401279] 'range keys from in-memory index tree' (duration: 111.873771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:34.847129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.534597Z","time spent":"312.523089ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-28T07:15:34.84714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.502298ms","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-04-28T07:15:34.847213Z","caller":"traceutil/trace.go:171","msg":"trace[1142310007] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:337; }","duration":"411.683991ms","start":"2026-04-28T07:15:34.435513Z","end":"2026-04-28T07:15:34.847197Z","steps":["trace[1142310007] 'range keys from in-memory index tree' (duration: 411.395061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:34.846984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.001807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-28T07:15:34.847273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.435491Z","time spent":"411.766847ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-04-28T07:15:34.847301Z","caller":"traceutil/trace.go:171","msg":"trace[1314024947] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:337; }","duration":"212.385422ms","start":"2026-04-28T07:15:34.634902Z","end":"2026-04-28T07:15:34.847288Z","steps":["trace[1314024947] 'range keys from in-memory index tree' (duration: 211.853873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:34.847336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.148062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-28T07:15:34.847348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.088268ms","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-04-28T07:15:34.847378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.070071ms","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-04-28T07:15:34.847381Z","caller":"traceutil/trace.go:171","msg":"trace[38751163] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:337; }","duration":"162.165625ms","start":"2026-04-28T07:15:34.685205Z","end":"2026-04-28T07:15:34.847371Z","steps":["trace[38751163] 'range keys from in-memory index tree' (duration: 161.951944ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:34.847382Z","caller":"traceutil/trace.go:171","msg":"trace[20722234] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:337; }","duration":"511.235788ms","start":"2026-04-28T07:15:34.336129Z","end":"2026-04-28T07:15:34.847365Z","steps":["trace[20722234] 'range keys from in-memory index tree' (duration: 511.036816ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:34.847398Z","caller":"traceutil/trace.go:171","msg":"trace[1328846065] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:337; }","duration":"462.143678ms","start":"2026-04-28T07:15:34.385248Z","end":"2026-04-28T07:15:34.847391Z","steps":["trace[1328846065] 'range keys from in-memory index tree' (duration: 461.942717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:34.84742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.385213Z","time spent":"462.202946ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-28T07:15:34.847421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.336103Z","time spent":"511.308276ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-28T07:15:34.847482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.791606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T07:15:34.847494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.07149ms","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-28T07:15:34.847513Z","caller":"traceutil/trace.go:171","msg":"trace[1140907043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:337; }","duration":"390.126198ms","start":"2026-04-28T07:15:34.457379Z","end":"2026-04-28T07:15:34.847505Z","steps":["trace[1140907043] 'range keys from in-memory index tree' (duration: 389.962534ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:34.847511Z","caller":"traceutil/trace.go:171","msg":"trace[1227675097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:337; }","duration":"406.897632ms","start":"2026-04-28T07:15:34.440604Z","end":"2026-04-28T07:15:34.847502Z","steps":["trace[1227675097] 'range keys from in-memory index tree' (duration: 406.668371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:34.847531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.457354Z","time spent":"390.172536ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T07:15:34.847541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.440575Z","time spent":"406.95668ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:15:34.846858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.83626ms","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-04-28T07:15:34.84767Z","caller":"traceutil/trace.go:171","msg":"trace[813713718] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:337; }","duration":"362.734885ms","start":"2026-04-28T07:15:34.484924Z","end":"2026-04-28T07:15:34.847659Z","steps":["trace[813713718] 'range keys from in-memory index tree' (duration: 361.704905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:34.848034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.484903Z","time spent":"363.086793ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-04-28T07:15:35.386566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851122141741589,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T07:15:35.472072Z","caller":"traceutil/trace.go:171","msg":"trace[748626341] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"620.818426ms","start":"2026-04-28T07:15:34.851196Z","end":"2026-04-28T07:15:35.472014Z","steps":["trace[748626341] 'process raft request' (duration: 620.639042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.472769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.851174Z","time spent":"620.98845ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:15:35.780737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.325499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:15:35.780926Z","caller":"traceutil/trace.go:171","msg":"trace[1160568921] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:346; }","duration":"895.101548ms","start":"2026-04-28T07:15:34.885808Z","end":"2026-04-28T07:15:35.780909Z","steps":["trace[1160568921] 'read index received' (duration: 585.933438ms)","trace[1160568921] 'applied index is now lower than readState.Index' (duration: 309.16726ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:15:35.780979Z","caller":"traceutil/trace.go:171","msg":"trace[1390620132] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"927.841126ms","start":"2026-04-28T07:15:34.853085Z","end":"2026-04-28T07:15:35.780926Z","steps":["trace[1390620132] 'process raft request' (duration: 714.113672ms)","trace[1390620132] 'compare' (duration: 213.178834ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:15:35.781069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.027732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-04-28T07:15:35.781105Z","caller":"traceutil/trace.go:171","msg":"trace[1997750830] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:340; }","duration":"891.099589ms","start":"2026-04-28T07:15:34.889995Z","end":"2026-04-28T07:15:35.781094Z","steps":["trace[1997750830] 'agreement among raft nodes before linearized reading' (duration: 891.035781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.88997Z","time spent":"891.165887ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5360,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-28T07:15:35.781134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.28441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-28T07:15:35.781173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.886589ms","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-04-28T07:15:35.781209Z","caller":"traceutil/trace.go:171","msg":"trace[1108864408] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:340; }","duration":"895.424086ms","start":"2026-04-28T07:15:34.885765Z","end":"2026-04-28T07:15:35.781189Z","steps":["trace[1108864408] 'agreement among raft nodes before linearized reading' (duration: 895.217723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.894821ms","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-28T07:15:35.781237Z","caller":"traceutil/trace.go:171","msg":"trace[823069637] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:340; }","duration":"644.986445ms","start":"2026-04-28T07:15:35.13623Z","end":"2026-04-28T07:15:35.781216Z","steps":["trace[823069637] 'agreement among raft nodes before linearized reading' (duration: 644.858699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.885742Z","time spent":"895.503683ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-04-28T07:15:35.781271Z","caller":"traceutil/trace.go:171","msg":"trace[647310835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:340; }","duration":"546.005777ms","start":"2026-04-28T07:15:35.235247Z","end":"2026-04-28T07:15:35.781253Z","steps":["trace[647310835] 'agreement among raft nodes before linearized reading' (duration: 545.884172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.386151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-28T07:15:35.781287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:35.136211Z","time spent":"645.066672ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-28T07:15:35.781307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:35.235215Z","time spent":"546.083535ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-04-28T07:15:35.781322Z","caller":"traceutil/trace.go:171","msg":"trace[232670008] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:340; }","duration":"595.473538ms","start":"2026-04-28T07:15:35.185831Z","end":"2026-04-28T07:15:35.781304Z","steps":["trace[232670008] 'agreement among raft nodes before linearized reading' (duration: 595.373381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.15091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-28T07:15:35.781372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:35.185812Z","time spent":"595.550814ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-04-28T07:15:35.781396Z","caller":"traceutil/trace.go:171","msg":"trace[1022307945] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:340; }","duration":"695.222366ms","start":"2026-04-28T07:15:35.086166Z","end":"2026-04-28T07:15:35.781389Z","steps":["trace[1022307945] 'agreement among raft nodes before linearized reading' (duration: 695.160079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:35.086141Z","time spent":"695.286755ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-28T07:15:35.781455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.843127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-28T07:15:35.781486Z","caller":"traceutil/trace.go:171","msg":"trace[2072847412] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:340; }","duration":"496.912584ms","start":"2026-04-28T07:15:35.284563Z","end":"2026-04-28T07:15:35.781475Z","steps":["trace[2072847412] 'agreement among raft nodes before linearized reading' (duration: 496.854886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.039422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"warn","ts":"2026-04-28T07:15:35.781516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.161958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4664"} {"level":"warn","ts":"2026-04-28T07:15:35.781518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.33369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T07:15:35.781532Z","caller":"traceutil/trace.go:171","msg":"trace[1468340889] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:340; }","duration":"891.11287ms","start":"2026-04-28T07:15:34.890408Z","end":"2026-04-28T07:15:35.781521Z","steps":["trace[1468340889] 'agreement among raft nodes before linearized reading' (duration: 891.015113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:35.284541Z","time spent":"496.975612ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-04-28T07:15:35.781561Z","caller":"traceutil/trace.go:171","msg":"trace[1685250405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:340; }","duration":"618.404847ms","start":"2026-04-28T07:15:35.163145Z","end":"2026-04-28T07:15:35.78155Z","steps":["trace[1685250405] 'agreement among raft nodes before linearized reading' (duration: 618.337049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.469576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-28T07:15:35.781593Z","caller":"traceutil/trace.go:171","msg":"trace[1341840326] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:340; }","duration":"891.502715ms","start":"2026-04-28T07:15:34.890085Z","end":"2026-04-28T07:15:35.781588Z","steps":["trace[1341840326] 'agreement among raft nodes before linearized reading' (duration: 891.459877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:35.163122Z","time spent":"618.467475ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:15:35.78159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.508837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"warn","ts":"2026-04-28T07:15:35.781619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.89008Z","time spent":"891.534263ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6989,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-04-28T07:15:35.78164Z","caller":"traceutil/trace.go:171","msg":"trace[621849582] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:340; }","duration":"167.585975ms","start":"2026-04-28T07:15:35.61404Z","end":"2026-04-28T07:15:35.781626Z","steps":["trace[621849582] 'agreement among raft nodes before linearized reading' (duration: 167.509178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.855279ms","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-04-28T07:15:35.781697Z","caller":"traceutil/trace.go:171","msg":"trace[66016596] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:340; }","duration":"795.926447ms","start":"2026-04-28T07:15:34.985761Z","end":"2026-04-28T07:15:35.781687Z","steps":["trace[66016596] 'agreement among raft nodes before linearized reading' (duration: 795.876068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.315432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-04-28T07:15:35.781726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.64366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-04-28T07:15:35.781755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.98574Z","time spent":"795.994834ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-04-28T07:15:35.781775Z","caller":"traceutil/trace.go:171","msg":"trace[1460686817] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:340; }","duration":"445.93925ms","start":"2026-04-28T07:15:35.335823Z","end":"2026-04-28T07:15:35.781763Z","steps":["trace[1460686817] 'agreement among raft nodes before linearized reading' (duration: 445.298363ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:35.781784Z","caller":"traceutil/trace.go:171","msg":"trace[32948390] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:340; }","duration":"891.711688ms","start":"2026-04-28T07:15:34.890061Z","end":"2026-04-28T07:15:35.781773Z","steps":["trace[32948390] 'agreement among raft nodes before linearized reading' (duration: 891.623141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:35.335801Z","time spent":"446.008367ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-28T07:15:35.781833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.890056Z","time spent":"891.769436ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7327,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-04-28T07:15:35.781533Z","caller":"traceutil/trace.go:171","msg":"trace[752335225] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:340; }","duration":"891.204316ms","start":"2026-04-28T07:15:34.890324Z","end":"2026-04-28T07:15:35.781528Z","steps":["trace[752335225] 'agreement among raft nodes before linearized reading' (duration: 891.165938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.143029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-04-28T07:15:35.781904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.890309Z","time spent":"891.588182ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4688,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-28T07:15:35.781617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.901432ms","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-04-28T07:15:35.78192Z","caller":"traceutil/trace.go:171","msg":"trace[1353933047] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:340; }","duration":"891.175457ms","start":"2026-04-28T07:15:34.89074Z","end":"2026-04-28T07:15:35.781915Z","steps":["trace[1353933047] 'agreement among raft nodes before linearized reading' (duration: 891.127819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.78194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.890736Z","time spent":"891.200297ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-28T07:15:35.781945Z","caller":"traceutil/trace.go:171","msg":"trace[274232554] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:340; }","duration":"746.266619ms","start":"2026-04-28T07:15:35.035666Z","end":"2026-04-28T07:15:35.781932Z","steps":["trace[274232554] 'agreement among raft nodes before linearized reading' (duration: 745.917481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.78199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:35.035645Z","time spent":"746.325636ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-28T07:15:35.782053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.173494ms","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-04-28T07:15:35.782075Z","caller":"traceutil/trace.go:171","msg":"trace[1296545999] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:340; }","duration":"846.225611ms","start":"2026-04-28T07:15:34.935844Z","end":"2026-04-28T07:15:35.78207Z","steps":["trace[1296545999] 'agreement among raft nodes before linearized reading' (duration: 846.186302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.781564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.890387Z","time spent":"891.169437ms","remote":"127.0.0.1:34870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4221,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-28T07:15:35.782095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.935826Z","time spent":"846.26448ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-04-28T07:15:35.781033Z","caller":"traceutil/trace.go:171","msg":"trace[1789308079] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"925.481116ms","start":"2026-04-28T07:15:34.855512Z","end":"2026-04-28T07:15:35.780993Z","steps":["trace[1789308079] 'process raft request' (duration: 925.337481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:35.782723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.855502Z","time spent":"926.930974ms","remote":"127.0.0.1:34966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:15:35.789239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:34.853063Z","time spent":"928.03859ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:15:49.813608Z","caller":"traceutil/trace.go:171","msg":"trace[1324887345] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"250.750224ms","start":"2026-04-28T07:15:49.562821Z","end":"2026-04-28T07:15:49.813572Z","steps":["trace[1324887345] 'process raft request' (duration: 250.562657ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:51.446452Z","caller":"traceutil/trace.go:171","msg":"trace[860558181] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"212.325227ms","start":"2026-04-28T07:15:51.234102Z","end":"2026-04-28T07:15:51.446427Z","steps":["trace[860558181] 'process raft request' (duration: 212.278508ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:51.446576Z","caller":"traceutil/trace.go:171","msg":"trace[836683422] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"212.634424ms","start":"2026-04-28T07:15:51.233892Z","end":"2026-04-28T07:15:51.446526Z","steps":["trace[836683422] 'process raft request' (duration: 179.804951ms)","trace[836683422] 'compare' (duration: 32.509387ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:15:51.693639Z","caller":"traceutil/trace.go:171","msg":"trace[82497510] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"107.462788ms","start":"2026-04-28T07:15:51.586147Z","end":"2026-04-28T07:15:51.69361Z","steps":["trace[82497510] 'process raft request' (duration: 107.065322ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:51.741868Z","caller":"traceutil/trace.go:171","msg":"trace[294196997] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"124.044741ms","start":"2026-04-28T07:15:51.617789Z","end":"2026-04-28T07:15:51.741834Z","steps":["trace[294196997] 'process raft request' (duration: 121.018659ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:51.741924Z","caller":"traceutil/trace.go:171","msg":"trace[1923703829] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:505; }","duration":"122.652838ms","start":"2026-04-28T07:15:51.619246Z","end":"2026-04-28T07:15:51.741899Z","steps":["trace[1923703829] 'read index received' (duration: 73.895613ms)","trace[1923703829] 'applied index is now lower than readState.Index' (duration: 48.756115ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:15:51.742036Z","caller":"traceutil/trace.go:171","msg":"trace[1726070870] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"115.885782ms","start":"2026-04-28T07:15:51.626099Z","end":"2026-04-28T07:15:51.741985Z","steps":["trace[1726070870] 'process raft request' (duration: 115.709555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:51.742397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.147573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-04-28T07:15:51.742457Z","caller":"traceutil/trace.go:171","msg":"trace[733447718] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:492; }","duration":"123.263912ms","start":"2026-04-28T07:15:51.619175Z","end":"2026-04-28T07:15:51.742439Z","steps":["trace[733447718] 'agreement among raft nodes before linearized reading' (duration: 123.064094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:51.742587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.490046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4632"} {"level":"info","ts":"2026-04-28T07:15:51.742713Z","caller":"traceutil/trace.go:171","msg":"trace[1657686159] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:492; }","duration":"120.713753ms","start":"2026-04-28T07:15:51.621913Z","end":"2026-04-28T07:15:51.742627Z","steps":["trace[1657686159] 'agreement among raft nodes before linearized reading' (duration: 120.378498ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:53.655311Z","caller":"traceutil/trace.go:171","msg":"trace[1047936519] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"386.598792ms","start":"2026-04-28T07:15:53.268675Z","end":"2026-04-28T07:15:53.655274Z","steps":["trace[1047936519] 'process raft request' (duration: 386.383684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:53.658771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:53.268644Z","time spent":"386.783799ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:15:53.668125Z","caller":"traceutil/trace.go:171","msg":"trace[107734460] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"393.062248ms","start":"2026-04-28T07:15:53.275033Z","end":"2026-04-28T07:15:53.668095Z","steps":["trace[107734460] 'process raft request' (duration: 392.81663ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:53.668196Z","caller":"traceutil/trace.go:171","msg":"trace[258319039] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"380.238924ms","start":"2026-04-28T07:15:53.287949Z","end":"2026-04-28T07:15:53.668187Z","steps":["trace[258319039] 'process raft request' (duration: 380.145825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:53.668267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:53.275006Z","time spent":"393.194077ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:15:53.668256Z","caller":"traceutil/trace.go:171","msg":"trace[38615135] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"282.559944ms","start":"2026-04-28T07:15:53.38566Z","end":"2026-04-28T07:15:53.66822Z","steps":["trace[38615135] 'process raft request' (duration: 282.467325ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:53.668265Z","caller":"traceutil/trace.go:171","msg":"trace[67450760] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"383.230125ms","start":"2026-04-28T07:15:53.284979Z","end":"2026-04-28T07:15:53.668209Z","steps":["trace[67450760] 'process raft request' (duration: 383.084196ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:53.668148Z","caller":"traceutil/trace.go:171","msg":"trace[1425560844] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"392.94358ms","start":"2026-04-28T07:15:53.275189Z","end":"2026-04-28T07:15:53.668133Z","steps":["trace[1425560844] 'process raft request' (duration: 392.801151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:53.668388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:53.275166Z","time spent":"393.199277ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:15:53.668424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:53.284954Z","time spent":"383.393043ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:15:53.668425Z","caller":"traceutil/trace.go:171","msg":"trace[1993674339] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"273.358894ms","start":"2026-04-28T07:15:53.394888Z","end":"2026-04-28T07:15:53.668247Z","steps":["trace[1993674339] 'process raft request' (duration: 273.266775ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:53.668515Z","caller":"traceutil/trace.go:171","msg":"trace[23859649] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"242.980813ms","start":"2026-04-28T07:15:53.425529Z","end":"2026-04-28T07:15:53.66851Z","steps":["trace[23859649] 'process raft request' (duration: 242.793925ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:53.668522Z","caller":"traceutil/trace.go:171","msg":"trace[1991168923] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"261.53246ms","start":"2026-04-28T07:15:53.406978Z","end":"2026-04-28T07:15:53.66851Z","steps":["trace[1991168923] 'process raft request' (duration: 261.242603ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:53.66852Z","caller":"traceutil/trace.go:171","msg":"trace[567512158] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"257.53618ms","start":"2026-04-28T07:15:53.410971Z","end":"2026-04-28T07:15:53.668507Z","steps":["trace[567512158] 'process raft request' (duration: 257.291232ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:53.668436Z","caller":"traceutil/trace.go:171","msg":"trace[551389251] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"123.866333ms","start":"2026-04-28T07:15:53.544561Z","end":"2026-04-28T07:15:53.668427Z","steps":["trace[551389251] 'process raft request' (duration: 123.819103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:53.668285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:53.287926Z","time spent":"380.332123ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:15:53.668496Z","caller":"traceutil/trace.go:171","msg":"trace[723435551] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"269.664301ms","start":"2026-04-28T07:15:53.398826Z","end":"2026-04-28T07:15:53.66849Z","steps":["trace[723435551] 'process raft request' (duration: 269.360664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:58.619571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.110475ms","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-28T07:15:58.619671Z","caller":"traceutil/trace.go:171","msg":"trace[1183155249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"328.267195ms","start":"2026-04-28T07:15:58.291384Z","end":"2026-04-28T07:15:58.619651Z","steps":["trace[1183155249] 'range keys from in-memory index tree' (duration: 327.994436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:15:58.619708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:15:58.291336Z","time spent":"328.363694ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T07:15:59.243917Z","caller":"traceutil/trace.go:171","msg":"trace[1350070540] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"146.556854ms","start":"2026-04-28T07:15:59.096479Z","end":"2026-04-28T07:15:59.243036Z","steps":["trace[1350070540] 'process raft request' (duration: 145.953865ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:15:59.891297Z","caller":"traceutil/trace.go:171","msg":"trace[1270589315] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"120.369132ms","start":"2026-04-28T07:15:59.77083Z","end":"2026-04-28T07:15:59.891199Z","steps":["trace[1270589315] 'process raft request' (duration: 119.935503ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:00.088151Z","caller":"traceutil/trace.go:171","msg":"trace[374612808] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"220.937607ms","start":"2026-04-28T07:15:59.867131Z","end":"2026-04-28T07:16:00.088069Z","steps":["trace[374612808] 'read index received' (duration: 23.60174ms)","trace[374612808] 'applied index is now lower than readState.Index' (duration: 197.334817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:16:00.088436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.250236ms","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-28T07:16:00.088179Z","caller":"traceutil/trace.go:171","msg":"trace[366666743] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"297.517841ms","start":"2026-04-28T07:15:59.790612Z","end":"2026-04-28T07:16:00.08813Z","steps":["trace[366666743] 'process raft request' (duration: 223.810906ms)","trace[366666743] 'compare' (duration: 73.155095ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:16:00.088598Z","caller":"traceutil/trace.go:171","msg":"trace[1428527291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"221.450946ms","start":"2026-04-28T07:15:59.867082Z","end":"2026-04-28T07:16:00.088533Z","steps":["trace[1428527291] 'agreement among raft nodes before linearized reading' (duration: 221.163366ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:00.269406Z","caller":"traceutil/trace.go:171","msg":"trace[531974430] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"117.052733ms","start":"2026-04-28T07:16:00.152322Z","end":"2026-04-28T07:16:00.269375Z","steps":["trace[531974430] 'process raft request' (duration: 104.835111ms)","trace[531974430] 'compare' (duration: 12.081182ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:16:00.791714Z","caller":"traceutil/trace.go:171","msg":"trace[964636775] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"127.214819ms","start":"2026-04-28T07:16:00.664464Z","end":"2026-04-28T07:16:00.791679Z","steps":["trace[964636775] 'process raft request' (duration: 127.047359ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:01.305259Z","caller":"traceutil/trace.go:171","msg":"trace[547375543] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"634.873051ms","start":"2026-04-28T07:16:00.670355Z","end":"2026-04-28T07:16:01.305228Z","steps":["trace[547375543] 'process raft request' (duration: 634.214011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:01.305479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:00.67034Z","time spent":"635.000291ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:16:01.305947Z","caller":"traceutil/trace.go:171","msg":"trace[1098866457] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"247.689935ms","start":"2026-04-28T07:16:01.058226Z","end":"2026-04-28T07:16:01.305916Z","steps":["trace[1098866457] 'read index received' (duration: 246.174105ms)","trace[1098866457] 'applied index is now lower than readState.Index' (duration: 1.51447ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:16:01.3061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.851495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:01.306077Z","caller":"traceutil/trace.go:171","msg":"trace[845918448] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"613.901273ms","start":"2026-04-28T07:16:00.692146Z","end":"2026-04-28T07:16:01.306047Z","steps":["trace[845918448] 'process raft request' (duration: 612.743003ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:01.30614Z","caller":"traceutil/trace.go:171","msg":"trace[1852185925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"247.962855ms","start":"2026-04-28T07:16:01.058166Z","end":"2026-04-28T07:16:01.306129Z","steps":["trace[1852185925] 'agreement among raft nodes before linearized reading' (duration: 247.868725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:01.30625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:00.692127Z","time spent":"614.025603ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:16:02.016438Z","caller":"traceutil/trace.go:171","msg":"trace[1355471624] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"697.512263ms","start":"2026-04-28T07:16:01.318891Z","end":"2026-04-28T07:16:02.016403Z","steps":["trace[1355471624] 'process raft request' (duration: 696.685003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:02.016777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:01.318841Z","time spent":"697.815323ms","remote":"127.0.0.1:34870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:16:03.972124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.932303257s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:16:03.972945Z","caller":"traceutil/trace.go:171","msg":"trace[1513434337] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"2.651169241s","start":"2026-04-28T07:16:01.321723Z","end":"2026-04-28T07:16:03.972893Z","steps":["trace[1513434337] 'process raft request' (duration: 717.992913ms)","trace[1513434337] 'compare' (duration: 1.931456975s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:16:03.97306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:01.321694Z","time spent":"2.651312592s","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:16:03.973615Z","caller":"traceutil/trace.go:171","msg":"trace[863039989] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"2.588972142s","start":"2026-04-28T07:16:01.384628Z","end":"2026-04-28T07:16:03.973601Z","steps":["trace[863039989] 'process raft request' (duration: 2.588791121s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:03.973694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:01.384599Z","time spent":"2.589055992s","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:16:03.974154Z","caller":"traceutil/trace.go:171","msg":"trace[1730013563] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"1.915789655s","start":"2026-04-28T07:16:02.057979Z","end":"2026-04-28T07:16:03.973769Z","steps":["trace[1730013563] 'read index received' (duration: 46.09µs)","trace[1730013563] 'applied index is now lower than readState.Index' (duration: 1.915741395s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:16:04.004723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852020817s","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-28T07:16:04.004732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.876247567s","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-28T07:16:04.00479Z","caller":"traceutil/trace.go:171","msg":"trace[1668202937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.852130007s","start":"2026-04-28T07:16:02.152642Z","end":"2026-04-28T07:16:04.004772Z","steps":["trace[1668202937] 'agreement among raft nodes before linearized reading' (duration: 1.851923836s)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:04.004795Z","caller":"traceutil/trace.go:171","msg":"trace[1625326973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.876363987s","start":"2026-04-28T07:16:02.128415Z","end":"2026-04-28T07:16:04.004779Z","steps":["trace[1625326973] 'agreement among raft nodes before linearized reading' (duration: 1.876170496s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:04.004825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:02.152619Z","time spent":"1.852198057s","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T07:16:04.004832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:02.128388Z","time spent":"1.876434387s","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:16:04.004807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183399456s","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-28T07:16:04.004872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851704547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-28T07:16:04.004932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.946961014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:04.004923Z","caller":"traceutil/trace.go:171","msg":"trace[1470312948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"1.183551286s","start":"2026-04-28T07:16:02.821338Z","end":"2026-04-28T07:16:04.00489Z","steps":["trace[1470312948] 'agreement among raft nodes before linearized reading' (duration: 1.183202775s)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:04.004957Z","caller":"traceutil/trace.go:171","msg":"trace[839104157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.851843197s","start":"2026-04-28T07:16:02.153094Z","end":"2026-04-28T07:16:04.004937Z","steps":["trace[839104157] 'agreement among raft nodes before linearized reading' (duration: 1.851547396s)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:04.004967Z","caller":"traceutil/trace.go:171","msg":"trace[1913744820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.946990064s","start":"2026-04-28T07:16:02.057962Z","end":"2026-04-28T07:16:04.004952Z","steps":["trace[1913744820] 'agreement among raft nodes before linearized reading' (duration: 1.916147146s)","trace[1913744820] 'range keys from in-memory index tree' (duration: 30.796508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:16:04.00498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:02.821325Z","time spent":"1.183645466s","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:16:04.005012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:02.153067Z","time spent":"1.851936837s","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:16:04.005005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:02.057902Z","time spent":"1.947097554s","remote":"127.0.0.1:34706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:16:04.186833Z","caller":"traceutil/trace.go:171","msg":"trace[1799440629] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"176.290264ms","start":"2026-04-28T07:16:04.010495Z","end":"2026-04-28T07:16:04.186785Z","steps":["trace[1799440629] 'process raft request' (duration: 175.795382ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:04.186891Z","caller":"traceutil/trace.go:171","msg":"trace[1617803071] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"176.407204ms","start":"2026-04-28T07:16:04.010448Z","end":"2026-04-28T07:16:04.186856Z","steps":["trace[1617803071] 'process raft request' (duration: 176.049633ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:04.189222Z","caller":"traceutil/trace.go:171","msg":"trace[1332788461] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"178.46894ms","start":"2026-04-28T07:16:04.010709Z","end":"2026-04-28T07:16:04.189178Z","steps":["trace[1332788461] 'process raft request' (duration: 178.297799ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:04.189401Z","caller":"traceutil/trace.go:171","msg":"trace[120517660] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:640; }","duration":"173.143204ms","start":"2026-04-28T07:16:04.01619Z","end":"2026-04-28T07:16:04.189333Z","steps":["trace[120517660] 'read index received' (duration: 170.178956ms)","trace[120517660] 'applied index is now lower than readState.Index' (duration: 2.962368ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:16:04.189392Z","caller":"traceutil/trace.go:171","msg":"trace[1031629460] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"178.0307ms","start":"2026-04-28T07:16:04.011337Z","end":"2026-04-28T07:16:04.189368Z","steps":["trace[1031629460] 'process raft request' (duration: 177.796579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:04.189577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.358965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:16:04.189651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.052348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:04.189675Z","caller":"traceutil/trace.go:171","msg":"trace[489895571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"173.491255ms","start":"2026-04-28T07:16:04.01617Z","end":"2026-04-28T07:16:04.189662Z","steps":["trace[489895571] 'agreement among raft nodes before linearized reading' (duration: 173.347245ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:04.189723Z","caller":"traceutil/trace.go:171","msg":"trace[966035400] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:622; }","duration":"171.145639ms","start":"2026-04-28T07:16:04.018555Z","end":"2026-04-28T07:16:04.189701Z","steps":["trace[966035400] 'agreement among raft nodes before linearized reading' (duration: 171.014608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:05.125193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.162473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:05.125269Z","caller":"traceutil/trace.go:171","msg":"trace[808362157] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"334.295403ms","start":"2026-04-28T07:16:04.790957Z","end":"2026-04-28T07:16:05.125253Z","steps":["trace[808362157] 'range keys from in-memory index tree' (duration: 334.053163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:05.125418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:04.790929Z","time spent":"334.367633ms","remote":"127.0.0.1:35142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-28T07:16:05.125634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.549885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:05.125686Z","caller":"traceutil/trace.go:171","msg":"trace[1699908257] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"334.615535ms","start":"2026-04-28T07:16:04.79106Z","end":"2026-04-28T07:16:05.125676Z","steps":["trace[1699908257] 'count revisions from in-memory index tree' (duration: 334.491215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:05.125723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:04.791056Z","time spent":"334.654005ms","remote":"127.0.0.1:35142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:16:05.125739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.369212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:05.125795Z","caller":"traceutil/trace.go:171","msg":"trace[1128118263] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:631; }","duration":"182.499752ms","start":"2026-04-28T07:16:04.943282Z","end":"2026-04-28T07:16:05.125782Z","steps":["trace[1128118263] 'count revisions from in-memory index tree' (duration: 182.234641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:05.126033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.012216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:05.126052Z","caller":"traceutil/trace.go:171","msg":"trace[1063664543] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"335.067676ms","start":"2026-04-28T07:16:04.790975Z","end":"2026-04-28T07:16:05.126043Z","steps":["trace[1063664543] 'range keys from in-memory index tree' (duration: 334.891046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:05.126069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:04.790951Z","time spent":"335.111796ms","remote":"127.0.0.1:35142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T07:16:05.126226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.048304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:05.126242Z","caller":"traceutil/trace.go:171","msg":"trace[648279829] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:631; }","duration":"183.065224ms","start":"2026-04-28T07:16:04.943172Z","end":"2026-04-28T07:16:05.126237Z","steps":["trace[648279829] 'range keys from in-memory index tree' (duration: 182.960784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:05.956224Z","caller":"traceutil/trace.go:171","msg":"trace[112916425] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"160.425547ms","start":"2026-04-28T07:16:05.795773Z","end":"2026-04-28T07:16:05.956198Z","steps":["trace[112916425] 'process raft request' (duration: 159.460603ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:10.72576Z","caller":"traceutil/trace.go:171","msg":"trace[1316870217] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:735; }","duration":"156.788774ms","start":"2026-04-28T07:16:10.568947Z","end":"2026-04-28T07:16:10.725736Z","steps":["trace[1316870217] 'read index received' (duration: 24.831796ms)","trace[1316870217] 'applied index is now lower than readState.Index' (duration: 131.955878ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:16:10.725875Z","caller":"traceutil/trace.go:171","msg":"trace[1427265639] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"161.564537ms","start":"2026-04-28T07:16:10.564294Z","end":"2026-04-28T07:16:10.725859Z","steps":["trace[1427265639] 'process raft request' (duration: 93.064605ms)","trace[1427265639] 'compare' (duration: 68.22915ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:16:10.726083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.134417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-28T07:16:10.726413Z","caller":"traceutil/trace.go:171","msg":"trace[1915979148] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"157.50661ms","start":"2026-04-28T07:16:10.568892Z","end":"2026-04-28T07:16:10.726399Z","steps":["trace[1915979148] 'agreement among raft nodes before linearized reading' (duration: 157.020936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:10.726574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.652002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:10.726603Z","caller":"traceutil/trace.go:171","msg":"trace[1206430894] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:714; }","duration":"156.717323ms","start":"2026-04-28T07:16:10.569879Z","end":"2026-04-28T07:16:10.726596Z","steps":["trace[1206430894] 'agreement among raft nodes before linearized reading' (duration: 156.660732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:10.72685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.222328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:10.726917Z","caller":"traceutil/trace.go:171","msg":"trace[1343885715] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:714; }","duration":"157.353888ms","start":"2026-04-28T07:16:10.569547Z","end":"2026-04-28T07:16:10.726901Z","steps":["trace[1343885715] 'agreement among raft nodes before linearized reading' (duration: 156.981855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:10.726855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.196178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:10.727045Z","caller":"traceutil/trace.go:171","msg":"trace[1183108163] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:714; }","duration":"157.369679ms","start":"2026-04-28T07:16:10.569629Z","end":"2026-04-28T07:16:10.726998Z","steps":["trace[1183108163] 'agreement among raft nodes before linearized reading' (duration: 157.143797ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:18.2553Z","caller":"traceutil/trace.go:171","msg":"trace[1181432184] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"131.340518ms","start":"2026-04-28T07:16:18.123937Z","end":"2026-04-28T07:16:18.255277Z","steps":["trace[1181432184] 'process raft request' (duration: 130.602096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:18.701463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.400885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:16:18.701581Z","caller":"traceutil/trace.go:171","msg":"trace[939325083] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"437.692901ms","start":"2026-04-28T07:16:18.26387Z","end":"2026-04-28T07:16:18.701563Z","steps":["trace[939325083] 'read index received' (duration: 71.099743ms)","trace[939325083] 'applied index is now lower than readState.Index' (duration: 366.591808ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:16:18.701637Z","caller":"traceutil/trace.go:171","msg":"trace[1309594408] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"576.379141ms","start":"2026-04-28T07:16:18.125219Z","end":"2026-04-28T07:16:18.701598Z","steps":["trace[1309594408] 'process raft request' (duration: 209.695011ms)","trace[1309594408] 'compare' (duration: 366.257893ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:16:18.701801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:18.1252Z","time spent":"576.516123ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:16:18.701968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.089798ms","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-28T07:16:18.702014Z","caller":"traceutil/trace.go:171","msg":"trace[1255733989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"438.171509ms","start":"2026-04-28T07:16:18.26383Z","end":"2026-04-28T07:16:18.702001Z","steps":["trace[1255733989] 'agreement among raft nodes before linearized reading' (duration: 437.826354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:18.702116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.709367ms","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-04-28T07:16:18.702206Z","caller":"traceutil/trace.go:171","msg":"trace[280163894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"433.807548ms","start":"2026-04-28T07:16:18.268374Z","end":"2026-04-28T07:16:18.702181Z","steps":["trace[280163894] 'agreement among raft nodes before linearized reading' (duration: 433.623235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:18.702209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.486419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-28T07:16:18.70222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.19358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T07:16:18.702255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:18.268367Z","time spent":"433.879269ms","remote":"127.0.0.1:34972","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-28T07:16:18.702131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:18.263808Z","time spent":"438.309361ms","remote":"127.0.0.1:34972","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-28T07:16:18.702377Z","caller":"traceutil/trace.go:171","msg":"trace[209973354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"438.423763ms","start":"2026-04-28T07:16:18.263866Z","end":"2026-04-28T07:16:18.70229Z","steps":["trace[209973354] 'agreement among raft nodes before linearized reading' (duration: 438.026707ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:18.702254Z","caller":"traceutil/trace.go:171","msg":"trace[2144173679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:770; }","duration":"437.555689ms","start":"2026-04-28T07:16:18.264687Z","end":"2026-04-28T07:16:18.702243Z","steps":["trace[2144173679] 'agreement among raft nodes before linearized reading' (duration: 437.403467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:18.702539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:18.264675Z","time spent":"437.813813ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T07:16:18.702746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:18.263853Z","time spent":"438.595087ms","remote":"127.0.0.1:34972","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-28T07:16:18.926593Z","caller":"traceutil/trace.go:171","msg":"trace[863850322] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"220.02054ms","start":"2026-04-28T07:16:18.706548Z","end":"2026-04-28T07:16:18.926568Z","steps":["trace[863850322] 'process raft request' (duration: 219.941399ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:18.926725Z","caller":"traceutil/trace.go:171","msg":"trace[239761678] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"220.150232ms","start":"2026-04-28T07:16:18.706548Z","end":"2026-04-28T07:16:18.926698Z","steps":["trace[239761678] 'process raft request' (duration: 219.544013ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:18.97038Z","caller":"traceutil/trace.go:171","msg":"trace[1864735899] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:795; }","duration":"164.32345ms","start":"2026-04-28T07:16:18.806043Z","end":"2026-04-28T07:16:18.970366Z","steps":["trace[1864735899] 'read index received' (duration: 120.065136ms)","trace[1864735899] 'applied index is now lower than readState.Index' (duration: 44.257824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:16:18.970502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.450401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.146\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T07:16:18.970555Z","caller":"traceutil/trace.go:171","msg":"trace[1710374181] range","detail":"{range_begin:/registry/masterleases/199.19.213.146; range_end:; response_count:1; response_revision:774; }","duration":"164.555963ms","start":"2026-04-28T07:16:18.805992Z","end":"2026-04-28T07:16:18.970548Z","steps":["trace[1710374181] 'agreement among raft nodes before linearized reading' (duration: 164.42143ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:18.97049Z","caller":"traceutil/trace.go:171","msg":"trace[1085144007] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"262.550726ms","start":"2026-04-28T07:16:18.707918Z","end":"2026-04-28T07:16:18.970469Z","steps":["trace[1085144007] 'process raft request' (duration: 262.421454ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:16:18.970438Z","caller":"traceutil/trace.go:171","msg":"trace[2040229450] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"263.274967ms","start":"2026-04-28T07:16:18.707137Z","end":"2026-04-28T07:16:18.970412Z","steps":["trace[2040229450] 'process raft request' (duration: 263.112735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:23.508714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851122141742471,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:16:23.621703Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.349437112s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T07:16:23.622363Z","caller":"traceutil/trace.go:171","msg":"trace[351906815] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"1.350171216s","start":"2026-04-28T07:16:22.272131Z","end":"2026-04-28T07:16:23.622302Z","steps":["trace[351906815] 'process raft request' (duration: 1.350010273s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:23.622535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:22.272109Z","time spent":"1.35032079s","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:16:23.676221Z","caller":"traceutil/trace.go:171","msg":"trace[1461707965] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:815; }","duration":"667.815342ms","start":"2026-04-28T07:16:23.008371Z","end":"2026-04-28T07:16:23.676187Z","steps":["trace[1461707965] 'read index received' (duration: 613.724784ms)","trace[1461707965] 'applied index is now lower than readState.Index' (duration: 54.089198ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:16:23.676299Z","caller":"traceutil/trace.go:171","msg":"trace[1537147344] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"888.184209ms","start":"2026-04-28T07:16:22.788086Z","end":"2026-04-28T07:16:23.67627Z","steps":["trace[1537147344] 'process raft request' (duration: 887.932744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:23.676451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:22.788054Z","time spent":"888.318322ms","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:16:23.676621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.283118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:16:23.676661Z","caller":"traceutil/trace.go:171","msg":"trace[852075357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"619.35974ms","start":"2026-04-28T07:16:23.057289Z","end":"2026-04-28T07:16:23.676648Z","steps":["trace[852075357] 'agreement among raft nodes before linearized reading' (duration: 619.300779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:23.676692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:23.05727Z","time spent":"619.415471ms","remote":"127.0.0.1:34706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:16:23.676701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.454152ms","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-28T07:16:23.676766Z","caller":"traceutil/trace.go:171","msg":"trace[1287055545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"642.573135ms","start":"2026-04-28T07:16:23.034177Z","end":"2026-04-28T07:16:23.67675Z","steps":["trace[1287055545] 'agreement among raft nodes before linearized reading' (duration: 642.355871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:23.676813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:23.03416Z","time spent":"642.642156ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:16:23.676719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.272622ms","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-28T07:16:23.676901Z","caller":"traceutil/trace.go:171","msg":"trace[212445555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"668.517266ms","start":"2026-04-28T07:16:23.008336Z","end":"2026-04-28T07:16:23.676853Z","steps":["trace[212445555] 'agreement among raft nodes before linearized reading' (duration: 667.961455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:23.676984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:23.00832Z","time spent":"668.625308ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T07:16:23.677015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.606786ms","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-28T07:16:23.67706Z","caller":"traceutil/trace.go:171","msg":"trace[586779891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"642.668747ms","start":"2026-04-28T07:16:23.034379Z","end":"2026-04-28T07:16:23.677047Z","steps":["trace[586779891] 'agreement among raft nodes before linearized reading' (duration: 642.134376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:23.677091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:23.034372Z","time spent":"642.711998ms","remote":"127.0.0.1:34972","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-28T07:16:23.677276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.410284ms","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-28T07:16:23.67733Z","caller":"traceutil/trace.go:171","msg":"trace[643130253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"667.478976ms","start":"2026-04-28T07:16:23.009832Z","end":"2026-04-28T07:16:23.677311Z","steps":["trace[643130253] 'agreement among raft nodes before linearized reading' (duration: 666.669779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:16:23.677381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:23.009822Z","time spent":"667.546057ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:16:54.703033Z","caller":"traceutil/trace.go:171","msg":"trace[2079004828] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"182.055405ms","start":"2026-04-28T07:16:54.520951Z","end":"2026-04-28T07:16:54.703007Z","steps":["trace[2079004828] 'process raft request' (duration: 181.917669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.432794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.826586ms","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-28T07:17:00.432929Z","caller":"traceutil/trace.go:171","msg":"trace[1001020210] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1056; }","duration":"145.974412ms","start":"2026-04-28T07:17:00.286932Z","end":"2026-04-28T07:17:00.432906Z","steps":["trace[1001020210] 'range keys from in-memory index tree' (duration: 145.806605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.433366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.384886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:17:00.43372Z","caller":"traceutil/trace.go:171","msg":"trace[1373556942] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"549.984589ms","start":"2026-04-28T07:16:59.883713Z","end":"2026-04-28T07:17:00.433698Z","steps":["trace[1373556942] 'process raft request' (duration: 160.186825ms)","trace[1373556942] 'compare' (duration: 388.99913ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:17:00.433741Z","caller":"traceutil/trace.go:171","msg":"trace[66713912] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"506.841809ms","start":"2026-04-28T07:16:59.92684Z","end":"2026-04-28T07:17:00.433682Z","steps":["trace[66713912] 'read index received' (duration: 117.011224ms)","trace[66713912] 'applied index is now lower than readState.Index' (duration: 389.828955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:17:00.434027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:59.883696Z","time spent":"550.198068ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:17:00.434238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.328822ms","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-28T07:17:00.434274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.084548ms","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-28T07:17:00.434325Z","caller":"traceutil/trace.go:171","msg":"trace[1217651495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1057; }","duration":"433.163492ms","start":"2026-04-28T07:17:00.001144Z","end":"2026-04-28T07:17:00.434308Z","steps":["trace[1217651495] 'agreement among raft nodes before linearized reading' (duration: 433.039116ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:00.434329Z","caller":"traceutil/trace.go:171","msg":"trace[865420860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"494.419136ms","start":"2026-04-28T07:16:59.939863Z","end":"2026-04-28T07:17:00.434282Z","steps":["trace[865420860] 'agreement among raft nodes before linearized reading' (duration: 494.174625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.434381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:17:00.001123Z","time spent":"433.246515ms","remote":"127.0.0.1:34972","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-28T07:17:00.434391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.009525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d794b936-e25f-449b-a28f-3e4d156356fa\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-28T07:17:00.434482Z","caller":"traceutil/trace.go:171","msg":"trace[817712724] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d794b936-e25f-449b-a28f-3e4d156356fa; range_end:; response_count:1; response_revision:1057; }","duration":"265.14928ms","start":"2026-04-28T07:17:00.169311Z","end":"2026-04-28T07:17:00.43446Z","steps":["trace[817712724] 'agreement among raft nodes before linearized reading' (duration: 264.936501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.434477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.806762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:17:00.434589Z","caller":"traceutil/trace.go:171","msg":"trace[2020923715] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1057; }","duration":"411.927067ms","start":"2026-04-28T07:17:00.022642Z","end":"2026-04-28T07:17:00.434569Z","steps":["trace[2020923715] 'agreement among raft nodes before linearized reading' (duration: 411.750399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.434637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:17:00.02257Z","time spent":"412.056092ms","remote":"127.0.0.1:35064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:17:00.434491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:59.939849Z","time spent":"494.522379ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T07:17:00.434677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.211594ms","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-04-28T07:17:00.434753Z","caller":"traceutil/trace.go:171","msg":"trace[422594942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"399.413744ms","start":"2026-04-28T07:17:00.03532Z","end":"2026-04-28T07:17:00.434734Z","steps":["trace[422594942] 'agreement among raft nodes before linearized reading' (duration: 398.86256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.434807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:17:00.035302Z","time spent":"399.494827ms","remote":"127.0.0.1:34972","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-28T07:17:00.434854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.897351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:17:00.434897Z","caller":"traceutil/trace.go:171","msg":"trace[1896311316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"375.979614ms","start":"2026-04-28T07:17:00.058906Z","end":"2026-04-28T07:17:00.434886Z","steps":["trace[1896311316] 'agreement among raft nodes before linearized reading' (duration: 375.906961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.434926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:17:00.058883Z","time spent":"376.035807ms","remote":"127.0.0.1:34706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:17:00.43523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.383775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d794b936-e25f-449b-a28f-3e4d156356fa\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-28T07:17:00.435614Z","caller":"traceutil/trace.go:171","msg":"trace[314711084] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d794b936-e25f-449b-a28f-3e4d156356fa; range_end:; response_count:1; response_revision:1057; }","duration":"508.797792ms","start":"2026-04-28T07:16:59.926801Z","end":"2026-04-28T07:17:00.435599Z","steps":["trace[314711084] 'agreement among raft nodes before linearized reading' (duration: 507.012966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.435656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:59.926784Z","time spent":"508.859855ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3603,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d794b936-e25f-449b-a28f-3e4d156356fa\" "} {"level":"warn","ts":"2026-04-28T07:17:00.436751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.704817ms","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-28T07:17:00.436894Z","caller":"traceutil/trace.go:171","msg":"trace[909357535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1057; }","duration":"489.846353ms","start":"2026-04-28T07:16:59.947011Z","end":"2026-04-28T07:17:00.436857Z","steps":["trace[909357535] 'agreement among raft nodes before linearized reading' (duration: 487.331006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:00.437042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:16:59.947001Z","time spent":"490.024372ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T07:17:00.598101Z","caller":"traceutil/trace.go:171","msg":"trace[324994698] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"153.623448ms","start":"2026-04-28T07:17:00.444453Z","end":"2026-04-28T07:17:00.598077Z","steps":["trace[324994698] 'process raft request' (duration: 153.560776ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:00.598286Z","caller":"traceutil/trace.go:171","msg":"trace[137228704] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"157.004282ms","start":"2026-04-28T07:17:00.441203Z","end":"2026-04-28T07:17:00.598207Z","steps":["trace[137228704] 'process raft request' (duration: 156.73007ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:00.598545Z","caller":"traceutil/trace.go:171","msg":"trace[1806829408] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"159.346392ms","start":"2026-04-28T07:17:00.439186Z","end":"2026-04-28T07:17:00.598533Z","steps":["trace[1806829408] 'process raft request' (duration: 158.559248ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:00.598655Z","caller":"traceutil/trace.go:171","msg":"trace[286170396] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"157.262593ms","start":"2026-04-28T07:17:00.441354Z","end":"2026-04-28T07:17:00.598617Z","steps":["trace[286170396] 'process raft request' (duration: 156.622716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:08.318031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.839457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:17:08.318065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.924018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-04-28T07:17:08.318093Z","caller":"traceutil/trace.go:171","msg":"trace[1946049828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"259.944252ms","start":"2026-04-28T07:17:08.058134Z","end":"2026-04-28T07:17:08.318078Z","steps":["trace[1946049828] 'range keys from in-memory index tree' (duration: 259.761914ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:08.318115Z","caller":"traceutil/trace.go:171","msg":"trace[1192102587] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1101; }","duration":"177.006172ms","start":"2026-04-28T07:17:08.141096Z","end":"2026-04-28T07:17:08.318102Z","steps":["trace[1192102587] 'range keys from in-memory index tree' (duration: 176.805393ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:09.078353Z","caller":"traceutil/trace.go:171","msg":"trace[1107212365] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"140.875731ms","start":"2026-04-28T07:17:08.937453Z","end":"2026-04-28T07:17:09.078329Z","steps":["trace[1107212365] 'process raft request' (duration: 140.800377ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:09.078365Z","caller":"traceutil/trace.go:171","msg":"trace[1012900265] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"265.982838ms","start":"2026-04-28T07:17:08.812351Z","end":"2026-04-28T07:17:09.078334Z","steps":["trace[1012900265] 'process raft request' (duration: 196.251888ms)","trace[1012900265] 'compare' (duration: 69.51554ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:17:09.078585Z","caller":"traceutil/trace.go:171","msg":"trace[1435414611] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"202.330994ms","start":"2026-04-28T07:17:08.876237Z","end":"2026-04-28T07:17:09.078568Z","steps":["trace[1435414611] 'read index received' (duration: 60.08685ms)","trace[1435414611] 'applied index is now lower than readState.Index' (duration: 142.241674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:17:09.078685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.438699ms","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-28T07:17:09.078733Z","caller":"traceutil/trace.go:171","msg":"trace[1765498099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1107; }","duration":"202.533784ms","start":"2026-04-28T07:17:08.876184Z","end":"2026-04-28T07:17:09.078717Z","steps":["trace[1765498099] 'agreement among raft nodes before linearized reading' (duration: 202.439109ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:10.747669Z","caller":"traceutil/trace.go:171","msg":"trace[1165926099] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"109.950426ms","start":"2026-04-28T07:17:10.637676Z","end":"2026-04-28T07:17:10.747626Z","steps":["trace[1165926099] 'process raft request' (duration: 109.807969ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:15.158619Z","caller":"traceutil/trace.go:171","msg":"trace[1036068855] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"385.078952ms","start":"2026-04-28T07:17:14.773513Z","end":"2026-04-28T07:17:15.158592Z","steps":["trace[1036068855] 'process raft request' (duration: 384.835121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:15.158767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:17:14.773494Z","time spent":"385.198258ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:17:15.169304Z","caller":"traceutil/trace.go:171","msg":"trace[1565713714] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"394.686669ms","start":"2026-04-28T07:17:14.774593Z","end":"2026-04-28T07:17:15.169279Z","steps":["trace[1565713714] 'process raft request' (duration: 394.531991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:15.169547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:17:14.774575Z","time spent":"394.873968ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:17:15.169724Z","caller":"traceutil/trace.go:171","msg":"trace[2011302963] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"392.782648ms","start":"2026-04-28T07:17:14.776911Z","end":"2026-04-28T07:17:15.169694Z","steps":["trace[2011302963] 'process raft request' (duration: 392.319236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:17:15.169931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:17:14.7769Z","time spent":"392.914744ms","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:17:15.170372Z","caller":"traceutil/trace.go:171","msg":"trace[391455937] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1166; }","duration":"112.289038ms","start":"2026-04-28T07:17:15.058073Z","end":"2026-04-28T07:17:15.170362Z","steps":["trace[391455937] 'read index received' (duration: 100.235964ms)","trace[391455937] 'applied index is now lower than readState.Index' (duration: 12.052424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:17:15.170669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.591613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:17:15.170699Z","caller":"traceutil/trace.go:171","msg":"trace[49215785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1133; }","duration":"112.660466ms","start":"2026-04-28T07:17:15.058032Z","end":"2026-04-28T07:17:15.170692Z","steps":["trace[49215785] 'agreement among raft nodes before linearized reading' (duration: 112.614304ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:17:21.349827Z","caller":"traceutil/trace.go:171","msg":"trace[615171035] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"111.715643ms","start":"2026-04-28T07:17:21.238094Z","end":"2026-04-28T07:17:21.34981Z","steps":["trace[615171035] 'process raft request' (duration: 42.819803ms)","trace[615171035] 'compare' (duration: 68.71255ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:17:21.35016Z","caller":"traceutil/trace.go:171","msg":"trace[1421386018] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"102.177921ms","start":"2026-04-28T07:17:21.247957Z","end":"2026-04-28T07:17:21.350135Z","steps":["trace[1421386018] 'process raft request' (duration: 101.780111ms)"],"step_count":1}