{"level":"warn","ts":"2026-02-26T01:41:06.728218Z","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-26T01:41:06.72829Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-26T01:41:06.728345Z","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-26T01:41:06.728358Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-26T01:41:06.728372Z","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-26T01:41:06.728999Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-02-26T01:41:06.729064Z","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.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-26T01:41:06.732757Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.530821ms"} {"level":"info","ts":"2026-02-26T01:41:06.740733Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-02-26T01:41:06.740861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T01:41:06.740893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-02-26T01:41:06.740914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T01:41:06.740928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-02-26T01:41:06.740962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-02-26T01:41:06.750991Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T01:41:06.75217Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T01:41:06.753406Z","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-26T01:41:06.755297Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T01:41:06.756318Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T01:41:06.756366Z","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-26T01:41:06.756968Z","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-26T01:41:06.757002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-02-26T01:41:06.757022Z","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-26T01:41:06.757107Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-26T01:41:06.758954Z","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-26T01:41:06.75903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-26T01:41:06.759121Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-26T01:41:06.759152Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T01:41:06.759186Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T01:41:07.241942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T01:41:07.242027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T01:41:07.242082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-02-26T01:41:07.242126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-02-26T01:41:07.242148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-26T01:41:07.242171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-02-26T01:41:07.242196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-26T01:41:07.248951Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:41:07.249656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:41:07.249676Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T01:41:07.249802Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:41:07.250095Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T01:41:07.250146Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T01:41:07.250142Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:41:07.250229Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:41:07.250264Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:41:07.253369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-02-26T01:41:07.25344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T01:41:36.559839Z","caller":"traceutil/trace.go:171","msg":"trace[491571898] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"127.097974ms","start":"2026-02-26T01:41:36.432713Z","end":"2026-02-26T01:41:36.559811Z","steps":["trace[491571898] 'process raft request' (duration: 126.915051ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:41:41.456532Z","caller":"traceutil/trace.go:171","msg":"trace[1977608481] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"134.822617ms","start":"2026-02-26T01:41:41.321679Z","end":"2026-02-26T01:41:41.456501Z","steps":["trace[1977608481] 'process raft request' (duration: 134.634203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:03.20452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.959376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:42:03.204679Z","caller":"traceutil/trace.go:171","msg":"trace[1228609205] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"373.450935ms","start":"2026-02-26T01:42:02.831192Z","end":"2026-02-26T01:42:03.204643Z","steps":["trace[1228609205] 'process raft request' (duration: 170.903857ms)","trace[1228609205] 'compare' (duration: 201.670031ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:03.204708Z","caller":"traceutil/trace.go:171","msg":"trace[1423305245] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"289.944684ms","start":"2026-02-26T01:42:02.914751Z","end":"2026-02-26T01:42:03.204695Z","steps":["trace[1423305245] 'process raft request' (duration: 289.894903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:03.204832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:02.831142Z","time spent":"373.597538ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:42:03.205604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.393328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:42:03.205703Z","caller":"traceutil/trace.go:171","msg":"trace[847903169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"365.803405ms","start":"2026-02-26T01:42:02.839876Z","end":"2026-02-26T01:42:03.205679Z","steps":["trace[847903169] 'agreement among raft nodes before linearized reading' (duration: 365.134201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:03.205752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:02.839857Z","time spent":"365.881657ms","remote":"127.0.0.1:55104","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-26T01:42:03.20475Z","caller":"traceutil/trace.go:171","msg":"trace[622457135] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"364.746753ms","start":"2026-02-26T01:42:02.839972Z","end":"2026-02-26T01:42:03.204718Z","steps":["trace[622457135] 'read index received' (duration: 162.201386ms)","trace[622457135] 'applied index is now lower than readState.Index' (duration: 202.543177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:03.206748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.188927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ktz5p\" ","response":"range_response_count:1 size:18709"} {"level":"info","ts":"2026-02-26T01:42:03.206931Z","caller":"traceutil/trace.go:171","msg":"trace[739929653] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ktz5p; range_end:; response_count:1; response_revision:662; }","duration":"351.392911ms","start":"2026-02-26T01:42:02.855526Z","end":"2026-02-26T01:42:03.206919Z","steps":["trace[739929653] 'agreement among raft nodes before linearized reading' (duration: 350.801079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:03.206977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:02.855515Z","time spent":"351.452843ms","remote":"127.0.0.1:55018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18731,"request content":"key:\"/registry/pods/kube-system/cilium-ktz5p\" "} {"level":"info","ts":"2026-02-26T01:42:03.474411Z","caller":"traceutil/trace.go:171","msg":"trace[1104760727] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"264.078667ms","start":"2026-02-26T01:42:03.210302Z","end":"2026-02-26T01:42:03.474381Z","steps":["trace[1104760727] 'process raft request' (duration: 263.911594ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:03.740679Z","caller":"traceutil/trace.go:171","msg":"trace[689449005] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"518.884502ms","start":"2026-02-26T01:42:03.221767Z","end":"2026-02-26T01:42:03.740652Z","steps":["trace[689449005] 'process raft request' (duration: 518.680608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:03.740835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:03.221742Z","time spent":"519.005824ms","remote":"127.0.0.1:55018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18506,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:42:03.740982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.392275ms","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-26T01:42:03.741046Z","caller":"traceutil/trace.go:171","msg":"trace[1538435266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"507.503127ms","start":"2026-02-26T01:42:03.233527Z","end":"2026-02-26T01:42:03.74103Z","steps":["trace[1538435266] 'agreement among raft nodes before linearized reading' (duration: 507.284153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:03.741092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:03.233508Z","time spent":"507.572879ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:42:03.740738Z","caller":"traceutil/trace.go:171","msg":"trace[76579303] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"507.046018ms","start":"2026-02-26T01:42:03.233584Z","end":"2026-02-26T01:42:03.74063Z","steps":["trace[76579303] 'read index received' (duration: 240.694547ms)","trace[76579303] 'applied index is now lower than readState.Index' (duration: 266.349731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:03.741635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.728016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:03.741676Z","caller":"traceutil/trace.go:171","msg":"trace[1116938177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"470.810076ms","start":"2026-02-26T01:42:03.270851Z","end":"2026-02-26T01:42:03.741661Z","steps":["trace[1116938177] 'agreement among raft nodes before linearized reading' (duration: 470.747545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:03.741713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:03.270829Z","time spent":"470.877398ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:42:15.064561Z","caller":"traceutil/trace.go:171","msg":"trace[793942279] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"247.033162ms","start":"2026-02-26T01:42:14.817499Z","end":"2026-02-26T01:42:15.064532Z","steps":["trace[793942279] 'process raft request' (duration: 246.876079ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:15.971034Z","caller":"traceutil/trace.go:171","msg":"trace[879637102] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"104.350149ms","start":"2026-02-26T01:42:15.866648Z","end":"2026-02-26T01:42:15.970998Z","steps":["trace[879637102] 'read index received' (duration: 103.949721ms)","trace[879637102] 'applied index is now lower than readState.Index' (duration: 398.868µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:15.971124Z","caller":"traceutil/trace.go:171","msg":"trace[324188566] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"128.255219ms","start":"2026-02-26T01:42:15.842826Z","end":"2026-02-26T01:42:15.971081Z","steps":["trace[324188566] 'process raft request' (duration: 127.920762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:15.971313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.546015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:15.971387Z","caller":"traceutil/trace.go:171","msg":"trace[610966182] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:761; }","duration":"104.780518ms","start":"2026-02-26T01:42:15.866587Z","end":"2026-02-26T01:42:15.971367Z","steps":["trace[610966182] 'agreement among raft nodes before linearized reading' (duration: 104.552604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:16.52245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.25136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:16.522566Z","caller":"traceutil/trace.go:171","msg":"trace[1391609302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"401.404793ms","start":"2026-02-26T01:42:16.121136Z","end":"2026-02-26T01:42:16.522541Z","steps":["trace[1391609302] 'range keys from in-memory index tree' (duration: 401.063177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:16.522631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:16.121108Z","time spent":"401.512955ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:42:16.523068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.655453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T01:42:16.523146Z","caller":"traceutil/trace.go:171","msg":"trace[229204638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"334.531231ms","start":"2026-02-26T01:42:16.188604Z","end":"2026-02-26T01:42:16.523135Z","steps":["trace[229204638] 'range keys from in-memory index tree' (duration: 333.46271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:16.523213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:16.188559Z","time spent":"334.609323ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:42:16.636745Z","caller":"traceutil/trace.go:171","msg":"trace[1012637205] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"105.827389ms","start":"2026-02-26T01:42:16.530893Z","end":"2026-02-26T01:42:16.63672Z","steps":["trace[1012637205] 'process raft request' (duration: 105.676226ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:29.791509Z","caller":"traceutil/trace.go:171","msg":"trace[128581782] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"132.541825ms","start":"2026-02-26T01:42:29.658942Z","end":"2026-02-26T01:42:29.791484Z","steps":["trace[128581782] 'process raft request' (duration: 132.372901ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:30.155724Z","caller":"traceutil/trace.go:171","msg":"trace[297400848] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"118.665517ms","start":"2026-02-26T01:42:30.037036Z","end":"2026-02-26T01:42:30.155701Z","steps":["trace[297400848] 'read index received' (duration: 78.707787ms)","trace[297400848] 'applied index is now lower than readState.Index' (duration: 39.95665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:30.15601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.960583ms","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-02-26T01:42:30.156094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.31413ms","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-02-26T01:42:30.156112Z","caller":"traceutil/trace.go:171","msg":"trace[1011012205] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"207.577528ms","start":"2026-02-26T01:42:29.948513Z","end":"2026-02-26T01:42:30.15609Z","steps":["trace[1011012205] 'process raft request' (duration: 167.288451ms)","trace[1011012205] 'compare' (duration: 39.777567ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:30.1562Z","caller":"traceutil/trace.go:171","msg":"trace[1185304612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"119.137957ms","start":"2026-02-26T01:42:30.036974Z","end":"2026-02-26T01:42:30.156112Z","steps":["trace[1185304612] 'agreement among raft nodes before linearized reading' (duration: 118.886902ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:30.156134Z","caller":"traceutil/trace.go:171","msg":"trace[450618068] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:804; }","duration":"112.368811ms","start":"2026-02-26T01:42:30.043753Z","end":"2026-02-26T01:42:30.156122Z","steps":["trace[450618068] 'agreement among raft nodes before linearized reading' (duration: 112.298429ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:30.275139Z","caller":"traceutil/trace.go:171","msg":"trace[1253350001] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"114.687928ms","start":"2026-02-26T01:42:30.160409Z","end":"2026-02-26T01:42:30.275097Z","steps":["trace[1253350001] 'process raft request' (duration: 96.569044ms)","trace[1253350001] 'compare' (duration: 17.895609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:41.554647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.414298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-26T01:42:41.554687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.655762ms","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-26T01:42:41.554727Z","caller":"traceutil/trace.go:171","msg":"trace[239350021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"306.49879ms","start":"2026-02-26T01:42:41.248199Z","end":"2026-02-26T01:42:41.554697Z","steps":["trace[239350021] 'range keys from in-memory index tree' (duration: 306.276995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:41.554748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.329293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:42:41.55477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:41.248185Z","time spent":"306.576212ms","remote":"127.0.0.1:55104","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":"info","ts":"2026-02-26T01:42:41.55474Z","caller":"traceutil/trace.go:171","msg":"trace[1629444219] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:886; }","duration":"253.773944ms","start":"2026-02-26T01:42:41.300955Z","end":"2026-02-26T01:42:41.554729Z","steps":["trace[1629444219] 'count revisions from in-memory index tree' (duration: 253.577799ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:41.554799Z","caller":"traceutil/trace.go:171","msg":"trace[1252002694] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:886; }","duration":"496.428505ms","start":"2026-02-26T01:42:41.058356Z","end":"2026-02-26T01:42:41.554784Z","steps":["trace[1252002694] 'count revisions from in-memory index tree' (duration: 496.216811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:41.554857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:41.058335Z","time spent":"496.512946ms","remote":"127.0.0.1:55320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:42:41.554982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.285273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:42:41.554997Z","caller":"traceutil/trace.go:171","msg":"trace[741103576] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:886; }","duration":"492.328173ms","start":"2026-02-26T01:42:41.062663Z","end":"2026-02-26T01:42:41.554992Z","steps":["trace[741103576] 'count revisions from in-memory index tree' (duration: 492.232312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:41.555011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:41.062652Z","time spent":"492.353994ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:42:41.55449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.948925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:41.556302Z","caller":"traceutil/trace.go:171","msg":"trace[973190604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"434.804822ms","start":"2026-02-26T01:42:41.121482Z","end":"2026-02-26T01:42:41.556287Z","steps":["trace[973190604] 'range keys from in-memory index tree' (duration: 432.897173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:41.55635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:41.121472Z","time spent":"434.864563ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:42:43.32191Z","caller":"traceutil/trace.go:171","msg":"trace[290541308] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"200.312934ms","start":"2026-02-26T01:42:43.121582Z","end":"2026-02-26T01:42:43.321895Z","steps":["trace[290541308] 'read index received' (duration: 200.166771ms)","trace[290541308] 'applied index is now lower than readState.Index' (duration: 145.563µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:43.32201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.413266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:43.321993Z","caller":"traceutil/trace.go:171","msg":"trace[962454574] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"453.910584ms","start":"2026-02-26T01:42:42.868048Z","end":"2026-02-26T01:42:43.321958Z","steps":["trace[962454574] 'process raft request' (duration: 453.750251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:43.322059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.650239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:43.322076Z","caller":"traceutil/trace.go:171","msg":"trace[349322379] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:891; }","duration":"172.68604ms","start":"2026-02-26T01:42:43.149385Z","end":"2026-02-26T01:42:43.322071Z","steps":["trace[349322379] 'agreement among raft nodes before linearized reading' (duration: 172.66019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:43.322161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:42.868028Z","time spent":"454.041146ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:42:43.322031Z","caller":"traceutil/trace.go:171","msg":"trace[972840568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"200.484437ms","start":"2026-02-26T01:42:43.121539Z","end":"2026-02-26T01:42:43.322024Z","steps":["trace[972840568] 'agreement among raft nodes before linearized reading' (duration: 200.437776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:43.706109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.241289ms","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-02-26T01:42:43.706192Z","caller":"traceutil/trace.go:171","msg":"trace[1835386551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"123.360671ms","start":"2026-02-26T01:42:43.582814Z","end":"2026-02-26T01:42:43.706175Z","steps":["trace[1835386551] 'range keys from in-memory index tree' (duration: 123.065735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:43.706362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.260529ms","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-02-26T01:42:43.706404Z","caller":"traceutil/trace.go:171","msg":"trace[980255087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"129.346991ms","start":"2026-02-26T01:42:43.577048Z","end":"2026-02-26T01:42:43.706395Z","steps":["trace[980255087] 'range keys from in-memory index tree' (duration: 129.145087ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:59.40139Z","caller":"traceutil/trace.go:171","msg":"trace[1204727108] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"102.469033ms","start":"2026-02-26T01:42:59.298886Z","end":"2026-02-26T01:42:59.401355Z","steps":["trace[1204727108] 'process raft request' (duration: 102.257989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:00.186612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.662159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:43:00.18674Z","caller":"traceutil/trace.go:171","msg":"trace[298094084] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"243.404956ms","start":"2026-02-26T01:42:59.943315Z","end":"2026-02-26T01:43:00.18672Z","steps":["trace[298094084] 'read index received' (duration: 83.431812ms)","trace[298094084] 'applied index is now lower than readState.Index' (duration: 159.971844ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:00.187196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.847295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:43:00.187315Z","caller":"traceutil/trace.go:171","msg":"trace[2084275426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"244.046499ms","start":"2026-02-26T01:42:59.943239Z","end":"2026-02-26T01:43:00.187285Z","steps":["trace[2084275426] 'agreement among raft nodes before linearized reading' (duration: 243.709442ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:00.187491Z","caller":"traceutil/trace.go:171","msg":"trace[1516067020] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"319.601352ms","start":"2026-02-26T01:42:59.867872Z","end":"2026-02-26T01:43:00.187473Z","steps":["trace[1516067020] 'process raft request' (duration: 158.966634ms)","trace[1516067020] 'compare' (duration: 159.532656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:00.187641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:59.867856Z","time spent":"319.687955ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:43:00.376488Z","caller":"traceutil/trace.go:171","msg":"trace[953183824] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"150.399483ms","start":"2026-02-26T01:43:00.226065Z","end":"2026-02-26T01:43:00.376465Z","steps":["trace[953183824] 'read index received' (duration: 131.685088ms)","trace[953183824] 'applied index is now lower than readState.Index' (duration: 18.713375ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:00.376623Z","caller":"traceutil/trace.go:171","msg":"trace[2057772976] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"185.684569ms","start":"2026-02-26T01:43:00.190902Z","end":"2026-02-26T01:43:00.376586Z","steps":["trace[2057772976] 'process raft request' (duration: 166.809361ms)","trace[2057772976] 'compare' (duration: 18.652594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:00.376708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.638708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T01:43:00.376733Z","caller":"traceutil/trace.go:171","msg":"trace[2039307401] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:958; }","duration":"150.7299ms","start":"2026-02-26T01:43:00.225996Z","end":"2026-02-26T01:43:00.376725Z","steps":["trace[2039307401] 'agreement among raft nodes before linearized reading' (duration: 150.552626ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:01.55008Z","caller":"traceutil/trace.go:171","msg":"trace[122022584] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"427.77282ms","start":"2026-02-26T01:43:01.122275Z","end":"2026-02-26T01:43:01.550048Z","steps":["trace[122022584] 'read index received' (duration: 427.513355ms)","trace[122022584] 'applied index is now lower than readState.Index' (duration: 258.215µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:01.550421Z","caller":"traceutil/trace.go:171","msg":"trace[518155455] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"528.789224ms","start":"2026-02-26T01:43:01.021619Z","end":"2026-02-26T01:43:01.550408Z","steps":["trace[518155455] 'process raft request' (duration: 528.256714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:01.550588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:01.021578Z","time spent":"528.879776ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:43:01.550849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.580036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:43:01.550895Z","caller":"traceutil/trace.go:171","msg":"trace[421090479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"428.662068ms","start":"2026-02-26T01:43:01.12222Z","end":"2026-02-26T01:43:01.550882Z","steps":["trace[421090479] 'agreement among raft nodes before linearized reading' (duration: 428.591226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:01.550934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:01.1222Z","time spent":"428.728099ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:43:02.069426Z","caller":"traceutil/trace.go:171","msg":"trace[609733157] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"174.558097ms","start":"2026-02-26T01:43:01.894831Z","end":"2026-02-26T01:43:02.069389Z","steps":["trace[609733157] 'process raft request' (duration: 116.382081ms)","trace[609733157] 'compare' (duration: 57.983872ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:02.382001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.019917ms","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-02-26T01:43:02.38212Z","caller":"traceutil/trace.go:171","msg":"trace[947741684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"190.210721ms","start":"2026-02-26T01:43:02.191888Z","end":"2026-02-26T01:43:02.382098Z","steps":["trace[947741684] 'range keys from in-memory index tree' (duration: 189.64188ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:02.598525Z","caller":"traceutil/trace.go:171","msg":"trace[958071850] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"212.903155ms","start":"2026-02-26T01:43:02.385598Z","end":"2026-02-26T01:43:02.598501Z","steps":["trace[958071850] 'process raft request' (duration: 212.764812ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:02.636105Z","caller":"traceutil/trace.go:171","msg":"trace[1323337874] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1007; }","duration":"166.649948ms","start":"2026-02-26T01:43:02.469425Z","end":"2026-02-26T01:43:02.636075Z","steps":["trace[1323337874] 'read index received' (duration: 128.991954ms)","trace[1323337874] 'applied index is now lower than readState.Index' (duration: 37.656924ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:02.636153Z","caller":"traceutil/trace.go:171","msg":"trace[2105555687] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"249.095949ms","start":"2026-02-26T01:43:02.387018Z","end":"2026-02-26T01:43:02.636114Z","steps":["trace[2105555687] 'process raft request' (duration: 248.920116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:02.636353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.909874ms","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-26T01:43:02.636384Z","caller":"traceutil/trace.go:171","msg":"trace[1279790512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"167.010816ms","start":"2026-02-26T01:43:02.469363Z","end":"2026-02-26T01:43:02.636374Z","steps":["trace[1279790512] 'agreement among raft nodes before linearized reading' (duration: 166.814911ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:02.837316Z","caller":"traceutil/trace.go:171","msg":"trace[1617943182] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"195.739032ms","start":"2026-02-26T01:43:02.641548Z","end":"2026-02-26T01:43:02.837287Z","steps":["trace[1617943182] 'process raft request' (duration: 158.058467ms)","trace[1617943182] 'compare' (duration: 37.533932ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:05.480193Z","caller":"traceutil/trace.go:171","msg":"trace[142737402] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"141.479795ms","start":"2026-02-26T01:43:05.338685Z","end":"2026-02-26T01:43:05.480165Z","steps":["trace[142737402] 'process raft request' (duration: 141.22032ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:05.574089Z","caller":"traceutil/trace.go:171","msg":"trace[579548221] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1023; }","duration":"151.949013ms","start":"2026-02-26T01:43:05.422096Z","end":"2026-02-26T01:43:05.574045Z","steps":["trace[579548221] 'read index received' (duration: 57.854449ms)","trace[579548221] 'applied index is now lower than readState.Index' (duration: 94.088174ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:05.574261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.137608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:43:05.574678Z","caller":"traceutil/trace.go:171","msg":"trace[945819581] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:987; }","duration":"152.571786ms","start":"2026-02-26T01:43:05.422084Z","end":"2026-02-26T01:43:05.574656Z","steps":["trace[945819581] 'agreement among raft nodes before linearized reading' (duration: 152.053516ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:13.998026Z","caller":"traceutil/trace.go:171","msg":"trace[1201824798] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"299.105492ms","start":"2026-02-26T01:43:13.698883Z","end":"2026-02-26T01:43:13.997989Z","steps":["trace[1201824798] 'process raft request' (duration: 298.784905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:14.777586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.575582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:43:14.777759Z","caller":"traceutil/trace.go:171","msg":"trace[994873264] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"704.375821ms","start":"2026-02-26T01:43:14.073366Z","end":"2026-02-26T01:43:14.777741Z","steps":["trace[994873264] 'read index received' (duration: 405.542444ms)","trace[994873264] 'applied index is now lower than readState.Index' (duration: 298.831697ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:14.777857Z","caller":"traceutil/trace.go:171","msg":"trace[1415983299] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"772.73252ms","start":"2026-02-26T01:43:14.005109Z","end":"2026-02-26T01:43:14.777842Z","steps":["trace[1415983299] 'process raft request' (duration: 473.804911ms)","trace[1415983299] 'compare' (duration: 298.385908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:14.777948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:14.005079Z","time spent":"772.834453ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:43:14.778273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.912813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T01:43:14.77836Z","caller":"traceutil/trace.go:171","msg":"trace[2100516431] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1044; }","duration":"705.039565ms","start":"2026-02-26T01:43:14.073306Z","end":"2026-02-26T01:43:14.778345Z","steps":["trace[2100516431] 'agreement among raft nodes before linearized reading' (duration: 704.866192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:14.778423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:14.073282Z","time spent":"705.131037ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-26T01:43:14.95804Z","caller":"traceutil/trace.go:171","msg":"trace[1303189400] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"180.199589ms","start":"2026-02-26T01:43:14.77781Z","end":"2026-02-26T01:43:14.958009Z","steps":["trace[1303189400] 'read index received' (duration: 180.069357ms)","trace[1303189400] 'applied index is now lower than readState.Index' (duration: 128.542µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:14.958304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.95183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:43:14.95837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.124921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:43:14.958431Z","caller":"traceutil/trace.go:171","msg":"trace[1470847765] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1045; }","duration":"296.139703ms","start":"2026-02-26T01:43:14.66227Z","end":"2026-02-26T01:43:14.958409Z","steps":["trace[1470847765] 'agreement among raft nodes before linearized reading' (duration: 295.942319ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:14.958476Z","caller":"traceutil/trace.go:171","msg":"trace[444790524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"267.299434ms","start":"2026-02-26T01:43:14.691152Z","end":"2026-02-26T01:43:14.958451Z","steps":["trace[444790524] 'agreement among raft nodes before linearized reading' (duration: 266.918887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:14.958355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.0851ms","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-02-26T01:43:14.958563Z","caller":"traceutil/trace.go:171","msg":"trace[1211572372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1045; }","duration":"267.324385ms","start":"2026-02-26T01:43:14.691222Z","end":"2026-02-26T01:43:14.958547Z","steps":["trace[1211572372] 'agreement among raft nodes before linearized reading' (duration: 266.822855ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:14.958932Z","caller":"traceutil/trace.go:171","msg":"trace[1292384708] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"554.913877ms","start":"2026-02-26T01:43:14.403964Z","end":"2026-02-26T01:43:14.958878Z","steps":["trace[1292384708] 'process raft request' (duration: 553.865036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:14.959202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:14.403937Z","time spent":"555.134221ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:43:14.962496Z","caller":"traceutil/trace.go:171","msg":"trace[1433760932] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"178.219661ms","start":"2026-02-26T01:43:14.78424Z","end":"2026-02-26T01:43:14.962459Z","steps":["trace[1433760932] 'process raft request' (duration: 177.605388ms)"],"step_count":1}