{"level":"warn","ts":"2026-03-02T21:21:09.230393Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T21:21:09.2305Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.213:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.213:2380","--initial-cluster=instance=https://199.19.213.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.213: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-03-02T21:21:09.230569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T21:21:09.230578Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-03-02T21:21:09.230602Z","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-03-02T21:21:09.231764Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"]} {"level":"info","ts":"2026-03-02T21:21:09.231845Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.213: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-03-02T21:21:09.238862Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.725284ms"} {"level":"info","ts":"2026-03-02T21:21:09.248514Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3faf6a3dfbe0100","cluster-id":"4c2e50e35fb92a79"} {"level":"info","ts":"2026-03-02T21:21:09.248576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T21:21:09.248598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 0"} {"level":"info","ts":"2026-03-02T21:21:09.248608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3faf6a3dfbe0100 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T21:21:09.248616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 1"} {"level":"info","ts":"2026-03-02T21:21:09.248647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"warn","ts":"2026-03-02T21:21:09.257676Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T21:21:09.260672Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T21:21:09.2668Z","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-03-02T21:21:09.271495Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3faf6a3dfbe0100","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T21:21:09.271802Z","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-03-02T21:21:09.271809Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3faf6a3dfbe0100","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T21:21:09.275339Z","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-03-02T21:21:09.275377Z","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-03-02T21:21:09.275762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"info","ts":"2026-03-02T21:21:09.280687Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","added-peer-id":"f3faf6a3dfbe0100","added-peer-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-03-02T21:21:09.282501Z","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-03-02T21:21:09.2826Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-03-02T21:21:09.282691Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-03-02T21:21:09.282891Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3faf6a3dfbe0100","initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T21:21:09.282962Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T21:21:10.24919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T21:21:10.249255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T21:21:10.249275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgPreVoteResp from f3faf6a3dfbe0100 at term 1"} {"level":"info","ts":"2026-03-02T21:21:10.24929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became candidate at term 2"} {"level":"info","ts":"2026-03-02T21:21:10.249298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgVoteResp from f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-03-02T21:21:10.249309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became leader at term 2"} {"level":"info","ts":"2026-03-02T21:21:10.249318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3faf6a3dfbe0100 elected leader f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-03-02T21:21:10.296861Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:21:10.32666Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3faf6a3dfbe0100","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.213:2379]}","request-path":"/0/members/f3faf6a3dfbe0100/attributes","cluster-id":"4c2e50e35fb92a79","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T21:21:10.326667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:21:10.326787Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:21:10.32685Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T21:21:10.32691Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T21:21:10.330163Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.213:2379"} {"level":"info","ts":"2026-03-02T21:21:10.330709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T21:21:10.345985Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:21:10.346091Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:21:10.346148Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:21:12.932522Z","caller":"traceutil/trace.go:171","msg":"trace[307603426] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"148.797322ms","start":"2026-03-02T21:21:12.783699Z","end":"2026-03-02T21:21:12.932496Z","steps":["trace[307603426] 'process raft request' (duration: 140.172511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:16.988549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.126064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:21:16.988799Z","caller":"traceutil/trace.go:171","msg":"trace[2132982994] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"1.374607586s","start":"2026-03-02T21:21:15.614165Z","end":"2026-03-02T21:21:16.988773Z","steps":["trace[2132982994] 'process raft request' (duration: 495.974514ms)","trace[2132982994] 'compare' (duration: 872.586841ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:16.988834Z","caller":"traceutil/trace.go:171","msg":"trace[1157015852] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"1.251528074s","start":"2026-03-02T21:21:15.737277Z","end":"2026-03-02T21:21:16.988805Z","steps":["trace[1157015852] 'process raft request' (duration: 1.251430092s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:16.988936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:15.614136Z","time spent":"1.374730019s","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:21:16.988872Z","caller":"traceutil/trace.go:171","msg":"trace[566949094] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:302; }","duration":"1.341690998s","start":"2026-03-02T21:21:15.64712Z","end":"2026-03-02T21:21:16.988811Z","steps":["trace[566949094] 'read index received' (duration: 463.037315ms)","trace[566949094] 'applied index is now lower than readState.Index' (duration: 878.649513ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:16.98891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:15.73725Z","time spent":"1.251622656s","remote":"127.0.0.1:57158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:21:16.989724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342585718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-02T21:21:16.989874Z","caller":"traceutil/trace.go:171","msg":"trace[1527045247] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:297; }","duration":"1.342777823s","start":"2026-03-02T21:21:15.647072Z","end":"2026-03-02T21:21:16.98985Z","steps":["trace[1527045247] 'agreement among raft nodes before linearized reading' (duration: 1.341842651s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:16.990442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:15.647056Z","time spent":"1.343335485s","remote":"127.0.0.1:57074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-02T21:21:17.108345Z","caller":"traceutil/trace.go:171","msg":"trace[1791130399] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"751.126258ms","start":"2026-03-02T21:21:16.357196Z","end":"2026-03-02T21:21:17.108323Z","steps":["trace[1791130399] 'process raft request' (duration: 751.052106ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:17.108393Z","caller":"traceutil/trace.go:171","msg":"trace[1511120001] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"751.549628ms","start":"2026-03-02T21:21:16.356818Z","end":"2026-03-02T21:21:17.108368Z","steps":["trace[1511120001] 'process raft request' (duration: 751.403465ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:17.10846Z","caller":"traceutil/trace.go:171","msg":"trace[1340994270] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"115.213449ms","start":"2026-03-02T21:21:16.993238Z","end":"2026-03-02T21:21:17.108452Z","steps":["trace[1340994270] 'process raft request' (duration: 115.052295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:17.108531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:16.356801Z","time spent":"751.673261ms","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:21:17.108349Z","caller":"traceutil/trace.go:171","msg":"trace[920178175] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"751.511027ms","start":"2026-03-02T21:21:16.356794Z","end":"2026-03-02T21:21:17.108305Z","steps":["trace[920178175] 'process raft request' (duration: 744.924792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:17.108722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:16.356765Z","time spent":"751.914326ms","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:21:17.108415Z","caller":"traceutil/trace.go:171","msg":"trace[1276566416] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"114.681296ms","start":"2026-03-02T21:21:16.993715Z","end":"2026-03-02T21:21:17.108396Z","steps":["trace[1276566416] 'process raft request' (duration: 114.641805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:17.108843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.691347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T21:21:17.108911Z","caller":"traceutil/trace.go:171","msg":"trace[195214937] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"751.64899ms","start":"2026-03-02T21:21:16.357251Z","end":"2026-03-02T21:21:17.1089Z","steps":["trace[195214937] 'process raft request' (duration: 751.016926ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:17.108917Z","caller":"traceutil/trace.go:171","msg":"trace[78899598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:299; }","duration":"411.812189ms","start":"2026-03-02T21:21:16.697085Z","end":"2026-03-02T21:21:17.108897Z","steps":["trace[78899598] 'agreement among raft nodes before linearized reading' (duration: 411.666576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:17.108866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:16.357169Z","time spent":"751.285092ms","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:21:17.10897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.257644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-03-02T21:21:17.10897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:16.697065Z","time spent":"411.892551ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T21:21:17.109139Z","caller":"traceutil/trace.go:171","msg":"trace[804296804] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:299; }","duration":"752.467068ms","start":"2026-03-02T21:21:16.356657Z","end":"2026-03-02T21:21:17.109124Z","steps":["trace[804296804] 'agreement among raft nodes before linearized reading' (duration: 752.159121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:17.108975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:16.357212Z","time spent":"751.735632ms","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:21:17.109734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:16.35664Z","time spent":"753.080523ms","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-02T21:21:17.109512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.496123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:21:17.109934Z","caller":"traceutil/trace.go:171","msg":"trace[381133858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:299; }","duration":"816.945753ms","start":"2026-03-02T21:21:16.292959Z","end":"2026-03-02T21:21:17.109905Z","steps":["trace[381133858] 'agreement among raft nodes before linearized reading' (duration: 815.874129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:17.109999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:16.292938Z","time spent":"817.041105ms","remote":"127.0.0.1:56938","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-03-02T21:21:17.109588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015902926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:21:17.11009Z","caller":"traceutil/trace.go:171","msg":"trace[461999558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:299; }","duration":"1.016441358s","start":"2026-03-02T21:21:16.093636Z","end":"2026-03-02T21:21:17.110078Z","steps":["trace[461999558] 'agreement among raft nodes before linearized reading' (duration: 1.015734382s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:17.110134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:16.093617Z","time spent":"1.016505059s","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T21:21:17.109579Z","caller":"traceutil/trace.go:171","msg":"trace[170943563] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:304; }","duration":"119.650302ms","start":"2026-03-02T21:21:16.989038Z","end":"2026-03-02T21:21:17.108689Z","steps":["trace[170943563] 'read index received' (duration: 112.69143ms)","trace[170943563] 'applied index is now lower than readState.Index' (duration: 6.953842ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:21.426851Z","caller":"traceutil/trace.go:171","msg":"trace[2055170614] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"186.146355ms","start":"2026-03-02T21:21:21.240655Z","end":"2026-03-02T21:21:21.426801Z","steps":["trace[2055170614] 'process raft request' (duration: 185.324036ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:21.569311Z","caller":"traceutil/trace.go:171","msg":"trace[792418498] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"139.051262ms","start":"2026-03-02T21:21:21.430219Z","end":"2026-03-02T21:21:21.56927Z","steps":["trace[792418498] 'process raft request' (duration: 42.712251ms)","trace[792418498] 'compare' (duration: 96.026843ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:21.787358Z","caller":"traceutil/trace.go:171","msg":"trace[216081207] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"351.255448ms","start":"2026-03-02T21:21:21.436078Z","end":"2026-03-02T21:21:21.787333Z","steps":["trace[216081207] 'process raft request' (duration: 270.94083ms)","trace[216081207] 'compare' (duration: 80.208396ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:21.787531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:21.436061Z","time spent":"351.368812ms","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:21:44.01673Z","caller":"traceutil/trace.go:171","msg":"trace[1827664066] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"245.877162ms","start":"2026-03-02T21:21:43.770835Z","end":"2026-03-02T21:21:44.016712Z","steps":["trace[1827664066] 'process raft request' (duration: 231.471078ms)","trace[1827664066] 'compare' (duration: 14.314362ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:47.269008Z","caller":"traceutil/trace.go:171","msg":"trace[1653275751] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"128.465518ms","start":"2026-03-02T21:21:47.14051Z","end":"2026-03-02T21:21:47.268975Z","steps":["trace[1653275751] 'process raft request' (duration: 128.207101ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:47.436322Z","caller":"traceutil/trace.go:171","msg":"trace[1011733792] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"142.619416ms","start":"2026-03-02T21:21:47.293677Z","end":"2026-03-02T21:21:47.436296Z","steps":["trace[1011733792] 'read index received' (duration: 78.147796ms)","trace[1011733792] 'applied index is now lower than readState.Index' (duration: 64.470639ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:47.436479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.777279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:21:47.436533Z","caller":"traceutil/trace.go:171","msg":"trace[444453763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"142.879152ms","start":"2026-03-02T21:21:47.293637Z","end":"2026-03-02T21:21:47.436516Z","steps":["trace[444453763] 'agreement among raft nodes before linearized reading' (duration: 142.758859ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:47.436665Z","caller":"traceutil/trace.go:171","msg":"trace[92219280] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"163.197207ms","start":"2026-03-02T21:21:47.273432Z","end":"2026-03-02T21:21:47.436629Z","steps":["trace[92219280] 'process raft request' (duration: 98.570494ms)","trace[92219280] 'compare' (duration: 64.180493ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:21:48.23482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72229875597345927,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T21:21:48.697718Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.23740451s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-02T21:21:48.703725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.099818ms","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-03-02T21:21:48.703828Z","caller":"traceutil/trace.go:171","msg":"trace[872590458] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:531; }","duration":"991.210131ms","start":"2026-03-02T21:21:47.712598Z","end":"2026-03-02T21:21:48.703809Z","steps":["trace[872590458] 'range keys from in-memory index tree' (duration: 991.085598ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:48.70384Z","caller":"traceutil/trace.go:171","msg":"trace[1745250925] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"969.074502ms","start":"2026-03-02T21:21:47.734747Z","end":"2026-03-02T21:21:48.703821Z","steps":["trace[1745250925] 'read index received' (duration: 963.097539ms)","trace[1745250925] 'applied index is now lower than readState.Index' (duration: 5.976043ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:21:48.703898Z","caller":"traceutil/trace.go:171","msg":"trace[753395769] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.243620728s","start":"2026-03-02T21:21:47.460231Z","end":"2026-03-02T21:21:48.703851Z","steps":["trace[753395769] 'process raft request' (duration: 1.237701637s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:48.703964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.789279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:21:48.703998Z","caller":"traceutil/trace.go:171","msg":"trace[1300795166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"410.863741ms","start":"2026-03-02T21:21:48.293125Z","end":"2026-03-02T21:21:48.703989Z","steps":["trace[1300795166] 'agreement among raft nodes before linearized reading' (duration: 410.803599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:48.704031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:48.293104Z","time spent":"410.918474ms","remote":"127.0.0.1:56936","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-03-02T21:21:48.704088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:47.460215Z","time spent":"1.243781022s","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:21:48.704961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.188479ms","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-03-02T21:21:48.705215Z","caller":"traceutil/trace.go:171","msg":"trace[614473071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"970.344603ms","start":"2026-03-02T21:21:47.73471Z","end":"2026-03-02T21:21:48.705055Z","steps":["trace[614473071] 'agreement among raft nodes before linearized reading' (duration: 969.183605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:21:48.705298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:21:47.734685Z","time spent":"970.572109ms","remote":"127.0.0.1:57230","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-03-02T21:21:50.692646Z","caller":"traceutil/trace.go:171","msg":"trace[1176516871] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"113.180482ms","start":"2026-03-02T21:21:50.579076Z","end":"2026-03-02T21:21:50.692256Z","steps":["trace[1176516871] 'process raft request' (duration: 112.884285ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:03.849141Z","caller":"traceutil/trace.go:171","msg":"trace[1418927348] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"168.296839ms","start":"2026-03-02T21:22:03.680811Z","end":"2026-03-02T21:22:03.849107Z","steps":["trace[1418927348] 'read index received' (duration: 168.131025ms)","trace[1418927348] 'applied index is now lower than readState.Index' (duration: 164.964µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:03.849209Z","caller":"traceutil/trace.go:171","msg":"trace[366067988] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"345.44497ms","start":"2026-03-02T21:22:03.503741Z","end":"2026-03-02T21:22:03.849186Z","steps":["trace[366067988] 'process raft request' (duration: 345.226464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:03.849907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:03.503713Z","time spent":"345.552603ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:22:03.84939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.568106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.213\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-02T21:22:03.850208Z","caller":"traceutil/trace.go:171","msg":"trace[489968094] range","detail":"{range_begin:/registry/masterleases/199.19.213.213; range_end:; response_count:1; response_revision:710; }","duration":"169.367105ms","start":"2026-03-02T21:22:03.680761Z","end":"2026-03-02T21:22:03.850129Z","steps":["trace[489968094] 'agreement among raft nodes before linearized reading' (duration: 168.474944ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:08.889197Z","caller":"traceutil/trace.go:171","msg":"trace[659766988] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"591.734121ms","start":"2026-03-02T21:22:08.29744Z","end":"2026-03-02T21:22:08.889174Z","steps":["trace[659766988] 'process raft request' (duration: 591.580748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:08.889331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:08.297429Z","time spent":"591.832224ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:22:09.873398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72229875597346303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T21:22:10.374016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72229875597346303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T21:22:10.403159Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.514162629s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-02T21:22:10.552197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.847824ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-02T21:22:10.552497Z","caller":"traceutil/trace.go:171","msg":"trace[460698614] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:751; }","duration":"1.179881146s","start":"2026-03-02T21:22:09.372594Z","end":"2026-03-02T21:22:10.552475Z","steps":["trace[460698614] 'read index received' (duration: 1.030767736s)","trace[460698614] 'applied index is now lower than readState.Index' (duration: 149.11228ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:10.552589Z","caller":"traceutil/trace.go:171","msg":"trace[1235642814] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.659140461s","start":"2026-03-02T21:22:08.893415Z","end":"2026-03-02T21:22:10.552555Z","steps":["trace[1235642814] 'process raft request' (duration: 1.658899545s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:10.552709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.217843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T21:22:10.55272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180120631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-02T21:22:10.552741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:08.893394Z","time spent":"1.659266934s","remote":"127.0.0.1:57230","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-03-02T21:22:10.552769Z","caller":"traceutil/trace.go:171","msg":"trace[1635561103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"260.322345ms","start":"2026-03-02T21:22:10.29243Z","end":"2026-03-02T21:22:10.552752Z","steps":["trace[1635561103] 'agreement among raft nodes before linearized reading' (duration: 260.230573ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:10.552776Z","caller":"traceutil/trace.go:171","msg":"trace[1628421313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"1.180201644s","start":"2026-03-02T21:22:09.372559Z","end":"2026-03-02T21:22:10.552761Z","steps":["trace[1628421313] 'agreement among raft nodes before linearized reading' (duration: 1.18003177s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:10.552799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.765919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-02T21:22:10.552813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:09.372503Z","time spent":"1.180301746s","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T21:22:10.552831Z","caller":"traceutil/trace.go:171","msg":"trace[1793909189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:730; }","duration":"642.827671ms","start":"2026-03-02T21:22:09.909992Z","end":"2026-03-02T21:22:10.55282Z","steps":["trace[1793909189] 'agreement among raft nodes before linearized reading' (duration: 642.754539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:10.552859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:09.909974Z","time spent":"642.878503ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T21:22:10.552716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17464783s","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-03-02T21:22:10.552898Z","caller":"traceutil/trace.go:171","msg":"trace[1798055501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:730; }","duration":"1.174832816s","start":"2026-03-02T21:22:09.378057Z","end":"2026-03-02T21:22:10.55289Z","steps":["trace[1798055501] 'agreement among raft nodes before linearized reading' (duration: 1.174572728s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:10.552925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:09.378012Z","time spent":"1.174906017s","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T21:22:10.921043Z","caller":"traceutil/trace.go:171","msg":"trace[1679882139] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"362.908757ms","start":"2026-03-02T21:22:10.558118Z","end":"2026-03-02T21:22:10.921026Z","steps":["trace[1679882139] 'process raft request' (duration: 339.885817ms)","trace[1679882139] 'compare' (duration: 22.459326ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:10.921052Z","caller":"traceutil/trace.go:171","msg":"trace[1587413627] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"362.899467ms","start":"2026-03-02T21:22:10.55813Z","end":"2026-03-02T21:22:10.921029Z","steps":["trace[1587413627] 'process raft request' (duration: 362.6303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:10.921144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:10.5581Z","time spent":"362.988529ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:22:10.921181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:10.558113Z","time spent":"362.995108ms","remote":"127.0.0.1:57230","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-03-02T21:22:11.06644Z","caller":"traceutil/trace.go:171","msg":"trace[1305973654] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"140.750201ms","start":"2026-03-02T21:22:10.925666Z","end":"2026-03-02T21:22:11.066416Z","steps":["trace[1305973654] 'process raft request' (duration: 95.49174ms)","trace[1305973654] 'compare' (duration: 45.173349ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:22:11.458223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.765599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:22:11.458318Z","caller":"traceutil/trace.go:171","msg":"trace[1550326258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"165.913973ms","start":"2026-03-02T21:22:11.292383Z","end":"2026-03-02T21:22:11.458297Z","steps":["trace[1550326258] 'range keys from in-memory index tree' (duration: 165.686567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:33.165125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.453704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:22:33.16525Z","caller":"traceutil/trace.go:171","msg":"trace[1342068903] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"377.356661ms","start":"2026-03-02T21:22:32.787876Z","end":"2026-03-02T21:22:33.165233Z","steps":["trace[1342068903] 'read index received' (duration: 129.748094ms)","trace[1342068903] 'applied index is now lower than readState.Index' (duration: 247.606746ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:22:33.165389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.513645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:22:33.165365Z","caller":"traceutil/trace.go:171","msg":"trace[110175119] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"464.849138ms","start":"2026-03-02T21:22:32.700485Z","end":"2026-03-02T21:22:33.165335Z","steps":["trace[110175119] 'process raft request' (duration: 217.091127ms)","trace[110175119] 'compare' (duration: 247.319961ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:33.165511Z","caller":"traceutil/trace.go:171","msg":"trace[1230136755] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:798; }","duration":"377.562576ms","start":"2026-03-02T21:22:32.787858Z","end":"2026-03-02T21:22:33.165421Z","steps":["trace[1230136755] 'agreement among raft nodes before linearized reading' (duration: 377.485574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:33.165556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:32.787789Z","time spent":"377.75556ms","remote":"127.0.0.1:57102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-03-02T21:22:33.165569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:32.700465Z","time spent":"465.012551ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:22:33.16569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.111372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-02T21:22:33.165795Z","caller":"traceutil/trace.go:171","msg":"trace[1556130263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"124.247205ms","start":"2026-03-02T21:22:33.041522Z","end":"2026-03-02T21:22:33.165769Z","steps":["trace[1556130263] 'agreement among raft nodes before linearized reading' (duration: 124.016539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:33.165791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.328479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-02T21:22:33.165869Z","caller":"traceutil/trace.go:171","msg":"trace[1550467876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"133.458162ms","start":"2026-03-02T21:22:33.032391Z","end":"2026-03-02T21:22:33.16585Z","steps":["trace[1550467876] 'agreement among raft nodes before linearized reading' (duration: 133.216686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:33.165752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.090001ms","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-03-02T21:22:33.166085Z","caller":"traceutil/trace.go:171","msg":"trace[2079078563] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:798; }","duration":"149.401328ms","start":"2026-03-02T21:22:33.016599Z","end":"2026-03-02T21:22:33.166Z","steps":["trace[2079078563] 'agreement among raft nodes before linearized reading' (duration: 149.074921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:33.166272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.755175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T21:22:33.166351Z","caller":"traceutil/trace.go:171","msg":"trace[1830976803] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:798; }","duration":"229.997331ms","start":"2026-03-02T21:22:32.936335Z","end":"2026-03-02T21:22:33.166332Z","steps":["trace[1830976803] 'agreement among raft nodes before linearized reading' (duration: 229.177181ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:33.580961Z","caller":"traceutil/trace.go:171","msg":"trace[2108514377] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"410.574227ms","start":"2026-03-02T21:22:33.17035Z","end":"2026-03-02T21:22:33.580924Z","steps":["trace[2108514377] 'process raft request' (duration: 335.469773ms)","trace[2108514377] 'compare' (duration: 74.679674ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:22:33.581121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:33.17032Z","time spent":"410.721369ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:22:33.600161Z","caller":"traceutil/trace.go:171","msg":"trace[1480768239] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:825; }","duration":"306.829566ms","start":"2026-03-02T21:22:33.293301Z","end":"2026-03-02T21:22:33.60013Z","steps":["trace[1480768239] 'read index received' (duration: 212.544599ms)","trace[1480768239] 'applied index is now lower than readState.Index' (duration: 94.284117ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:33.600245Z","caller":"traceutil/trace.go:171","msg":"trace[849458156] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"429.417441ms","start":"2026-03-02T21:22:33.170792Z","end":"2026-03-02T21:22:33.600209Z","steps":["trace[849458156] 'process raft request' (duration: 429.297259ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:33.600227Z","caller":"traceutil/trace.go:171","msg":"trace[826288343] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"429.573235ms","start":"2026-03-02T21:22:33.170615Z","end":"2026-03-02T21:22:33.600188Z","steps":["trace[826288343] 'process raft request' (duration: 429.331909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:33.600343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.030792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:22:33.600382Z","caller":"traceutil/trace.go:171","msg":"trace[1433528501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"307.134954ms","start":"2026-03-02T21:22:33.293236Z","end":"2026-03-02T21:22:33.600371Z","steps":["trace[1433528501] 'agreement among raft nodes before linearized reading' (duration: 306.995421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:33.600418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:33.293213Z","time spent":"307.196725ms","remote":"127.0.0.1:56936","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-03-02T21:22:33.600417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:33.170574Z","time spent":"429.746439ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:22:33.600575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:33.170769Z","time spent":"429.526974ms","remote":"127.0.0.1:57230","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-03-02T21:22:42.861334Z","caller":"traceutil/trace.go:171","msg":"trace[1632963938] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:854; }","duration":"106.301941ms","start":"2026-03-02T21:22:42.755014Z","end":"2026-03-02T21:22:42.861316Z","steps":["trace[1632963938] 'read index received' (duration: 106.106976ms)","trace[1632963938] 'applied index is now lower than readState.Index' (duration: 193.975µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:42.861594Z","caller":"traceutil/trace.go:171","msg":"trace[1663429411] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"110.126651ms","start":"2026-03-02T21:22:42.751434Z","end":"2026-03-02T21:22:42.86156Z","steps":["trace[1663429411] 'process raft request' (duration: 109.767593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:42.862513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.479718ms","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-03-02T21:22:42.862577Z","caller":"traceutil/trace.go:171","msg":"trace[948894210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:825; }","duration":"107.582281ms","start":"2026-03-02T21:22:42.754978Z","end":"2026-03-02T21:22:42.862561Z","steps":["trace[948894210] 'agreement among raft nodes before linearized reading' (duration: 106.427554ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:47.465513Z","caller":"traceutil/trace.go:171","msg":"trace[813439259] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"194.655936ms","start":"2026-03-02T21:22:47.27084Z","end":"2026-03-02T21:22:47.465496Z","steps":["trace[813439259] 'read index received' (duration: 194.492192ms)","trace[813439259] 'applied index is now lower than readState.Index' (duration: 163.114µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:47.465609Z","caller":"traceutil/trace.go:171","msg":"trace[847893326] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"519.425686ms","start":"2026-03-02T21:22:46.946175Z","end":"2026-03-02T21:22:47.4656Z","steps":["trace[847893326] 'process raft request' (duration: 519.214231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:47.465692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:22:46.946162Z","time spent":"519.467126ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:22:47.465872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.038976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T21:22:47.465903Z","caller":"traceutil/trace.go:171","msg":"trace[674193185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"195.103687ms","start":"2026-03-02T21:22:47.270791Z","end":"2026-03-02T21:22:47.465895Z","steps":["trace[674193185] 'agreement among raft nodes before linearized reading' (duration: 194.988855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:47.465976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.047442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:22:47.465996Z","caller":"traceutil/trace.go:171","msg":"trace[2136779929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"172.094164ms","start":"2026-03-02T21:22:47.293897Z","end":"2026-03-02T21:22:47.465991Z","steps":["trace[2136779929] 'agreement among raft nodes before linearized reading' (duration: 172.066013ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:49.722141Z","caller":"traceutil/trace.go:171","msg":"trace[863664195] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"199.683186ms","start":"2026-03-02T21:22:49.522442Z","end":"2026-03-02T21:22:49.722125Z","steps":["trace[863664195] 'read index received' (duration: 199.612175ms)","trace[863664195] 'applied index is now lower than readState.Index' (duration: 70.321µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:49.722218Z","caller":"traceutil/trace.go:171","msg":"trace[461431754] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"249.343439ms","start":"2026-03-02T21:22:49.472853Z","end":"2026-03-02T21:22:49.722196Z","steps":["trace[461431754] 'process raft request' (duration: 249.138494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:49.722959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.498895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T21:22:49.723027Z","caller":"traceutil/trace.go:171","msg":"trace[1343242364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"200.601058ms","start":"2026-03-02T21:22:49.52241Z","end":"2026-03-02T21:22:49.723011Z","steps":["trace[1343242364] 'agreement among raft nodes before linearized reading' (duration: 200.460475ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:49.924463Z","caller":"traceutil/trace.go:171","msg":"trace[173239127] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"198.56895ms","start":"2026-03-02T21:22:49.725873Z","end":"2026-03-02T21:22:49.924442Z","steps":["trace[173239127] 'process raft request' (duration: 190.53923ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:49.99825Z","caller":"traceutil/trace.go:171","msg":"trace[1482353067] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:928; }","duration":"238.344219ms","start":"2026-03-02T21:22:49.759889Z","end":"2026-03-02T21:22:49.998233Z","steps":["trace[1482353067] 'read index received' (duration: 156.595169ms)","trace[1482353067] 'applied index is now lower than readState.Index' (duration: 81.7483ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:22:49.998321Z","caller":"traceutil/trace.go:171","msg":"trace[723452997] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"272.188488ms","start":"2026-03-02T21:22:49.726105Z","end":"2026-03-02T21:22:49.998294Z","steps":["trace[723452997] 'process raft request' (duration: 271.975383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:22:49.998392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.492142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T21:22:49.998413Z","caller":"traceutil/trace.go:171","msg":"trace[718002342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"238.570784ms","start":"2026-03-02T21:22:49.759835Z","end":"2026-03-02T21:22:49.998406Z","steps":["trace[718002342] 'agreement among raft nodes before linearized reading' (duration: 238.471102ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:22:49.998378Z","caller":"traceutil/trace.go:171","msg":"trace[1862673117] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"271.650766ms","start":"2026-03-02T21:22:49.726689Z","end":"2026-03-02T21:22:49.998339Z","steps":["trace[1862673117] 'process raft request' (duration: 271.493282ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:05.206739Z","caller":"traceutil/trace.go:171","msg":"trace[1398191911] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"146.826197ms","start":"2026-03-02T21:23:05.05989Z","end":"2026-03-02T21:23:05.206716Z","steps":["trace[1398191911] 'process raft request' (duration: 146.666204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:23:05.536575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.234657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:23:05.53666Z","caller":"traceutil/trace.go:171","msg":"trace[1407284883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"244.373071ms","start":"2026-03-02T21:23:05.292265Z","end":"2026-03-02T21:23:05.536638Z","steps":["trace[1407284883] 'range keys from in-memory index tree' (duration: 244.156165ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:06.41915Z","caller":"traceutil/trace.go:171","msg":"trace[2062247460] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"175.278ms","start":"2026-03-02T21:23:06.243846Z","end":"2026-03-02T21:23:06.419124Z","steps":["trace[2062247460] 'read index received' (duration: 163.941772ms)","trace[2062247460] 'applied index is now lower than readState.Index' (duration: 11.335168ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:23:06.419221Z","caller":"traceutil/trace.go:171","msg":"trace[294602943] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"329.83465ms","start":"2026-03-02T21:23:06.089358Z","end":"2026-03-02T21:23:06.419193Z","steps":["trace[294602943] 'process raft request' (duration: 318.504422ms)","trace[294602943] 'compare' (duration: 11.158064ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:23:06.4193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.047167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:23:06.419332Z","caller":"traceutil/trace.go:171","msg":"trace[1241575224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"126.131319ms","start":"2026-03-02T21:23:06.293191Z","end":"2026-03-02T21:23:06.419322Z","steps":["trace[1241575224] 'agreement among raft nodes before linearized reading' (duration: 126.065917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:23:06.419347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:23:06.089346Z","time spent":"329.928302ms","remote":"127.0.0.1:57230","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-03-02T21:23:06.419445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.579947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-02T21:23:06.419549Z","caller":"traceutil/trace.go:171","msg":"trace[1160682098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"175.728751ms","start":"2026-03-02T21:23:06.243794Z","end":"2026-03-02T21:23:06.419523Z","steps":["trace[1160682098] 'agreement among raft nodes before linearized reading' (duration: 175.448724ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:06.734683Z","caller":"traceutil/trace.go:171","msg":"trace[124989060] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"311.568729ms","start":"2026-03-02T21:23:06.423086Z","end":"2026-03-02T21:23:06.734655Z","steps":["trace[124989060] 'process raft request' (duration: 232.586183ms)","trace[124989060] 'compare' (duration: 78.838813ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:23:06.734844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:23:06.423059Z","time spent":"311.708132ms","remote":"127.0.0.1:57230","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-03-02T21:23:07.927791Z","caller":"traceutil/trace.go:171","msg":"trace[425779623] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"107.676822ms","start":"2026-03-02T21:23:07.820091Z","end":"2026-03-02T21:23:07.927767Z","steps":["trace[425779623] 'process raft request' (duration: 107.522479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:23:08.282905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.776967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-02T21:23:08.282893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.689573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-02T21:23:08.282967Z","caller":"traceutil/trace.go:171","msg":"trace[2044438957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"268.87272ms","start":"2026-03-02T21:23:08.01408Z","end":"2026-03-02T21:23:08.282953Z","steps":["trace[2044438957] 'range keys from in-memory index tree' (duration: 268.655054ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:08.282984Z","caller":"traceutil/trace.go:171","msg":"trace[1271576330] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"168.816076ms","start":"2026-03-02T21:23:08.114148Z","end":"2026-03-02T21:23:08.282964Z","steps":["trace[1271576330] 'range keys from in-memory index tree' (duration: 168.505029ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:08.402503Z","caller":"traceutil/trace.go:171","msg":"trace[858774675] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"115.99421ms","start":"2026-03-02T21:23:08.286162Z","end":"2026-03-02T21:23:08.402156Z","steps":["trace[858774675] 'process raft request' (duration: 115.836006ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:08.415452Z","caller":"traceutil/trace.go:171","msg":"trace[609916458] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"128.74915ms","start":"2026-03-02T21:23:08.286683Z","end":"2026-03-02T21:23:08.415432Z","steps":["trace[609916458] 'process raft request' (duration: 128.613257ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:08.899016Z","caller":"traceutil/trace.go:171","msg":"trace[111428960] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"159.861485ms","start":"2026-03-02T21:23:08.739131Z","end":"2026-03-02T21:23:08.898992Z","steps":["trace[111428960] 'read index received' (duration: 159.717512ms)","trace[111428960] 'applied index is now lower than readState.Index' (duration: 142.813µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:23:08.899079Z","caller":"traceutil/trace.go:171","msg":"trace[1227086361] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"332.39734ms","start":"2026-03-02T21:23:08.566654Z","end":"2026-03-02T21:23:08.899051Z","steps":["trace[1227086361] 'process raft request' (duration: 332.170734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:23:08.8992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.06912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-02T21:23:08.899219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:23:08.566635Z","time spent":"332.502622ms","remote":"127.0.0.1:57230","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-03-02T21:23:08.899241Z","caller":"traceutil/trace.go:171","msg":"trace[2034354408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"160.158132ms","start":"2026-03-02T21:23:08.739071Z","end":"2026-03-02T21:23:08.899229Z","steps":["trace[2034354408] 'agreement among raft nodes before linearized reading' (duration: 160.016359ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:09.02712Z","caller":"traceutil/trace.go:171","msg":"trace[553495247] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"123.102687ms","start":"2026-03-02T21:23:08.90399Z","end":"2026-03-02T21:23:09.027093Z","steps":["trace[553495247] 'process raft request' (duration: 122.81773ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:23:17.466827Z","caller":"traceutil/trace.go:171","msg":"trace[570153911] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"231.931407ms","start":"2026-03-02T21:23:17.234867Z","end":"2026-03-02T21:23:17.466799Z","steps":["trace[570153911] 'read index received' (duration: 231.651261ms)","trace[570153911] 'applied index is now lower than readState.Index' (duration: 278.876µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:23:17.466977Z","caller":"traceutil/trace.go:171","msg":"trace[42370679] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"304.965092ms","start":"2026-03-02T21:23:17.161991Z","end":"2026-03-02T21:23:17.466956Z","steps":["trace[42370679] 'process raft request' (duration: 304.651545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:23:17.46711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:23:17.16197Z","time spent":"305.055374ms","remote":"127.0.0.1:57230","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-03-02T21:23:17.467196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.736303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:23:17.467239Z","caller":"traceutil/trace.go:171","msg":"trace[614193151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"173.815764ms","start":"2026-03-02T21:23:17.293411Z","end":"2026-03-02T21:23:17.467226Z","steps":["trace[614193151] 'agreement among raft nodes before linearized reading' (duration: 173.718782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:23:17.467227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.353447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-03-02T21:23:17.467294Z","caller":"traceutil/trace.go:171","msg":"trace[1002385460] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1036; }","duration":"232.46049ms","start":"2026-03-02T21:23:17.234813Z","end":"2026-03-02T21:23:17.467274Z","steps":["trace[1002385460] 'agreement among raft nodes before linearized reading' (duration: 232.254035ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:25.677688Z","caller":"traceutil/trace.go:171","msg":"trace[1911793907] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"132.378286ms","start":"2026-03-02T21:26:25.545283Z","end":"2026-03-02T21:26:25.677661Z","steps":["trace[1911793907] 'process raft request' (duration: 132.329285ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:25.677789Z","caller":"traceutil/trace.go:171","msg":"trace[2141560539] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"133.343549ms","start":"2026-03-02T21:26:25.544411Z","end":"2026-03-02T21:26:25.677754Z","steps":["trace[2141560539] 'process raft request' (duration: 79.508722ms)","trace[2141560539] 'compare' (duration: 53.55733ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:26:29.373684Z","caller":"traceutil/trace.go:171","msg":"trace[1146048724] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"116.671136ms","start":"2026-03-02T21:26:29.256961Z","end":"2026-03-02T21:26:29.373632Z","steps":["trace[1146048724] 'process raft request' (duration: 116.40034ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:31.165522Z","caller":"traceutil/trace.go:171","msg":"trace[1761967369] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"137.269902ms","start":"2026-03-02T21:26:31.028226Z","end":"2026-03-02T21:26:31.165496Z","steps":["trace[1761967369] 'process raft request' (duration: 137.130048ms)"],"step_count":1}