{"level":"warn","ts":"2026-02-05T17:34:23.094362Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:34:23.094436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-02-05T17:34:23.094494Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:34:23.094503Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-02-05T17:34:23.09452Z","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-02-05T17:34:23.09528Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-02-05T17:34:23.095366Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-02-05T17:34:23.226041Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"110.257006ms"} {"level":"info","ts":"2026-02-05T17:34:23.360345Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-02-05T17:34:23.360434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:34:23.360462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-02-05T17:34:23.36048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:34:23.360497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-02-05T17:34:23.360548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-02-05T17:34:23.555842Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:34:23.588898Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:34:23.659505Z","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-02-05T17:34:23.750809Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:34:23.751283Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:34:23.751391Z","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-02-05T17:34:23.751439Z","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-02-05T17:34:23.751456Z","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-02-05T17:34:23.771471Z","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-02-05T17:34:23.77168Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:34:23.771731Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:34:23.772218Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-02-05T17:34:23.772247Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-02-05T17:34:23.81728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-02-05T17:34:23.817405Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-02-05T17:34:24.46169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:34:24.461934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:34:24.462137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-02-05T17:34:24.462295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:34:24.462416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-02-05T17:34:24.46254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-02-05T17:34:24.462734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-02-05T17:34:24.54224Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:34:24.548621Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:34:24.548823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:34:24.552578Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-02-05T17:34:24.552921Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:34:24.553088Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:34:24.553188Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:34:24.554439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:34:24.554694Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:34:24.554779Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:34:24.557636Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:34:30.608178Z","caller":"traceutil/trace.go:171","msg":"trace[2071054683] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:262; }","duration":"107.117124ms","start":"2026-02-05T17:34:30.501041Z","end":"2026-02-05T17:34:30.608158Z","steps":["trace[2071054683] 'read index received' (duration: 85.738558ms)","trace[2071054683] 'applied index is now lower than readState.Index' (duration: 21.377656ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:30.608547Z","caller":"traceutil/trace.go:171","msg":"trace[1088354831] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"132.319652ms","start":"2026-02-05T17:34:30.475974Z","end":"2026-02-05T17:34:30.608294Z","steps":["trace[1088354831] 'process raft request' (duration: 110.799497ms)","trace[1088354831] 'compare' (duration: 21.145412ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:30.609003Z","caller":"traceutil/trace.go:171","msg":"trace[352686415] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"128.165549ms","start":"2026-02-05T17:34:30.480826Z","end":"2026-02-05T17:34:30.608992Z","steps":["trace[352686415] 'process raft request' (duration: 127.268385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:30.609417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.887779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:30.609707Z","caller":"traceutil/trace.go:171","msg":"trace[723157926] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:255; }","duration":"122.967634ms","start":"2026-02-05T17:34:30.486486Z","end":"2026-02-05T17:34:30.609454Z","steps":["trace[723157926] 'agreement among raft nodes before linearized reading' (duration: 122.860948ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:30.807978Z","caller":"traceutil/trace.go:171","msg":"trace[170653575] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:264; }","duration":"199.695956ms","start":"2026-02-05T17:34:30.608265Z","end":"2026-02-05T17:34:30.807961Z","steps":["trace[170653575] 'read index received' (duration: 105.112322ms)","trace[170653575] 'applied index is now lower than readState.Index' (duration: 94.582964ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:30.808142Z","caller":"traceutil/trace.go:171","msg":"trace[854646897] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"300.687038ms","start":"2026-02-05T17:34:30.507444Z","end":"2026-02-05T17:34:30.808131Z","steps":["trace[854646897] 'process raft request' (duration: 205.969536ms)","trace[854646897] 'compare' (duration: 94.35852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:30.808526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.397536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-02-05T17:34:30.80853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:30.507431Z","time spent":"300.730291ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:30.808559Z","caller":"traceutil/trace.go:171","msg":"trace[1245490227] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:257; }","duration":"196.513483ms","start":"2026-02-05T17:34:30.612037Z","end":"2026-02-05T17:34:30.808551Z","steps":["trace[1245490227] 'agreement among raft nodes before linearized reading' (duration: 196.444849ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:30.808653Z","caller":"traceutil/trace.go:171","msg":"trace[582692067] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"299.423811ms","start":"2026-02-05T17:34:30.509221Z","end":"2026-02-05T17:34:30.808644Z","steps":["trace[582692067] 'process raft request' (duration: 298.705498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:30.808731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.356663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-05T17:34:30.808753Z","caller":"traceutil/trace.go:171","msg":"trace[1652099372] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:257; }","duration":"195.389165ms","start":"2026-02-05T17:34:30.613357Z","end":"2026-02-05T17:34:30.808746Z","steps":["trace[1652099372] 'agreement among raft nodes before linearized reading' (duration: 195.348982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:30.808854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.466334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:34:30.808876Z","caller":"traceutil/trace.go:171","msg":"trace[1387112439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:257; }","duration":"299.510927ms","start":"2026-02-05T17:34:30.509358Z","end":"2026-02-05T17:34:30.808869Z","steps":["trace[1387112439] 'agreement among raft nodes before linearized reading' (duration: 299.473155ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:30.984046Z","caller":"traceutil/trace.go:171","msg":"trace[1214661099] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"166.77025ms","start":"2026-02-05T17:34:30.817255Z","end":"2026-02-05T17:34:30.984025Z","steps":["trace[1214661099] 'process raft request' (duration: 166.707266ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:30.984392Z","caller":"traceutil/trace.go:171","msg":"trace[1786192589] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"168.380298ms","start":"2026-02-05T17:34:30.816Z","end":"2026-02-05T17:34:30.98438Z","steps":["trace[1786192589] 'process raft request' (duration: 122.590472ms)","trace[1786192589] 'compare' (duration: 45.243973ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:31.259299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.153449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:31.259362Z","caller":"traceutil/trace.go:171","msg":"trace[681708976] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"189.52483ms","start":"2026-02-05T17:34:31.069827Z","end":"2026-02-05T17:34:31.259352Z","steps":["trace[681708976] 'read index received' (duration: 52.151307ms)","trace[681708976] 'applied index is now lower than readState.Index' (duration: 137.372883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:31.259395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.567033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:31.259407Z","caller":"traceutil/trace.go:171","msg":"trace[1013989441] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:264; }","duration":"189.590404ms","start":"2026-02-05T17:34:31.069812Z","end":"2026-02-05T17:34:31.259403Z","steps":["trace[1013989441] 'agreement among raft nodes before linearized reading' (duration: 189.563793ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:31.259565Z","caller":"traceutil/trace.go:171","msg":"trace[1956842771] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"192.245785ms","start":"2026-02-05T17:34:31.067314Z","end":"2026-02-05T17:34:31.259559Z","steps":["trace[1956842771] 'process raft request' (duration: 54.696431ms)","trace[1956842771] 'compare' (duration: 137.071275ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:31.491158Z","caller":"traceutil/trace.go:171","msg":"trace[1886648698] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:274; }","duration":"198.942622ms","start":"2026-02-05T17:34:31.292193Z","end":"2026-02-05T17:34:31.491135Z","steps":["trace[1886648698] 'read index received' (duration: 137.862403ms)","trace[1886648698] 'applied index is now lower than readState.Index' (duration: 61.079219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:31.491423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.206638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6688"} {"level":"info","ts":"2026-02-05T17:34:31.491505Z","caller":"traceutil/trace.go:171","msg":"trace[354064009] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:266; }","duration":"199.326376ms","start":"2026-02-05T17:34:31.292167Z","end":"2026-02-05T17:34:31.491493Z","steps":["trace[354064009] 'agreement among raft nodes before linearized reading' (duration: 199.139264ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:31.49255Z","caller":"traceutil/trace.go:171","msg":"trace[1946258323] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"223.9368ms","start":"2026-02-05T17:34:31.267908Z","end":"2026-02-05T17:34:31.491845Z","steps":["trace[1946258323] 'process raft request' (duration: 162.195831ms)","trace[1946258323] 'compare' (duration: 60.93492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:32.867754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493874229019275,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:34:33.061135Z","caller":"traceutil/trace.go:171","msg":"trace[1171628112] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"750.90192ms","start":"2026-02-05T17:34:32.310186Z","end":"2026-02-05T17:34:33.061088Z","steps":["trace[1171628112] 'process raft request' (duration: 750.782783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:33.061457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:32.310169Z","time spent":"751.201918ms","remote":"127.0.0.1:39776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:33.062006Z","caller":"traceutil/trace.go:171","msg":"trace[136878744] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:290; }","duration":"695.039921ms","start":"2026-02-05T17:34:32.366953Z","end":"2026-02-05T17:34:33.061993Z","steps":["trace[136878744] 'read index received' (duration: 695.035281ms)","trace[136878744] 'applied index is now lower than readState.Index' (duration: 3.81µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:33.062806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.444361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:34:33.063074Z","caller":"traceutil/trace.go:171","msg":"trace[1402475539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:281; }","duration":"641.721188ms","start":"2026-02-05T17:34:32.421317Z","end":"2026-02-05T17:34:33.063038Z","steps":["trace[1402475539] 'agreement among raft nodes before linearized reading' (duration: 641.413889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:33.063232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:32.421301Z","time spent":"641.887048ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:34:33.240129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.090449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T17:34:33.240153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.048877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:34:33.240206Z","caller":"traceutil/trace.go:171","msg":"trace[1554842254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:281; }","duration":"370.153695ms","start":"2026-02-05T17:34:32.870038Z","end":"2026-02-05T17:34:33.240192Z","steps":["trace[1554842254] 'agreement among raft nodes before linearized reading' (duration: 193.790107ms)","trace[1554842254] 'range keys from in-memory index tree' (duration: 176.260321ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:33.240207Z","caller":"traceutil/trace.go:171","msg":"trace[699904472] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:281; }","duration":"174.200376ms","start":"2026-02-05T17:34:33.065984Z","end":"2026-02-05T17:34:33.240184Z","steps":["trace[699904472] 'range keys from in-memory index tree' (duration: 174.051377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:33.240233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:32.87002Z","time spent":"370.207918ms","remote":"127.0.0.1:39838","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-02-05T17:34:33.240395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.458195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:34:33.240411Z","caller":"traceutil/trace.go:171","msg":"trace[302669308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:281; }","duration":"873.472006ms","start":"2026-02-05T17:34:32.366932Z","end":"2026-02-05T17:34:33.240404Z","steps":["trace[302669308] 'agreement among raft nodes before linearized reading' (duration: 696.888375ms)","trace[302669308] 'range keys from in-memory index tree' (duration: 176.559209ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:33.240424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:32.366861Z","time spent":"873.560551ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:34:33.240574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.83926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T17:34:33.240619Z","caller":"traceutil/trace.go:171","msg":"trace[1047369794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:281; }","duration":"423.912095ms","start":"2026-02-05T17:34:32.816689Z","end":"2026-02-05T17:34:33.240601Z","steps":["trace[1047369794] 'agreement among raft nodes before linearized reading' (duration: 247.143202ms)","trace[1047369794] 'range keys from in-memory index tree' (duration: 176.704709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:33.240665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:32.816671Z","time spent":"423.982769ms","remote":"127.0.0.1:39838","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-02-05T17:34:33.247285Z","caller":"traceutil/trace.go:171","msg":"trace[2100827306] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"180.069484ms","start":"2026-02-05T17:34:33.067197Z","end":"2026-02-05T17:34:33.247267Z","steps":["trace[2100827306] 'process raft request' (duration: 180.00282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.062184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.925087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T17:34:34.062332Z","caller":"traceutil/trace.go:171","msg":"trace[1302324845] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"102.137851ms","start":"2026-02-05T17:34:33.960178Z","end":"2026-02-05T17:34:34.062316Z","steps":["trace[1302324845] 'range keys from in-memory index tree' (duration: 101.844033ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:34.428727Z","caller":"traceutil/trace.go:171","msg":"trace[1746316757] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"118.156432ms","start":"2026-02-05T17:34:34.310543Z","end":"2026-02-05T17:34:34.4287Z","steps":["trace[1746316757] 'process raft request' (duration: 115.99383ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:34.58079Z","caller":"traceutil/trace.go:171","msg":"trace[1213036240] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"140.779683ms","start":"2026-02-05T17:34:34.439992Z","end":"2026-02-05T17:34:34.580772Z","steps":["trace[1213036240] 'process raft request' (duration: 140.365018ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:34.581232Z","caller":"traceutil/trace.go:171","msg":"trace[1019823599] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"121.077921ms","start":"2026-02-05T17:34:34.460149Z","end":"2026-02-05T17:34:34.581227Z","steps":["trace[1019823599] 'process raft request' (duration: 121.048999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.87265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.475726ms","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-02-05T17:34:34.873317Z","caller":"traceutil/trace.go:171","msg":"trace[1332853923] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:301; }","duration":"161.173808ms","start":"2026-02-05T17:34:34.712123Z","end":"2026-02-05T17:34:34.873297Z","steps":["trace[1332853923] 'range keys from in-memory index tree' (duration: 160.458165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.873764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.828101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T17:34:34.874046Z","caller":"traceutil/trace.go:171","msg":"trace[1935711090] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:301; }","duration":"164.127979ms","start":"2026-02-05T17:34:34.709875Z","end":"2026-02-05T17:34:34.874003Z","steps":["trace[1935711090] 'range keys from in-memory index tree' (duration: 163.626159ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:35.078988Z","caller":"traceutil/trace.go:171","msg":"trace[1746892965] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"119.394855ms","start":"2026-02-05T17:34:34.959574Z","end":"2026-02-05T17:34:35.078969Z","steps":["trace[1746892965] 'process raft request' (duration: 75.982551ms)","trace[1746892965] 'compare' (duration: 43.326009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:35.761284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.63528ms","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-02-05T17:34:35.761333Z","caller":"traceutil/trace.go:171","msg":"trace[832899502] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:310; }","duration":"150.720325ms","start":"2026-02-05T17:34:35.610602Z","end":"2026-02-05T17:34:35.761322Z","steps":["trace[832899502] 'range keys from in-memory index tree' (duration: 150.554835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:35.761562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.288274ms","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-02-05T17:34:35.761576Z","caller":"traceutil/trace.go:171","msg":"trace[83016016] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:310; }","duration":"102.329697ms","start":"2026-02-05T17:34:35.659242Z","end":"2026-02-05T17:34:35.761572Z","steps":["trace[83016016] 'range keys from in-memory index tree' (duration: 102.239771ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:36.396664Z","caller":"traceutil/trace.go:171","msg":"trace[402459310] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"117.730339ms","start":"2026-02-05T17:34:36.278916Z","end":"2026-02-05T17:34:36.396646Z","steps":["trace[402459310] 'process raft request' (duration: 117.683466ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:36.39669Z","caller":"traceutil/trace.go:171","msg":"trace[39141920] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"118.039978ms","start":"2026-02-05T17:34:36.278616Z","end":"2026-02-05T17:34:36.396656Z","steps":["trace[39141920] 'process raft request' (duration: 117.956823ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:36.397029Z","caller":"traceutil/trace.go:171","msg":"trace[704577664] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"118.556879ms","start":"2026-02-05T17:34:36.278466Z","end":"2026-02-05T17:34:36.397022Z","steps":["trace[704577664] 'process raft request' (duration: 70.508427ms)","trace[704577664] 'compare' (duration: 47.497298ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:37.538857Z","caller":"traceutil/trace.go:171","msg":"trace[1614946394] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"111.480336ms","start":"2026-02-05T17:34:37.427359Z","end":"2026-02-05T17:34:37.53884Z","steps":["trace[1614946394] 'process raft request' (duration: 94.204687ms)","trace[1614946394] 'compare' (duration: 17.108999ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:37.539066Z","caller":"traceutil/trace.go:171","msg":"trace[113814401] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"111.611103ms","start":"2026-02-05T17:34:37.427449Z","end":"2026-02-05T17:34:37.53906Z","steps":["trace[113814401] 'process raft request' (duration: 111.327376ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:43.63162Z","caller":"traceutil/trace.go:171","msg":"trace[1054900377] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"146.07413ms","start":"2026-02-05T17:34:43.485532Z","end":"2026-02-05T17:34:43.631606Z","steps":["trace[1054900377] 'process raft request' (duration: 145.806913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:43.987547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.861335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:43.987624Z","caller":"traceutil/trace.go:171","msg":"trace[176476031] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"349.451531ms","start":"2026-02-05T17:34:43.638159Z","end":"2026-02-05T17:34:43.987611Z","steps":["trace[176476031] 'process raft request' (duration: 77.471939ms)","trace[176476031] 'compare' (duration: 271.771129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:43.987657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:43.638146Z","time spent":"349.496884ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:49.517355Z","caller":"traceutil/trace.go:171","msg":"trace[148230906] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"172.845434ms","start":"2026-02-05T17:34:49.344494Z","end":"2026-02-05T17:34:49.517339Z","steps":["trace[148230906] 'process raft request' (duration: 172.756468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:50.005506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.017762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:34:50.005552Z","caller":"traceutil/trace.go:171","msg":"trace[1383189026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"351.111548ms","start":"2026-02-05T17:34:49.65443Z","end":"2026-02-05T17:34:50.005541Z","steps":["trace[1383189026] 'range keys from in-memory index tree' (duration: 350.951966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:50.005574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:49.654415Z","time spent":"351.154707ms","remote":"127.0.0.1:39838","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-02-05T17:34:50.005649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.898948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T17:34:50.005719Z","caller":"traceutil/trace.go:171","msg":"trace[1307275631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"331.991404ms","start":"2026-02-05T17:34:49.673711Z","end":"2026-02-05T17:34:50.005702Z","steps":["trace[1307275631] 'range keys from in-memory index tree' (duration: 331.85545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:50.005762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:49.673706Z","time spent":"332.048042ms","remote":"127.0.0.1:39838","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-02-05T17:34:50.005905Z","caller":"traceutil/trace.go:171","msg":"trace[1553470836] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"102.079465ms","start":"2026-02-05T17:34:49.903818Z","end":"2026-02-05T17:34:50.005897Z","steps":["trace[1553470836] 'process raft request' (duration: 99.766373ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:55.918759Z","caller":"traceutil/trace.go:171","msg":"trace[1500009034] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"352.664211ms","start":"2026-02-05T17:34:55.566069Z","end":"2026-02-05T17:34:55.918733Z","steps":["trace[1500009034] 'process raft request' (duration: 352.500906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.918956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:55.566053Z","time spent":"352.795187ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:34:56.228071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.52846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-05T17:34:56.228378Z","caller":"traceutil/trace.go:171","msg":"trace[1419710417] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:478; }","duration":"290.922107ms","start":"2026-02-05T17:34:55.937436Z","end":"2026-02-05T17:34:56.228358Z","steps":["trace[1419710417] 'range keys from in-memory index tree' (duration: 290.394685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:56.228903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.312539ms","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-02-05T17:34:56.229294Z","caller":"traceutil/trace.go:171","msg":"trace[733037055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"192.750205ms","start":"2026-02-05T17:34:56.036525Z","end":"2026-02-05T17:34:56.229275Z","steps":["trace[733037055] 'range keys from in-memory index tree' (duration: 192.178664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:56.228973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.893213ms","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-02-05T17:34:56.229909Z","caller":"traceutil/trace.go:171","msg":"trace[474850840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"192.857282ms","start":"2026-02-05T17:34:56.03704Z","end":"2026-02-05T17:34:56.229897Z","steps":["trace[474850840] 'range keys from in-memory index tree' (duration: 191.773468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:56.229006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.103343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T17:34:56.231249Z","caller":"traceutil/trace.go:171","msg":"trace[2023238305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"177.368189ms","start":"2026-02-05T17:34:56.053868Z","end":"2026-02-05T17:34:56.231236Z","steps":["trace[2023238305] 'range keys from in-memory index tree' (duration: 175.035086ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:02.603997Z","caller":"traceutil/trace.go:171","msg":"trace[1852762933] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"330.271862ms","start":"2026-02-05T17:35:02.273707Z","end":"2026-02-05T17:35:02.603979Z","steps":["trace[1852762933] 'read index received' (duration: 330.115246ms)","trace[1852762933] 'applied index is now lower than readState.Index' (duration: 155.956µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:02.604075Z","caller":"traceutil/trace.go:171","msg":"trace[1886538236] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"332.684021ms","start":"2026-02-05T17:35:02.271385Z","end":"2026-02-05T17:35:02.604069Z","steps":["trace[1886538236] 'process raft request' (duration: 332.493586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:02.60419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:02.271369Z","time spent":"332.725371ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:35:02.60437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.659942ms","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-02-05T17:35:02.604391Z","caller":"traceutil/trace.go:171","msg":"trace[2041398308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"330.689551ms","start":"2026-02-05T17:35:02.273695Z","end":"2026-02-05T17:35:02.604384Z","steps":["trace[2041398308] 'agreement among raft nodes before linearized reading' (duration: 330.605544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:02.604409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:02.27369Z","time spent":"330.714771ms","remote":"127.0.0.1:39838","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-02-05T17:35:02.60449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.56249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:35:02.604504Z","caller":"traceutil/trace.go:171","msg":"trace[921918161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"323.588309ms","start":"2026-02-05T17:35:02.280911Z","end":"2026-02-05T17:35:02.6045Z","steps":["trace[921918161] 'agreement among raft nodes before linearized reading' (duration: 323.545901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:02.60452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:02.280905Z","time spent":"323.610129ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:02.60461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.651764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5l7l9\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-05T17:35:02.604629Z","caller":"traceutil/trace.go:171","msg":"trace[906881447] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5l7l9; range_end:; response_count:1; response_revision:520; }","duration":"147.701412ms","start":"2026-02-05T17:35:02.456923Z","end":"2026-02-05T17:35:02.604625Z","steps":["trace[906881447] 'agreement among raft nodes before linearized reading' (duration: 147.637384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:02.968379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.717716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:35:02.968479Z","caller":"traceutil/trace.go:171","msg":"trace[1483261722] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"359.08641ms","start":"2026-02-05T17:35:02.609373Z","end":"2026-02-05T17:35:02.968459Z","steps":["trace[1483261722] 'process raft request' (duration: 245.213767ms)","trace[1483261722] 'compare' (duration: 113.403205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:02.968546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:02.609352Z","time spent":"359.163718ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:03.737016Z","caller":"traceutil/trace.go:171","msg":"trace[2046391117] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.111893845s","start":"2026-02-05T17:35:02.625107Z","end":"2026-02-05T17:35:03.737Z","steps":["trace[2046391117] 'process raft request' (duration: 1.111861676s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:03.73711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:02.625084Z","time spent":"1.111973954s","remote":"127.0.0.1:39754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:03.73732Z","caller":"traceutil/trace.go:171","msg":"trace[379868847] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.127915443s","start":"2026-02-05T17:35:02.609398Z","end":"2026-02-05T17:35:03.737313Z","steps":["trace[379868847] 'process raft request' (duration: 1.127443295s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:03.737348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:02.60937Z","time spent":"1.127961873s","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:03.797783Z","caller":"traceutil/trace.go:171","msg":"trace[1918677491] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"446.81188ms","start":"2026-02-05T17:35:03.350948Z","end":"2026-02-05T17:35:03.79776Z","steps":["trace[1918677491] 'read index received' (duration: 386.611988ms)","trace[1918677491] 'applied index is now lower than readState.Index' (duration: 60.198392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:03.797897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.926867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:03.797927Z","caller":"traceutil/trace.go:171","msg":"trace[1446362112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"446.999875ms","start":"2026-02-05T17:35:03.350918Z","end":"2026-02-05T17:35:03.797918Z","steps":["trace[1446362112] 'agreement among raft nodes before linearized reading' (duration: 446.926447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:03.797958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:03.350903Z","time spent":"447.048464ms","remote":"127.0.0.1:39546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:05.109076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493874229019767,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:35:05.610008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493874229019767,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:35:05.814641Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.862714675s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:35:05.815009Z","caller":"traceutil/trace.go:171","msg":"trace[1369198285] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"1.206564089s","start":"2026-02-05T17:35:04.608426Z","end":"2026-02-05T17:35:05.81499Z","steps":["trace[1369198285] 'read index received' (duration: 1.206385272s)","trace[1369198285] 'applied index is now lower than readState.Index' (duration: 177.997µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:05.815259Z","caller":"traceutil/trace.go:171","msg":"trace[216752267] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.863384811s","start":"2026-02-05T17:35:03.951862Z","end":"2026-02-05T17:35:05.815247Z","steps":["trace[216752267] 'process raft request' (duration: 1.863018019s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:05.815354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:03.951854Z","time spent":"1.863438009s","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:35:05.815599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207177456s","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-02-05T17:35:05.815638Z","caller":"traceutil/trace.go:171","msg":"trace[1798579444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"1.207251864s","start":"2026-02-05T17:35:04.608376Z","end":"2026-02-05T17:35:05.815628Z","steps":["trace[1798579444] 'agreement among raft nodes before linearized reading' (duration: 1.207116327s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:05.815677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:04.608354Z","time spent":"1.207311153s","remote":"127.0.0.1:39838","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-02-05T17:35:05.815838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.654582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T17:35:05.815872Z","caller":"traceutil/trace.go:171","msg":"trace[965565668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"844.707892ms","start":"2026-02-05T17:35:04.971155Z","end":"2026-02-05T17:35:05.815862Z","steps":["trace[965565668] 'agreement among raft nodes before linearized reading' (duration: 844.632323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:05.815902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:04.971137Z","time spent":"844.75846ms","remote":"127.0.0.1:39838","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-02-05T17:35:05.816215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.173028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:05.816263Z","caller":"traceutil/trace.go:171","msg":"trace[1604016565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"472.235757ms","start":"2026-02-05T17:35:05.344011Z","end":"2026-02-05T17:35:05.816247Z","steps":["trace[1604016565] 'agreement among raft nodes before linearized reading' (duration: 472.185488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:05.816294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:05.343997Z","time spent":"472.290526ms","remote":"127.0.0.1:39546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:35:06.602299Z","caller":"traceutil/trace.go:171","msg":"trace[1615162301] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"119.201092ms","start":"2026-02-05T17:35:06.483083Z","end":"2026-02-05T17:35:06.602284Z","steps":["trace[1615162301] 'process raft request' (duration: 119.107454ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:08.027687Z","caller":"traceutil/trace.go:171","msg":"trace[276216044] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"262.514146ms","start":"2026-02-05T17:35:07.765157Z","end":"2026-02-05T17:35:08.027671Z","steps":["trace[276216044] 'read index received' (duration: 262.388108ms)","trace[276216044] 'applied index is now lower than readState.Index' (duration: 125.468µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:08.02779Z","caller":"traceutil/trace.go:171","msg":"trace[560063145] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"350.573053ms","start":"2026-02-05T17:35:07.677189Z","end":"2026-02-05T17:35:08.027762Z","steps":["trace[560063145] 'process raft request' (duration: 350.398166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:08.02785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.674163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T17:35:08.027868Z","caller":"traceutil/trace.go:171","msg":"trace[786535329] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:540; }","duration":"262.733142ms","start":"2026-02-05T17:35:07.765129Z","end":"2026-02-05T17:35:08.027863Z","steps":["trace[786535329] 'agreement among raft nodes before linearized reading' (duration: 262.626814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:08.027899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:07.67718Z","time spent":"350.666472ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:35:08.028091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.923801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:35:08.028104Z","caller":"traceutil/trace.go:171","msg":"trace[89179514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"200.97273ms","start":"2026-02-05T17:35:07.827128Z","end":"2026-02-05T17:35:08.0281Z","steps":["trace[89179514] 'agreement among raft nodes before linearized reading' (duration: 200.918441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:08.028218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.629197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:35:08.028236Z","caller":"traceutil/trace.go:171","msg":"trace[1678289427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"174.654337ms","start":"2026-02-05T17:35:07.853572Z","end":"2026-02-05T17:35:08.028226Z","steps":["trace[1678289427] 'agreement among raft nodes before linearized reading' (duration: 174.618087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:08.028259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.919731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:35:08.028284Z","caller":"traceutil/trace.go:171","msg":"trace[2029729469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"173.96598ms","start":"2026-02-05T17:35:07.854311Z","end":"2026-02-05T17:35:08.028277Z","steps":["trace[2029729469] 'agreement among raft nodes before linearized reading' (duration: 173.856842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:08.028307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.967161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T17:35:08.028325Z","caller":"traceutil/trace.go:171","msg":"trace[810368429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"175.00419ms","start":"2026-02-05T17:35:07.853311Z","end":"2026-02-05T17:35:08.028315Z","steps":["trace[810368429] 'agreement among raft nodes before linearized reading' (duration: 174.970371ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:10.821413Z","caller":"traceutil/trace.go:171","msg":"trace[629216249] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"116.733489ms","start":"2026-02-05T17:35:10.704665Z","end":"2026-02-05T17:35:10.821399Z","steps":["trace[629216249] 'process raft request' (duration: 111.928705ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:24.655882Z","caller":"traceutil/trace.go:171","msg":"trace[1586342777] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"121.961982ms","start":"2026-02-05T17:36:24.533897Z","end":"2026-02-05T17:36:24.655859Z","steps":["trace[1586342777] 'process raft request' (duration: 121.832238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:24.878578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.06717ms","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-02-05T17:36:24.878795Z","caller":"traceutil/trace.go:171","msg":"trace[1945205299] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:927; }","duration":"166.296597ms","start":"2026-02-05T17:36:24.712478Z","end":"2026-02-05T17:36:24.878775Z","steps":["trace[1945205299] 'range keys from in-memory index tree' (duration: 166.028238ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:24.878926Z","caller":"traceutil/trace.go:171","msg":"trace[1631223378] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"343.557944ms","start":"2026-02-05T17:36:24.53515Z","end":"2026-02-05T17:36:24.878708Z","steps":["trace[1631223378] 'process raft request' (duration: 343.409669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:24.879105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:24.535144Z","time spent":"343.841352ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:36:32.520725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.692812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:36:32.520782Z","caller":"traceutil/trace.go:171","msg":"trace[1226886126] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:952; }","duration":"300.766225ms","start":"2026-02-05T17:36:32.220003Z","end":"2026-02-05T17:36:32.520769Z","steps":["trace[1226886126] 'count revisions from in-memory index tree' (duration: 300.580248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:32.52082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:32.219949Z","time spent":"300.860647ms","remote":"127.0.0.1:40100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-05T17:36:32.5209Z","caller":"traceutil/trace.go:171","msg":"trace[267460904] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"241.502277ms","start":"2026-02-05T17:36:32.27938Z","end":"2026-02-05T17:36:32.520883Z","steps":["trace[267460904] 'process raft request' (duration: 236.574582ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:32.527211Z","caller":"traceutil/trace.go:171","msg":"trace[106314555] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"242.759349ms","start":"2026-02-05T17:36:32.284438Z","end":"2026-02-05T17:36:32.527197Z","steps":["trace[106314555] 'process raft request' (duration: 242.508771ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:32.690684Z","caller":"traceutil/trace.go:171","msg":"trace[1677719668] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"158.022178ms","start":"2026-02-05T17:36:32.53264Z","end":"2026-02-05T17:36:32.690663Z","steps":["trace[1677719668] 'process raft request' (duration: 88.236818ms)","trace[1677719668] 'compare' (duration: 69.351325ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:32.690935Z","caller":"traceutil/trace.go:171","msg":"trace[181437401] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"156.082003ms","start":"2026-02-05T17:36:32.534817Z","end":"2026-02-05T17:36:32.690899Z","steps":["trace[181437401] 'process raft request' (duration: 155.511894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:33.498194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.327279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:36:33.498275Z","caller":"traceutil/trace.go:171","msg":"trace[285889488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"571.419612ms","start":"2026-02-05T17:36:32.926837Z","end":"2026-02-05T17:36:33.498256Z","steps":["trace[285889488] 'range keys from in-memory index tree' (duration: 571.195225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:33.498317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:32.926826Z","time spent":"571.481865ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:36:33.498576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.309026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:36:33.49864Z","caller":"traceutil/trace.go:171","msg":"trace[703832210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"590.41862ms","start":"2026-02-05T17:36:32.908205Z","end":"2026-02-05T17:36:33.498624Z","steps":["trace[703832210] 'range keys from in-memory index tree' (duration: 590.14016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:33.498683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:32.908186Z","time spent":"590.484022ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T17:36:33.546462Z","caller":"traceutil/trace.go:171","msg":"trace[569573171] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"198.11701ms","start":"2026-02-05T17:36:33.348324Z","end":"2026-02-05T17:36:33.546441Z","steps":["trace[569573171] 'read index received' (duration: 144.871369ms)","trace[569573171] 'applied index is now lower than readState.Index' (duration: 53.243861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:33.547187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.219323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:33.547505Z","caller":"traceutil/trace.go:171","msg":"trace[1121669026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"199.192607ms","start":"2026-02-05T17:36:33.348296Z","end":"2026-02-05T17:36:33.547489Z","steps":["trace[1121669026] 'agreement among raft nodes before linearized reading' (duration: 198.213053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:33.551325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.648287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:33.551413Z","caller":"traceutil/trace.go:171","msg":"trace[2026346261] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:959; }","duration":"195.748261ms","start":"2026-02-05T17:36:33.355654Z","end":"2026-02-05T17:36:33.551402Z","steps":["trace[2026346261] 'agreement among raft nodes before linearized reading' (duration: 195.112309ms)"],"step_count":1}