{"level":"warn","ts":"2026-02-10T21:27:15.086933Z","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-10T21:27:15.087023Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-10T21:27:15.087844Z","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-10T21:27:15.08787Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-10T21:27:15.087903Z","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-10T21:27:15.08847Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-02-10T21:27:15.088548Z","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://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-10T21:27:15.098733Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.001146ms"} {"level":"info","ts":"2026-02-10T21:27:15.1116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-02-10T21:27:15.111672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T21:27:15.11174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-02-10T21:27:15.111752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T21:27:15.111763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-02-10T21:27:15.11194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-02-10T21:27:15.124885Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T21:27:15.136974Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T21:27:15.142454Z","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-10T21:27:15.155285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T21:27:15.159767Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T21:27:15.160084Z","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-10T21:27:15.160109Z","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-10T21:27:15.160133Z","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-10T21:27:15.165595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-02-10T21:27:15.165692Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-10T21:27:15.167289Z","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-10T21:27:15.167556Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-10T21:27:15.167701Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-10T21:27:15.171258Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T21:27:15.171349Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T21:27:15.212686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T21:27:15.212803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T21:27:15.212899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-02-10T21:27:15.212933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-02-10T21:27:15.212963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-10T21:27:15.212985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-02-10T21:27:15.213001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-10T21:27:15.214349Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:27:15.215321Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T21:27:15.215363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:27:15.215648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:27:15.219054Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T21:27:15.220568Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-02-10T21:27:15.237023Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T21:27:15.239679Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T21:27:15.23992Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:27:15.240006Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:27:15.240046Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-10T21:27:33.907924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644355665800075,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:27:33.952098Z","caller":"traceutil/trace.go:171","msg":"trace[82828888] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"544.855754ms","start":"2026-02-10T21:27:33.40722Z","end":"2026-02-10T21:27:33.952076Z","steps":["trace[82828888] 'process raft request' (duration: 544.730571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:33.952867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:33.407196Z","time spent":"544.992956ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:27:33.95634Z","caller":"traceutil/trace.go:171","msg":"trace[1135176114] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:422; }","duration":"548.841764ms","start":"2026-02-10T21:27:33.407486Z","end":"2026-02-10T21:27:33.956328Z","steps":["trace[1135176114] 'read index received' (duration: 548.834194ms)","trace[1135176114] 'applied index is now lower than readState.Index' (duration: 6.53µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:33.956444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.946477ms","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-10T21:27:33.95648Z","caller":"traceutil/trace.go:171","msg":"trace[978129381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"548.996767ms","start":"2026-02-10T21:27:33.407474Z","end":"2026-02-10T21:27:33.956471Z","steps":["trace[978129381] 'agreement among raft nodes before linearized reading' (duration: 548.932506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:33.956501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:33.407467Z","time spent":"549.029028ms","remote":"127.0.0.1:33754","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-10T21:27:33.956621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.670216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-10T21:27:33.956644Z","caller":"traceutil/trace.go:171","msg":"trace[1380750687] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:408; }","duration":"434.715226ms","start":"2026-02-10T21:27:33.521924Z","end":"2026-02-10T21:27:33.956639Z","steps":["trace[1380750687] 'agreement among raft nodes before linearized reading' (duration: 434.672255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:33.956664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:33.52191Z","time spent":"434.750508ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-10T21:27:33.956815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.964043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:27:33.956839Z","caller":"traceutil/trace.go:171","msg":"trace[92597332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"195.024954ms","start":"2026-02-10T21:27:33.761809Z","end":"2026-02-10T21:27:33.956834Z","steps":["trace[92597332] 'agreement among raft nodes before linearized reading' (duration: 194.989234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:33.956934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.858968ms","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-10T21:27:33.956954Z","caller":"traceutil/trace.go:171","msg":"trace[1076853906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"379.917369ms","start":"2026-02-10T21:27:33.577032Z","end":"2026-02-10T21:27:33.95695Z","steps":["trace[1076853906] 'agreement among raft nodes before linearized reading' (duration: 379.883178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:33.956975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:33.577012Z","time spent":"379.95824ms","remote":"127.0.0.1:33754","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-10T21:27:34.697468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644355665800079,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T21:27:35.177736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215816895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:27:35.177807Z","caller":"traceutil/trace.go:171","msg":"trace[376426518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"1.215920157s","start":"2026-02-10T21:27:33.961869Z","end":"2026-02-10T21:27:35.177789Z","steps":["trace[376426518] 'range keys from in-memory index tree' (duration: 1.215751643s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:35.177846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:33.961801Z","time spent":"1.21603644s","remote":"127.0.0.1:33510","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-10T21:27:35.178244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.883944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:27:35.178304Z","caller":"traceutil/trace.go:171","msg":"trace[1213046455] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.215200601s","start":"2026-02-10T21:27:33.96309Z","end":"2026-02-10T21:27:35.178291Z","steps":["trace[1213046455] 'process raft request' (duration: 1.019082792s)","trace[1213046455] 'compare' (duration: 195.421474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:35.178353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:33.96308Z","time spent":"1.215247823s","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:27:35.179626Z","caller":"traceutil/trace.go:171","msg":"trace[1562148948] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.215439796s","start":"2026-02-10T21:27:33.96417Z","end":"2026-02-10T21:27:35.17961Z","steps":["trace[1562148948] 'process raft request' (duration: 1.214612557s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:35.179754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:33.964151Z","time spent":"1.21556022s","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:27:35.18054Z","caller":"traceutil/trace.go:171","msg":"trace[1026435837] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.21510094s","start":"2026-02-10T21:27:33.965425Z","end":"2026-02-10T21:27:35.180526Z","steps":["trace[1026435837] 'process raft request' (duration: 1.213416221s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:35.181368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:33.965418Z","time spent":"1.215905448s","remote":"127.0.0.1:33754","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-10T21:27:35.218456Z","caller":"traceutil/trace.go:171","msg":"trace[892947510] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:422; }","duration":"1.021399315s","start":"2026-02-10T21:27:34.197046Z","end":"2026-02-10T21:27:35.218446Z","steps":["trace[892947510] 'read index received' (duration: 785.13616ms)","trace[892947510] 'applied index is now lower than readState.Index' (duration: 236.262515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:35.218677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.711393ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:27:35.218763Z","caller":"traceutil/trace.go:171","msg":"trace[755375857] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:411; }","duration":"742.806955ms","start":"2026-02-10T21:27:34.475944Z","end":"2026-02-10T21:27:35.218751Z","steps":["trace[755375857] 'agreement among raft nodes before linearized reading' (duration: 742.696903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:35.219233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022190953s","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-02-10T21:27:35.219255Z","caller":"traceutil/trace.go:171","msg":"trace[980977861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"1.022275004s","start":"2026-02-10T21:27:34.196975Z","end":"2026-02-10T21:27:35.21925Z","steps":["trace[980977861] 'agreement among raft nodes before linearized reading' (duration: 1.022235524s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:35.21927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:34.196929Z","time spent":"1.022337196s","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:27:45.732429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644355665800206,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:27:45.917025Z","caller":"traceutil/trace.go:171","msg":"trace[1060387368] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"685.07698ms","start":"2026-02-10T21:27:45.231912Z","end":"2026-02-10T21:27:45.916989Z","steps":["trace[1060387368] 'read index received' (duration: 684.902996ms)","trace[1060387368] 'applied index is now lower than readState.Index' (duration: 173.114µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:27:45.917269Z","caller":"traceutil/trace.go:171","msg":"trace[153529436] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"686.017322ms","start":"2026-02-10T21:27:45.231241Z","end":"2026-02-10T21:27:45.917258Z","steps":["trace[153529436] 'process raft request' (duration: 685.623402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:45.917363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:45.231231Z","time spent":"686.068283ms","remote":"127.0.0.1:33754","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-10T21:27:45.91757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.654304ms","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-10T21:27:45.917602Z","caller":"traceutil/trace.go:171","msg":"trace[1181683579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"685.709155ms","start":"2026-02-10T21:27:45.231882Z","end":"2026-02-10T21:27:45.917592Z","steps":["trace[1181683579] 'agreement among raft nodes before linearized reading' (duration: 685.613383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:45.917629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:45.231866Z","time spent":"685.756976ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:27:45.91776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.345482ms","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-02-10T21:27:45.917785Z","caller":"traceutil/trace.go:171","msg":"trace[1290361137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"660.393223ms","start":"2026-02-10T21:27:45.257385Z","end":"2026-02-10T21:27:45.917778Z","steps":["trace[1290361137] 'agreement among raft nodes before linearized reading' (duration: 660.351122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:45.917811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:45.257371Z","time spent":"660.432764ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:27:45.917967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.457983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:27:45.917995Z","caller":"traceutil/trace.go:171","msg":"trace[90393460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"156.519985ms","start":"2026-02-10T21:27:45.761467Z","end":"2026-02-10T21:27:45.917987Z","steps":["trace[90393460] 'agreement among raft nodes before linearized reading' (duration: 156.480224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:45.918124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.555006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-10T21:27:45.918148Z","caller":"traceutil/trace.go:171","msg":"trace[1427350] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:469; }","duration":"319.612967ms","start":"2026-02-10T21:27:45.598528Z","end":"2026-02-10T21:27:45.918141Z","steps":["trace[1427350] 'agreement among raft nodes before linearized reading' (duration: 319.567576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:45.918171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:45.598515Z","time spent":"319.650468ms","remote":"127.0.0.1:33670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-10T21:27:48.02282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.940144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:27:48.022864Z","caller":"traceutil/trace.go:171","msg":"trace[1167894643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"254.008096ms","start":"2026-02-10T21:27:47.768845Z","end":"2026-02-10T21:27:48.022853Z","steps":["trace[1167894643] 'range keys from in-memory index tree' (duration: 253.902813ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:48.179574Z","caller":"traceutil/trace.go:171","msg":"trace[464488503] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"152.984574ms","start":"2026-02-10T21:27:48.026564Z","end":"2026-02-10T21:27:48.179549Z","steps":["trace[464488503] 'process raft request' (duration: 152.844711ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:48.204214Z","caller":"traceutil/trace.go:171","msg":"trace[1201084405] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"175.867122ms","start":"2026-02-10T21:27:48.028332Z","end":"2026-02-10T21:27:48.204199Z","steps":["trace[1201084405] 'process raft request' (duration: 175.839201ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:48.204487Z","caller":"traceutil/trace.go:171","msg":"trace[46325863] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"176.337992ms","start":"2026-02-10T21:27:48.028144Z","end":"2026-02-10T21:27:48.204482Z","steps":["trace[46325863] 'process raft request' (duration: 175.905693ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:48.204549Z","caller":"traceutil/trace.go:171","msg":"trace[276165315] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"176.319012ms","start":"2026-02-10T21:27:48.028226Z","end":"2026-02-10T21:27:48.204545Z","steps":["trace[276165315] 'process raft request' (duration: 175.923153ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:52.640001Z","caller":"traceutil/trace.go:171","msg":"trace[895463791] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"444.16991ms","start":"2026-02-10T21:27:52.195729Z","end":"2026-02-10T21:27:52.639898Z","steps":["trace[895463791] 'process raft request' (duration: 444.020977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:52.640133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.195705Z","time spent":"444.359344ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:27:52.640466Z","caller":"traceutil/trace.go:171","msg":"trace[617647563] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:506; }","duration":"421.025648ms","start":"2026-02-10T21:27:52.219429Z","end":"2026-02-10T21:27:52.640455Z","steps":["trace[617647563] 'read index received' (duration: 421.021878ms)","trace[617647563] 'applied index is now lower than readState.Index' (duration: 3.09µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:52.641548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.068676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T21:27:52.641699Z","caller":"traceutil/trace.go:171","msg":"trace[80086995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"416.22183ms","start":"2026-02-10T21:27:52.225465Z","end":"2026-02-10T21:27:52.641686Z","steps":["trace[80086995] 'agreement among raft nodes before linearized reading' (duration: 415.053134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:52.641856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.225432Z","time spent":"416.410654ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:27:52.642231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.806628ms","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-10T21:27:52.642486Z","caller":"traceutil/trace.go:171","msg":"trace[1267945987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"423.093144ms","start":"2026-02-10T21:27:52.21938Z","end":"2026-02-10T21:27:52.642473Z","steps":["trace[1267945987] 'agreement among raft nodes before linearized reading' (duration: 422.782787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:52.642617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.21936Z","time spent":"423.242917ms","remote":"127.0.0.1:33754","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-10T21:27:52.642968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.578117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bq2v5\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T21:27:52.643232Z","caller":"traceutil/trace.go:171","msg":"trace[1414261661] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bq2v5; range_end:; response_count:1; response_revision:488; }","duration":"389.836364ms","start":"2026-02-10T21:27:52.25338Z","end":"2026-02-10T21:27:52.643216Z","steps":["trace[1414261661] 'agreement among raft nodes before linearized reading' (duration: 389.512056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:52.643369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.253347Z","time spent":"390.008108ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-bq2v5\" "} {"level":"warn","ts":"2026-02-10T21:27:52.643714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.79098ms","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-10T21:27:52.643944Z","caller":"traceutil/trace.go:171","msg":"trace[886953463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"416.028396ms","start":"2026-02-10T21:27:52.227899Z","end":"2026-02-10T21:27:52.643928Z","steps":["trace[886953463] 'agreement among raft nodes before linearized reading' (duration: 415.744609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:52.644067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.227893Z","time spent":"416.160088ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T21:27:53.251047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.472719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:27:53.251141Z","caller":"traceutil/trace.go:171","msg":"trace[1706987078] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"601.605655ms","start":"2026-02-10T21:27:52.649518Z","end":"2026-02-10T21:27:53.251123Z","steps":["trace[1706987078] 'process raft request' (duration: 283.980492ms)","trace[1706987078] 'compare' (duration: 317.371957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:53.251196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.649503Z","time spent":"601.667518ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:27:53.382688Z","caller":"traceutil/trace.go:171","msg":"trace[1846390675] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:506; }","duration":"619.326555ms","start":"2026-02-10T21:27:52.763347Z","end":"2026-02-10T21:27:53.382674Z","steps":["trace[1846390675] 'read index received' (duration: 170.159552ms)","trace[1846390675] 'applied index is now lower than readState.Index' (duration: 449.166313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:53.382803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.46006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:27:53.382824Z","caller":"traceutil/trace.go:171","msg":"trace[2091043429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"619.95447ms","start":"2026-02-10T21:27:52.762864Z","end":"2026-02-10T21:27:53.382818Z","steps":["trace[2091043429] 'agreement among raft nodes before linearized reading' (duration: 619.869668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:53.382847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.76279Z","time spent":"620.050893ms","remote":"127.0.0.1:33498","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-10T21:27:53.383341Z","caller":"traceutil/trace.go:171","msg":"trace[1414388122] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"730.154049ms","start":"2026-02-10T21:27:52.653052Z","end":"2026-02-10T21:27:53.383206Z","steps":["trace[1414388122] 'process raft request' (duration: 729.471593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:53.383413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.653046Z","time spent":"730.324133ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:27:53.383467Z","caller":"traceutil/trace.go:171","msg":"trace[1019288218] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"730.355983ms","start":"2026-02-10T21:27:52.653106Z","end":"2026-02-10T21:27:53.383462Z","steps":["trace[1019288218] 'process raft request' (duration: 729.507023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:53.383494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.653101Z","time spent":"730.376923ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:27:53.383997Z","caller":"traceutil/trace.go:171","msg":"trace[807283937] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"726.743391ms","start":"2026-02-10T21:27:52.657242Z","end":"2026-02-10T21:27:53.383986Z","steps":["trace[807283937] 'process raft request' (duration: 725.40232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:53.384054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:52.657236Z","time spent":"726.786292ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:27:55.660046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.215173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:27:55.660224Z","caller":"traceutil/trace.go:171","msg":"trace[1614564313] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"247.859078ms","start":"2026-02-10T21:27:55.412349Z","end":"2026-02-10T21:27:55.660208Z","steps":["trace[1614564313] 'process raft request' (duration: 247.804936ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:55.66043Z","caller":"traceutil/trace.go:171","msg":"trace[1585539886] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"262.684712ms","start":"2026-02-10T21:27:55.397715Z","end":"2026-02-10T21:27:55.6604Z","steps":["trace[1585539886] 'process raft request' (duration: 42.035879ms)","trace[1585539886] 'compare' (duration: 220.08769ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:27:55.663941Z","caller":"traceutil/trace.go:171","msg":"trace[1270893103] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"130.815145ms","start":"2026-02-10T21:27:55.533111Z","end":"2026-02-10T21:27:55.663927Z","steps":["trace[1270893103] 'process raft request' (duration: 130.61308ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:58.588333Z","caller":"traceutil/trace.go:171","msg":"trace[698410087] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"112.697505ms","start":"2026-02-10T21:27:58.47562Z","end":"2026-02-10T21:27:58.588317Z","steps":["trace[698410087] 'process raft request' (duration: 108.947469ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:13.5971Z","caller":"traceutil/trace.go:171","msg":"trace[1468082328] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"209.275625ms","start":"2026-02-10T21:28:13.387802Z","end":"2026-02-10T21:28:13.597078Z","steps":["trace[1468082328] 'read index received' (duration: 209.0727ms)","trace[1468082328] 'applied index is now lower than readState.Index' (duration: 202.155µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:13.597305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.490619ms","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-10T21:28:13.597337Z","caller":"traceutil/trace.go:171","msg":"trace[528265734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"209.603032ms","start":"2026-02-10T21:28:13.387723Z","end":"2026-02-10T21:28:13.597326Z","steps":["trace[528265734] 'agreement among raft nodes before linearized reading' (duration: 209.446879ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:13.597617Z","caller":"traceutil/trace.go:171","msg":"trace[840454167] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"341.373389ms","start":"2026-02-10T21:28:13.256233Z","end":"2026-02-10T21:28:13.597606Z","steps":["trace[840454167] 'process raft request' (duration: 340.692593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:13.597694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:13.256222Z","time spent":"341.42042ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:15.82119Z","caller":"traceutil/trace.go:171","msg":"trace[1639340620] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"138.776783ms","start":"2026-02-10T21:28:15.682396Z","end":"2026-02-10T21:28:15.821173Z","steps":["trace[1639340620] 'read index received' (duration: 138.64007ms)","trace[1639340620] 'applied index is now lower than readState.Index' (duration: 135.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:28:15.821435Z","caller":"traceutil/trace.go:171","msg":"trace[1266807701] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"215.216379ms","start":"2026-02-10T21:28:15.606207Z","end":"2026-02-10T21:28:15.821424Z","steps":["trace[1266807701] 'process raft request' (duration: 214.838621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:15.821714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.300896ms","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-10T21:28:15.821751Z","caller":"traceutil/trace.go:171","msg":"trace[1446036117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"139.375698ms","start":"2026-02-10T21:28:15.682364Z","end":"2026-02-10T21:28:15.82174Z","steps":["trace[1446036117] 'agreement among raft nodes before linearized reading' (duration: 139.243234ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:16.995615Z","caller":"traceutil/trace.go:171","msg":"trace[1165451227] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"236.367248ms","start":"2026-02-10T21:28:16.759232Z","end":"2026-02-10T21:28:16.995599Z","steps":["trace[1165451227] 'process raft request' (duration: 235.956808ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:21.289009Z","caller":"traceutil/trace.go:171","msg":"trace[500423862] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"282.092465ms","start":"2026-02-10T21:28:21.006895Z","end":"2026-02-10T21:28:21.288987Z","steps":["trace[500423862] 'process raft request' (duration: 281.994112ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:39.045741Z","caller":"traceutil/trace.go:171","msg":"trace[553932637] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"106.626128ms","start":"2026-02-10T21:28:38.939102Z","end":"2026-02-10T21:28:39.045728Z","steps":["trace[553932637] 'process raft request' (duration: 106.539306ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:42.443302Z","caller":"traceutil/trace.go:171","msg":"trace[1817532242] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"358.374497ms","start":"2026-02-10T21:28:42.084912Z","end":"2026-02-10T21:28:42.443286Z","steps":["trace[1817532242] 'process raft request' (duration: 358.277605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:42.443797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:42.084898Z","time spent":"358.451318ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:42.542625Z","caller":"traceutil/trace.go:171","msg":"trace[1999469405] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"457.567259ms","start":"2026-02-10T21:28:42.085037Z","end":"2026-02-10T21:28:42.542604Z","steps":["trace[1999469405] 'process raft request' (duration: 457.380925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:42.542784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:42.085031Z","time spent":"457.702092ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:42.543162Z","caller":"traceutil/trace.go:171","msg":"trace[1075561803] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"457.531958ms","start":"2026-02-10T21:28:42.085606Z","end":"2026-02-10T21:28:42.543138Z","steps":["trace[1075561803] 'process raft request' (duration: 456.968665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:42.54329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:42.085588Z","time spent":"457.631001ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:53.514752Z","caller":"traceutil/trace.go:171","msg":"trace[2118407886] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"219.228692ms","start":"2026-02-10T21:28:53.295502Z","end":"2026-02-10T21:28:53.514731Z","steps":["trace[2118407886] 'process raft request' (duration: 218.731541ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:55.002284Z","caller":"traceutil/trace.go:171","msg":"trace[2142980919] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"503.828801ms","start":"2026-02-10T21:28:54.498432Z","end":"2026-02-10T21:28:55.002261Z","steps":["trace[2142980919] 'process raft request' (duration: 503.650147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:55.002439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:54.498423Z","time spent":"503.944664ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:55.002873Z","caller":"traceutil/trace.go:171","msg":"trace[847925302] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:885; }","duration":"387.376883ms","start":"2026-02-10T21:28:54.615484Z","end":"2026-02-10T21:28:55.002861Z","steps":["trace[847925302] 'read index received' (duration: 387.372943ms)","trace[847925302] 'applied index is now lower than readState.Index' (duration: 3.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:55.003005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.256853ms","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-10T21:28:55.003032Z","caller":"traceutil/trace.go:171","msg":"trace[1994853752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:849; }","duration":"354.318165ms","start":"2026-02-10T21:28:54.648705Z","end":"2026-02-10T21:28:55.003023Z","steps":["trace[1994853752] 'agreement among raft nodes before linearized reading' (duration: 354.218563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:55.00306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:54.648689Z","time spent":"354.364947ms","remote":"127.0.0.1:33754","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-10T21:28:55.003966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.481837ms","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-10T21:28:55.003982Z","caller":"traceutil/trace.go:171","msg":"trace[1293280877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:849; }","duration":"388.523558ms","start":"2026-02-10T21:28:54.615454Z","end":"2026-02-10T21:28:55.003978Z","steps":["trace[1293280877] 'agreement among raft nodes before linearized reading' (duration: 388.478377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:55.004016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:54.615441Z","time spent":"388.570839ms","remote":"127.0.0.1:33754","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-10T21:28:55.004084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.067612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:28:55.004112Z","caller":"traceutil/trace.go:171","msg":"trace[836794866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"243.117664ms","start":"2026-02-10T21:28:54.76099Z","end":"2026-02-10T21:28:55.004108Z","steps":["trace[836794866] 'agreement among raft nodes before linearized reading' (duration: 243.083053ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:55.875406Z","caller":"traceutil/trace.go:171","msg":"trace[419711196] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"864.730803ms","start":"2026-02-10T21:28:55.010653Z","end":"2026-02-10T21:28:55.875383Z","steps":["trace[419711196] 'process raft request' (duration: 864.569609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:55.875546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:55.010643Z","time spent":"864.830706ms","remote":"127.0.0.1:33754","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-10T21:28:56.152274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.712698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:28:56.152329Z","caller":"traceutil/trace.go:171","msg":"trace[341810831] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:885; }","duration":"629.873534ms","start":"2026-02-10T21:28:55.522446Z","end":"2026-02-10T21:28:56.15232Z","steps":["trace[341810831] 'read index received' (duration: 352.768851ms)","trace[341810831] 'applied index is now lower than readState.Index' (duration: 277.104083ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:28:56.152439Z","caller":"traceutil/trace.go:171","msg":"trace[1065305151] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"1.141721584s","start":"2026-02-10T21:28:55.010712Z","end":"2026-02-10T21:28:56.152433Z","steps":["trace[1065305151] 'process raft request' (duration: 910.802712ms)","trace[1065305151] 'compare' (duration: 230.496782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:56.152467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:55.010708Z","time spent":"1.141745174s","remote":"127.0.0.1:33754","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-10T21:28:56.152593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.16232ms","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-10T21:28:56.152606Z","caller":"traceutil/trace.go:171","msg":"trace[2146783857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:851; }","duration":"630.192352ms","start":"2026-02-10T21:28:55.522409Z","end":"2026-02-10T21:28:56.152602Z","steps":["trace[2146783857] 'agreement among raft nodes before linearized reading' (duration: 630.13733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:56.152621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:55.522394Z","time spent":"630.224372ms","remote":"127.0.0.1:33754","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":"info","ts":"2026-02-10T21:28:59.271332Z","caller":"traceutil/trace.go:171","msg":"trace[642431653] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"232.888327ms","start":"2026-02-10T21:28:59.03843Z","end":"2026-02-10T21:28:59.271318Z","steps":["trace[642431653] 'process raft request' (duration: 232.806576ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:59.651796Z","caller":"traceutil/trace.go:171","msg":"trace[1497794238] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"146.534998ms","start":"2026-02-10T21:28:59.505245Z","end":"2026-02-10T21:28:59.65178Z","steps":["trace[1497794238] 'process raft request' (duration: 129.94312ms)","trace[1497794238] 'compare' (duration: 16.265081ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:08.606523Z","caller":"traceutil/trace.go:171","msg":"trace[862520699] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"350.115772ms","start":"2026-02-10T21:29:08.256392Z","end":"2026-02-10T21:29:08.606507Z","steps":["trace[862520699] 'process raft request' (duration: 350.055041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:08.606617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:08.256382Z","time spent":"350.186373ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:29:08.606824Z","caller":"traceutil/trace.go:171","msg":"trace[964616630] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"350.445629ms","start":"2026-02-10T21:29:08.256372Z","end":"2026-02-10T21:29:08.606817Z","steps":["trace[964616630] 'process raft request' (duration: 349.963838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:08.606851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:08.256342Z","time spent":"350.49433ms","remote":"127.0.0.1:33754","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-10T21:29:09.129652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.468639ms","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-10T21:29:09.129726Z","caller":"traceutil/trace.go:171","msg":"trace[1767143376] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:885; }","duration":"362.55009ms","start":"2026-02-10T21:29:08.767165Z","end":"2026-02-10T21:29:09.129715Z","steps":["trace[1767143376] 'range keys from in-memory index tree' (duration: 362.458738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:09.129988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.265602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:29:09.130015Z","caller":"traceutil/trace.go:171","msg":"trace[202417721] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"487.771423ms","start":"2026-02-10T21:29:08.642239Z","end":"2026-02-10T21:29:09.13001Z","steps":["trace[202417721] 'read index received' (duration: 104.387048ms)","trace[202417721] 'applied index is now lower than readState.Index' (duration: 383.383845ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:09.130112Z","caller":"traceutil/trace.go:171","msg":"trace[685139883] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"870.80951ms","start":"2026-02-10T21:29:08.259297Z","end":"2026-02-10T21:29:09.130106Z","steps":["trace[685139883] 'process raft request' (duration: 487.393175ms)","trace[685139883] 'compare' (duration: 382.857673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:09.130141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:08.259289Z","time spent":"870.83706ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:29:09.130228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.990449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:09.130241Z","caller":"traceutil/trace.go:171","msg":"trace[543366308] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:886; }","duration":"488.036029ms","start":"2026-02-10T21:29:08.642201Z","end":"2026-02-10T21:29:09.130237Z","steps":["trace[543366308] 'agreement among raft nodes before linearized reading' (duration: 488.013118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:09.130257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:08.642184Z","time spent":"488.06947ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-10T21:29:09.13034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.557988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:09.130354Z","caller":"traceutil/trace.go:171","msg":"trace[1716703597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"370.600989ms","start":"2026-02-10T21:29:08.759749Z","end":"2026-02-10T21:29:09.13035Z","steps":["trace[1716703597] 'agreement among raft nodes before linearized reading' (duration: 370.565649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:09.130365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:08.759734Z","time spent":"370.62897ms","remote":"127.0.0.1:33498","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-10T21:29:09.130503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.083197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T21:29:09.130515Z","caller":"traceutil/trace.go:171","msg":"trace[1880050783] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:886; }","duration":"201.113368ms","start":"2026-02-10T21:29:08.929398Z","end":"2026-02-10T21:29:09.130511Z","steps":["trace[1880050783] 'agreement among raft nodes before linearized reading' (duration: 201.060317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:09.130581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.05799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:09.130593Z","caller":"traceutil/trace.go:171","msg":"trace[2058391405] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:886; }","duration":"237.104301ms","start":"2026-02-10T21:29:08.893484Z","end":"2026-02-10T21:29:09.130589Z","steps":["trace[2058391405] 'agreement among raft nodes before linearized reading' (duration: 237.08576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:10.326269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.637063ms","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-10T21:29:10.326338Z","caller":"traceutil/trace.go:171","msg":"trace[507481611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"272.745115ms","start":"2026-02-10T21:29:10.053576Z","end":"2026-02-10T21:29:10.326321Z","steps":["trace[507481611] 'range keys from in-memory index tree' (duration: 272.435639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:13.893398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.863529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:13.893724Z","caller":"traceutil/trace.go:171","msg":"trace[1592052682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"131.264639ms","start":"2026-02-10T21:29:13.762443Z","end":"2026-02-10T21:29:13.893708Z","steps":["trace[1592052682] 'range keys from in-memory index tree' (duration: 130.780437ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:14.50469Z","caller":"traceutil/trace.go:171","msg":"trace[1505393585] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"526.001285ms","start":"2026-02-10T21:29:13.978671Z","end":"2026-02-10T21:29:14.504672Z","steps":["trace[1505393585] 'process raft request' (duration: 442.35933ms)","trace[1505393585] 'compare' (duration: 83.130604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:14.504795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:13.978649Z","time spent":"526.085517ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:29:14.506258Z","caller":"traceutil/trace.go:171","msg":"trace[729862384] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:952; }","duration":"184.570108ms","start":"2026-02-10T21:29:14.321681Z","end":"2026-02-10T21:29:14.506251Z","steps":["trace[729862384] 'read index received' (duration: 99.357827ms)","trace[729862384] 'applied index is now lower than readState.Index' (duration: 85.211721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:14.506422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.738582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:29:14.506439Z","caller":"traceutil/trace.go:171","msg":"trace[1864236090] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:914; }","duration":"184.795613ms","start":"2026-02-10T21:29:14.321639Z","end":"2026-02-10T21:29:14.506434Z","steps":["trace[1864236090] 'agreement among raft nodes before linearized reading' (duration: 184.748322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:14.506548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.055103ms","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-10T21:29:14.50656Z","caller":"traceutil/trace.go:171","msg":"trace[2110733442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"118.100715ms","start":"2026-02-10T21:29:14.388455Z","end":"2026-02-10T21:29:14.506556Z","steps":["trace[2110733442] 'agreement among raft nodes before linearized reading' (duration: 118.048614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:14.506656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.705347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4740"} {"level":"info","ts":"2026-02-10T21:29:14.506667Z","caller":"traceutil/trace.go:171","msg":"trace[1825317071] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:914; }","duration":"100.740327ms","start":"2026-02-10T21:29:14.405924Z","end":"2026-02-10T21:29:14.506664Z","steps":["trace[1825317071] 'agreement among raft nodes before linearized reading' (duration: 100.702556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:14.724107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.859928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-nwkkx\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-10T21:29:14.72415Z","caller":"traceutil/trace.go:171","msg":"trace[1106145991] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-nwkkx; range_end:; response_count:1; response_revision:917; }","duration":"116.933039ms","start":"2026-02-10T21:29:14.607207Z","end":"2026-02-10T21:29:14.72414Z","steps":["trace[1106145991] 'range keys from in-memory index tree' (duration: 116.794956ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:29.246819Z","caller":"traceutil/trace.go:171","msg":"trace[1746536451] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"316.180933ms","start":"2026-02-10T21:29:28.930609Z","end":"2026-02-10T21:29:29.24679Z","steps":["trace[1746536451] 'read index received' (duration: 310.085938ms)","trace[1746536451] 'applied index is now lower than readState.Index' (duration: 6.093365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:29.247108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.473019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T21:29:29.247144Z","caller":"traceutil/trace.go:171","msg":"trace[1930042377] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:1011; }","duration":"316.559072ms","start":"2026-02-10T21:29:28.930572Z","end":"2026-02-10T21:29:29.247131Z","steps":["trace[1930042377] 'agreement among raft nodes before linearized reading' (duration: 316.328637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:29.247177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:28.930557Z","time spent":"316.611912ms","remote":"127.0.0.1:33540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"info","ts":"2026-02-10T21:29:29.24756Z","caller":"traceutil/trace.go:171","msg":"trace[2054628302] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"432.38029ms","start":"2026-02-10T21:29:28.815153Z","end":"2026-02-10T21:29:29.247533Z","steps":["trace[2054628302] 'process raft request' (duration: 425.495448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:29.247731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:28.815131Z","time spent":"432.508383ms","remote":"127.0.0.1:33754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}