{"level":"warn","ts":"2026-02-24T06:57:15.642898Z","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-24T06:57:15.643047Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-24T06:57:15.643616Z","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-24T06:57:15.643638Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-24T06:57:15.643702Z","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-24T06:57:15.644609Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-24T06:57:15.644856Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-24T06:57:15.649713Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.414627ms"} {"level":"info","ts":"2026-02-24T06:57:15.656062Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-24T06:57:15.658318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T06:57:15.658425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-24T06:57:15.658452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T06:57:15.658483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-24T06:57:15.658546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-24T06:57:15.667494Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T06:57:15.66899Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T06:57:15.670239Z","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-24T06:57:15.672461Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T06:57:15.673684Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T06:57:15.673861Z","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-24T06:57:15.679222Z","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-24T06:57:15.679269Z","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-24T06:57:15.681978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-24T06:57:15.682288Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-24T06:57:15.687593Z","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-24T06:57:15.687679Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-24T06:57:15.687996Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-24T06:57:15.689623Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T06:57:15.689677Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T06:57:16.559268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T06:57:16.55936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T06:57:16.55939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-24T06:57:16.559484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-24T06:57:16.559546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-24T06:57:16.559574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-24T06:57:16.559593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-24T06:57:16.778474Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T06:57:16.871243Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T06:57:16.871292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T06:57:16.871513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T06:57:16.871697Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T06:57:16.871778Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T06:57:16.874968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-24T06:57:16.876183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T06:57:16.881901Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T06:57:16.882097Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T06:57:16.882168Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-24T06:57:54.831125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.272373ms","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-02-24T06:57:54.831249Z","caller":"traceutil/trace.go:171","msg":"trace[2118451678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"463.527429ms","start":"2026-02-24T06:57:54.367694Z","end":"2026-02-24T06:57:54.831222Z","steps":["trace[2118451678] 'range keys from in-memory index tree' (duration: 463.084717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:57:54.831315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:57:54.367657Z","time spent":"463.645482ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T06:57:54.93804Z","caller":"traceutil/trace.go:171","msg":"trace[1538111324] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"102.964329ms","start":"2026-02-24T06:57:54.835045Z","end":"2026-02-24T06:57:54.938009Z","steps":["trace[1538111324] 'process raft request' (duration: 102.788155ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:57:54.968294Z","caller":"traceutil/trace.go:171","msg":"trace[1752581953] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"132.279987ms","start":"2026-02-24T06:57:54.835987Z","end":"2026-02-24T06:57:54.968267Z","steps":["trace[1752581953] 'process raft request' (duration: 132.116743ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:58:14.417688Z","caller":"traceutil/trace.go:171","msg":"trace[988407841] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"205.294261ms","start":"2026-02-24T06:58:14.212364Z","end":"2026-02-24T06:58:14.417658Z","steps":["trace[988407841] 'process raft request' (duration: 204.975392ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:58:14.896079Z","caller":"traceutil/trace.go:171","msg":"trace[91956342] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"107.321543ms","start":"2026-02-24T06:58:14.788737Z","end":"2026-02-24T06:58:14.896059Z","steps":["trace[91956342] 'process raft request' (duration: 107.173719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:58:15.268568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.560227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T06:58:15.268672Z","caller":"traceutil/trace.go:171","msg":"trace[918264261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"113.71743ms","start":"2026-02-24T06:58:15.154932Z","end":"2026-02-24T06:58:15.268649Z","steps":["trace[918264261] 'range keys from in-memory index tree' (duration: 113.351871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:58:15.268693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.952253ms","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-24T06:58:15.268784Z","caller":"traceutil/trace.go:171","msg":"trace[541856915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:725; }","duration":"231.112587ms","start":"2026-02-24T06:58:15.037649Z","end":"2026-02-24T06:58:15.268762Z","steps":["trace[541856915] 'range keys from in-memory index tree' (duration: 230.740608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:58:47.285177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.882477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T06:58:47.285221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.67498ms","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-24T06:58:47.285281Z","caller":"traceutil/trace.go:171","msg":"trace[2134807667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"520.046211ms","start":"2026-02-24T06:58:46.765212Z","end":"2026-02-24T06:58:47.285258Z","steps":["trace[2134807667] 'range keys from in-memory index tree' (duration: 519.753444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:58:47.285343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:58:46.765191Z","time spent":"520.139454ms","remote":"127.0.0.1:48146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T06:58:47.285307Z","caller":"traceutil/trace.go:171","msg":"trace[879513031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:819; }","duration":"130.824774ms","start":"2026-02-24T06:58:47.154462Z","end":"2026-02-24T06:58:47.285287Z","steps":["trace[879513031] 'range keys from in-memory index tree' (duration: 130.469185ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:58:48.063853Z","caller":"traceutil/trace.go:171","msg":"trace[1920715672] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"493.091819ms","start":"2026-02-24T06:58:47.570738Z","end":"2026-02-24T06:58:48.06383Z","steps":["trace[1920715672] 'process raft request' (duration: 492.908224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:58:48.06455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:58:47.570716Z","time spent":"493.208502ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:58:48.080485Z","caller":"traceutil/trace.go:171","msg":"trace[845857919] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:851; }","duration":"314.891724ms","start":"2026-02-24T06:58:47.765569Z","end":"2026-02-24T06:58:48.080461Z","steps":["trace[845857919] 'read index received' (duration: 298.07367ms)","trace[845857919] 'applied index is now lower than readState.Index' (duration: 16.816944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:58:48.080753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.014588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:58:48.080761Z","caller":"traceutil/trace.go:171","msg":"trace[1151863411] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"509.112122ms","start":"2026-02-24T06:58:47.571618Z","end":"2026-02-24T06:58:48.08073Z","steps":["trace[1151863411] 'process raft request' (duration: 508.748132ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:58:48.080802Z","caller":"traceutil/trace.go:171","msg":"trace[505002796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"315.268794ms","start":"2026-02-24T06:58:47.765519Z","end":"2026-02-24T06:58:48.080788Z","steps":["trace[505002796] 'agreement among raft nodes before linearized reading' (duration: 315.032428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:58:48.080899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:58:47.571604Z","time spent":"509.220765ms","remote":"127.0.0.1:48406","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-02-24T06:58:48.081022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:58:47.765499Z","time spent":"315.506301ms","remote":"127.0.0.1:48146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:58:50.406733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.58029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:58:50.407454Z","caller":"traceutil/trace.go:171","msg":"trace[1859604135] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"321.487559ms","start":"2026-02-24T06:58:50.085926Z","end":"2026-02-24T06:58:50.407414Z","steps":["trace[1859604135] 'process raft request' (duration: 109.986744ms)","trace[1859604135] 'compare' (duration: 210.478708ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:58:50.407495Z","caller":"traceutil/trace.go:171","msg":"trace[1624799040] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"305.494317ms","start":"2026-02-24T06:58:50.101977Z","end":"2026-02-24T06:58:50.407471Z","steps":["trace[1624799040] 'process raft request' (duration: 305.402185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:58:50.407646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.869829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-24T06:58:50.407696Z","caller":"traceutil/trace.go:171","msg":"trace[337464870] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:829; }","duration":"307.963462ms","start":"2026-02-24T06:58:50.099721Z","end":"2026-02-24T06:58:50.407684Z","steps":["trace[337464870] 'agreement among raft nodes before linearized reading' (duration: 307.823648ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:58:50.407493Z","caller":"traceutil/trace.go:171","msg":"trace[783783084] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:858; }","duration":"307.692815ms","start":"2026-02-24T06:58:50.099766Z","end":"2026-02-24T06:58:50.407458Z","steps":["trace[783783084] 'read index received' (duration: 96.20134ms)","trace[783783084] 'applied index is now lower than readState.Index' (duration: 211.488815ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:58:50.407738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:58:50.099701Z","time spent":"308.028764ms","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-24T06:58:50.407624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:58:50.085884Z","time spent":"321.650363ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:58:50.407873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:58:50.101957Z","time spent":"305.727023ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:59:00.604299Z","caller":"traceutil/trace.go:171","msg":"trace[1829041539] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"128.970415ms","start":"2026-02-24T06:59:00.475299Z","end":"2026-02-24T06:59:00.60427Z","steps":["trace[1829041539] 'process raft request' (duration: 128.827721ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:00.609974Z","caller":"traceutil/trace.go:171","msg":"trace[44528319] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"133.997208ms","start":"2026-02-24T06:59:00.475955Z","end":"2026-02-24T06:59:00.609952Z","steps":["trace[44528319] 'process raft request' (duration: 133.864765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:02.265188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826889025294825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T06:59:02.621708Z","caller":"traceutil/trace.go:171","msg":"trace[609543171] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"857.09914ms","start":"2026-02-24T06:59:01.764595Z","end":"2026-02-24T06:59:02.621695Z","steps":["trace[609543171] 'read index received' (duration: 856.975637ms)","trace[609543171] 'applied index is now lower than readState.Index' (duration: 123.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:59:02.621773Z","caller":"traceutil/trace.go:171","msg":"trace[929551740] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"941.710694ms","start":"2026-02-24T06:59:01.680034Z","end":"2026-02-24T06:59:02.621745Z","steps":["trace[929551740] 'process raft request' (duration: 941.572951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:02.621818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.204004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:59:02.621872Z","caller":"traceutil/trace.go:171","msg":"trace[1903657271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"857.316196ms","start":"2026-02-24T06:59:01.764542Z","end":"2026-02-24T06:59:02.621858Z","steps":["trace[1903657271] 'agreement among raft nodes before linearized reading' (duration: 857.207944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:02.621869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.978037ms","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-24T06:59:02.621894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:01.680022Z","time spent":"941.811288ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:59:02.621937Z","caller":"traceutil/trace.go:171","msg":"trace[300463068] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:912; }","duration":"176.086669ms","start":"2026-02-24T06:59:02.445835Z","end":"2026-02-24T06:59:02.621922Z","steps":["trace[300463068] 'agreement among raft nodes before linearized reading' (duration: 175.963016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:02.6219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:01.764521Z","time spent":"857.374317ms","remote":"127.0.0.1:48146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T06:59:02.726948Z","caller":"traceutil/trace.go:171","msg":"trace[452614244] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"101.87269ms","start":"2026-02-24T06:59:02.625058Z","end":"2026-02-24T06:59:02.726931Z","steps":["trace[452614244] 'process raft request' (duration: 101.745667ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:02.727511Z","caller":"traceutil/trace.go:171","msg":"trace[814744661] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"102.045695ms","start":"2026-02-24T06:59:02.625447Z","end":"2026-02-24T06:59:02.727493Z","steps":["trace[814744661] 'process raft request' (duration: 101.955402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:14.952235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.577735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:59:14.952359Z","caller":"traceutil/trace.go:171","msg":"trace[1166527209] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"104.49884ms","start":"2026-02-24T06:59:14.847844Z","end":"2026-02-24T06:59:14.952342Z","steps":["trace[1166527209] 'read index received' (duration: 59.262µs)","trace[1166527209] 'applied index is now lower than readState.Index' (duration: 104.438527ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:59:14.952407Z","caller":"traceutil/trace.go:171","msg":"trace[337519398] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"161.155246ms","start":"2026-02-24T06:59:14.791199Z","end":"2026-02-24T06:59:14.952354Z","steps":["trace[337519398] 'process raft request' (duration: 18.361905ms)","trace[337519398] 'compare' (duration: 142.449582ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:59:14.95251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.654173ms","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-24T06:59:14.952541Z","caller":"traceutil/trace.go:171","msg":"trace[1275777726] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:947; }","duration":"104.738745ms","start":"2026-02-24T06:59:14.847792Z","end":"2026-02-24T06:59:14.952531Z","steps":["trace[1275777726] 'agreement among raft nodes before linearized reading' (duration: 104.662823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:18.914743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.234456ms","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-24T06:59:18.914827Z","caller":"traceutil/trace.go:171","msg":"trace[1756699822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"192.37486ms","start":"2026-02-24T06:59:18.722435Z","end":"2026-02-24T06:59:18.91481Z","steps":["trace[1756699822] 'range keys from in-memory index tree' (duration: 192.085592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:18.91499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.959391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:59:18.91513Z","caller":"traceutil/trace.go:171","msg":"trace[1301557949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"147.137165ms","start":"2026-02-24T06:59:18.767958Z","end":"2026-02-24T06:59:18.915095Z","steps":["trace[1301557949] 'range keys from in-memory index tree' (duration: 146.910959ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:19.23647Z","caller":"traceutil/trace.go:171","msg":"trace[820358930] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"271.449558ms","start":"2026-02-24T06:59:18.964983Z","end":"2026-02-24T06:59:19.236433Z","steps":["trace[820358930] 'read index received' (duration: 270.932504ms)","trace[820358930] 'applied index is now lower than readState.Index' (duration: 515.124µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:59:19.236578Z","caller":"traceutil/trace.go:171","msg":"trace[1023325775] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"317.644607ms","start":"2026-02-24T06:59:18.91889Z","end":"2026-02-24T06:59:19.236535Z","steps":["trace[1023325775] 'process raft request' (duration: 317.315839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:19.236805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.783856ms","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-02-24T06:59:19.236856Z","caller":"traceutil/trace.go:171","msg":"trace[270069558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:977; }","duration":"271.9062ms","start":"2026-02-24T06:59:18.964935Z","end":"2026-02-24T06:59:19.236841Z","steps":["trace[270069558] 'agreement among raft nodes before linearized reading' (duration: 271.708204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:19.236895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.078002ms","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-24T06:59:19.236984Z","caller":"traceutil/trace.go:171","msg":"trace[1450652499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"267.196206ms","start":"2026-02-24T06:59:18.969765Z","end":"2026-02-24T06:59:19.236961Z","steps":["trace[1450652499] 'agreement among raft nodes before linearized reading' (duration: 266.950569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:19.237178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.269507ms","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-24T06:59:19.237212Z","caller":"traceutil/trace.go:171","msg":"trace[1373317007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"267.317418ms","start":"2026-02-24T06:59:18.969887Z","end":"2026-02-24T06:59:19.237204Z","steps":["trace[1373317007] 'agreement among raft nodes before linearized reading' (duration: 267.234806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:19.239051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:18.918869Z","time spent":"317.796222ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:59:19.436694Z","caller":"traceutil/trace.go:171","msg":"trace[2078466760] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"193.671684ms","start":"2026-02-24T06:59:19.243012Z","end":"2026-02-24T06:59:19.436684Z","steps":["trace[2078466760] 'process raft request' (duration: 193.613653ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:19.436865Z","caller":"traceutil/trace.go:171","msg":"trace[639558874] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"194.680431ms","start":"2026-02-24T06:59:19.242135Z","end":"2026-02-24T06:59:19.436815Z","steps":["trace[639558874] 'process raft request' (duration: 194.303081ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:19.436567Z","caller":"traceutil/trace.go:171","msg":"trace[522122043] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"195.165173ms","start":"2026-02-24T06:59:19.241372Z","end":"2026-02-24T06:59:19.436537Z","steps":["trace[522122043] 'process raft request' (duration: 109.985664ms)","trace[522122043] 'compare' (duration: 84.889112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:59:21.773728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.89349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:59:21.773861Z","caller":"traceutil/trace.go:171","msg":"trace[398000771] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"298.41931ms","start":"2026-02-24T06:59:21.475429Z","end":"2026-02-24T06:59:21.773848Z","steps":["trace[398000771] 'read index received' (duration: 65.256303ms)","trace[398000771] 'applied index is now lower than readState.Index' (duration: 233.162307ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:59:21.773882Z","caller":"traceutil/trace.go:171","msg":"trace[2026089541] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"327.21042ms","start":"2026-02-24T06:59:21.446644Z","end":"2026-02-24T06:59:21.773854Z","steps":["trace[2026089541] 'process raft request' (duration: 94.129336ms)","trace[2026089541] 'compare' (duration: 232.701304ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T06:59:21.773932Z","caller":"traceutil/trace.go:171","msg":"trace[1682580453] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"327.281532ms","start":"2026-02-24T06:59:21.446644Z","end":"2026-02-24T06:59:21.773926Z","steps":["trace[1682580453] 'process raft request' (duration: 327.166159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:21.773975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:21.446623Z","time spent":"327.329344ms","remote":"127.0.0.1:48406","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-02-24T06:59:21.774018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:21.446625Z","time spent":"327.324854ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T06:59:21.774178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.724948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T06:59:21.774214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.965998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T06:59:21.774226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.59595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T06:59:21.774249Z","caller":"traceutil/trace.go:171","msg":"trace[1923987094] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:993; }","duration":"298.844761ms","start":"2026-02-24T06:59:21.475386Z","end":"2026-02-24T06:59:21.774231Z","steps":["trace[1923987094] 'agreement among raft nodes before linearized reading' (duration: 298.703517ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:21.774261Z","caller":"traceutil/trace.go:171","msg":"trace[1718585949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"241.674632ms","start":"2026-02-24T06:59:21.532576Z","end":"2026-02-24T06:59:21.774251Z","steps":["trace[1718585949] 'agreement among raft nodes before linearized reading' (duration: 241.492517ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:21.774372Z","caller":"traceutil/trace.go:171","msg":"trace[92284142] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:993; }","duration":"117.164243ms","start":"2026-02-24T06:59:21.657198Z","end":"2026-02-24T06:59:21.774363Z","steps":["trace[92284142] 'agreement among raft nodes before linearized reading' (duration: 116.990079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:22.086977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.897798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T06:59:22.087122Z","caller":"traceutil/trace.go:171","msg":"trace[541561647] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"309.674218ms","start":"2026-02-24T06:59:21.777428Z","end":"2026-02-24T06:59:22.087103Z","steps":["trace[541561647] 'process raft request' (duration: 184.245386ms)","trace[541561647] 'compare' (duration: 124.780304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:59:22.087192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:21.777411Z","time spent":"309.753409ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T06:59:32.760186Z","caller":"traceutil/trace.go:171","msg":"trace[330354619] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"290.497751ms","start":"2026-02-24T06:59:32.469596Z","end":"2026-02-24T06:59:32.760093Z","steps":["trace[330354619] 'process raft request' (duration: 290.211823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:33.506824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.646129ms","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-24T06:59:33.506899Z","caller":"traceutil/trace.go:171","msg":"trace[1405150727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1062; }","duration":"197.767393ms","start":"2026-02-24T06:59:33.309114Z","end":"2026-02-24T06:59:33.506881Z","steps":["trace[1405150727] 'range keys from in-memory index tree' (duration: 197.417773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:33.507023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.484288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-24T06:59:33.507096Z","caller":"traceutil/trace.go:171","msg":"trace[172530638] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1062; }","duration":"135.618732ms","start":"2026-02-24T06:59:33.371455Z","end":"2026-02-24T06:59:33.507073Z","steps":["trace[172530638] 'range keys from in-memory index tree' (duration: 135.331734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:34.153885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826889025295157,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T06:59:34.207351Z","caller":"traceutil/trace.go:171","msg":"trace[1192418137] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"553.865884ms","start":"2026-02-24T06:59:33.653459Z","end":"2026-02-24T06:59:34.207325Z","steps":["trace[1192418137] 'read index received' (duration: 553.671149ms)","trace[1192418137] 'applied index is now lower than readState.Index' (duration: 193.825µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T06:59:34.20753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.561534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T06:59:34.207568Z","caller":"traceutil/trace.go:171","msg":"trace[1002725421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"442.648277ms","start":"2026-02-24T06:59:33.764911Z","end":"2026-02-24T06:59:34.207559Z","steps":["trace[1002725421] 'agreement among raft nodes before linearized reading' (duration: 442.540624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:34.20762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:33.764898Z","time spent":"442.697229ms","remote":"127.0.0.1:48146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T06:59:34.207637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.13002ms","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-24T06:59:34.207744Z","caller":"traceutil/trace.go:171","msg":"trace[1725088102] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1065; }","duration":"554.311485ms","start":"2026-02-24T06:59:33.65341Z","end":"2026-02-24T06:59:34.207721Z","steps":["trace[1725088102] 'agreement among raft nodes before linearized reading' (duration: 554.055709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:34.207782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.750078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T06:59:34.207808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:33.65339Z","time spent":"554.404648ms","remote":"127.0.0.1:48366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-24T06:59:34.207808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.412054ms","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:508"} {"level":"info","ts":"2026-02-24T06:59:34.207866Z","caller":"traceutil/trace.go:171","msg":"trace[283145886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"384.874232ms","start":"2026-02-24T06:59:33.822971Z","end":"2026-02-24T06:59:34.207845Z","steps":["trace[283145886] 'agreement among raft nodes before linearized reading' (duration: 384.629455ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:34.207945Z","caller":"traceutil/trace.go:171","msg":"trace[1824610333] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1065; }","duration":"124.573488ms","start":"2026-02-24T06:59:34.08334Z","end":"2026-02-24T06:59:34.207914Z","steps":["trace[1824610333] 'agreement among raft nodes before linearized reading' (duration: 124.181518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:34.207966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:33.822955Z","time spent":"385.002314ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T06:59:34.207955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.951581ms","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-02-24T06:59:34.207883Z","caller":"traceutil/trace.go:171","msg":"trace[1701201642] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"599.975862ms","start":"2026-02-24T06:59:33.607842Z","end":"2026-02-24T06:59:34.207818Z","steps":["trace[1701201642] 'process raft request' (duration: 599.356296ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T06:59:34.208113Z","caller":"traceutil/trace.go:171","msg":"trace[1982624566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"383.135675ms","start":"2026-02-24T06:59:33.824926Z","end":"2026-02-24T06:59:34.208062Z","steps":["trace[1982624566] 'agreement among raft nodes before linearized reading' (duration: 382.653173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T06:59:34.20831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:33.824909Z","time spent":"383.385312ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T06:59:34.208264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T06:59:33.607828Z","time spent":"600.352351ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T07:01:54.526533Z","caller":"traceutil/trace.go:171","msg":"trace[608632089] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"143.112376ms","start":"2026-02-24T07:01:54.383395Z","end":"2026-02-24T07:01:54.526507Z","steps":["trace[608632089] 'process raft request' (duration: 142.930791ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T07:01:58.689689Z","caller":"traceutil/trace.go:171","msg":"trace[1357434010] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"119.482584ms","start":"2026-02-24T07:01:58.570168Z","end":"2026-02-24T07:01:58.68965Z","steps":["trace[1357434010] 'process raft request' (duration: 119.263858ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T07:02:17.028165Z","caller":"traceutil/trace.go:171","msg":"trace[1794988095] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"135.369812ms","start":"2026-02-24T07:02:16.892764Z","end":"2026-02-24T07:02:17.028134Z","steps":["trace[1794988095] 'process raft request' (duration: 135.188768ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T07:02:17.151055Z","caller":"traceutil/trace.go:171","msg":"trace[918141073] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"118.531299ms","start":"2026-02-24T07:02:17.032452Z","end":"2026-02-24T07:02:17.150983Z","steps":["trace[918141073] 'process raft request' (duration: 95.208215ms)","trace[918141073] 'compare' (duration: 22.922324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T07:02:29.484689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.933307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-24T07:02:29.48482Z","caller":"traceutil/trace.go:171","msg":"trace[911606322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1779; }","duration":"109.145632ms","start":"2026-02-24T07:02:29.375648Z","end":"2026-02-24T07:02:29.484794Z","steps":["trace[911606322] 'range keys from in-memory index tree' (duration: 108.68156ms)"],"step_count":1}