{"level":"warn","ts":"2026-05-21T03:29:22.699861Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:29:22.699958Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-05-21T03:29:22.700027Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:29:22.700039Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-21T03:29:22.700058Z","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-05-21T03:29:22.700672Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-05-21T03:29:22.70075Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-05-21T03:29:22.704552Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.563785ms"} {"level":"info","ts":"2026-05-21T03:29:22.71229Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-05-21T03:29:22.712384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T03:29:22.712406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-05-21T03:29:22.712417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T03:29:22.712424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-05-21T03:29:22.712459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-05-21T03:29:22.721243Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T03:29:22.723366Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T03:29:22.7245Z","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-05-21T03:29:22.726715Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T03:29:22.727731Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T03:29:22.729548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-05-21T03:29:22.727827Z","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-05-21T03:29:22.732308Z","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-05-21T03:29:22.732541Z","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-05-21T03:29:22.736193Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-21T03:29:22.738383Z","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-05-21T03:29:22.738508Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-21T03:29:22.738552Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-21T03:29:22.739422Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T03:29:22.73949Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T03:29:23.520966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T03:29:23.521016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T03:29:23.521047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-05-21T03:29:23.521063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-05-21T03:29:23.521086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-21T03:29:23.521094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-05-21T03:29:23.521101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-21T03:29:23.710462Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:29:23.822612Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T03:29:23.822693Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:29:23.822668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:29:23.825364Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T03:29:23.825397Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T03:29:23.825609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T03:29:23.829928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-05-21T03:29:23.966066Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:29:23.966228Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:29:23.966301Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:30:00.036982Z","caller":"traceutil/trace.go:171","msg":"trace[864063439] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"112.164896ms","start":"2026-05-21T03:29:59.924789Z","end":"2026-05-21T03:30:00.036954Z","steps":["trace[864063439] 'process raft request' (duration: 38.440121ms)","trace[864063439] 'compare' (duration: 73.563021ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:00.039064Z","caller":"traceutil/trace.go:171","msg":"trace[233857386] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"108.969251ms","start":"2026-05-21T03:29:59.930079Z","end":"2026-05-21T03:30:00.039048Z","steps":["trace[233857386] 'process raft request' (duration: 108.52674ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:00.039177Z","caller":"traceutil/trace.go:171","msg":"trace[1855624695] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"106.132017ms","start":"2026-05-21T03:29:59.932965Z","end":"2026-05-21T03:30:00.039097Z","steps":["trace[1855624695] 'process raft request' (duration: 105.775707ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:05.890036Z","caller":"traceutil/trace.go:171","msg":"trace[474555879] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"135.320371ms","start":"2026-05-21T03:30:05.754686Z","end":"2026-05-21T03:30:05.890007Z","steps":["trace[474555879] 'process raft request' (duration: 134.729084ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:06.666883Z","caller":"traceutil/trace.go:171","msg":"trace[1043197639] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"103.600778ms","start":"2026-05-21T03:30:06.56325Z","end":"2026-05-21T03:30:06.66685Z","steps":["trace[1043197639] 'read index received' (duration: 102.564491ms)","trace[1043197639] 'applied index is now lower than readState.Index' (duration: 1.035137ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:06.667034Z","caller":"traceutil/trace.go:171","msg":"trace[1606630657] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"307.285872ms","start":"2026-05-21T03:30:06.35971Z","end":"2026-05-21T03:30:06.666996Z","steps":["trace[1606630657] 'process raft request' (duration: 306.4562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:06.667234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.932887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:30:06.667422Z","caller":"traceutil/trace.go:171","msg":"trace[1974873091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"104.195764ms","start":"2026-05-21T03:30:06.563203Z","end":"2026-05-21T03:30:06.667399Z","steps":["trace[1974873091] 'agreement among raft nodes before linearized reading' (duration: 103.842634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:06.66792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:06.359666Z","time spent":"307.553659ms","remote":"127.0.0.1:49908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:30:56.959949Z","caller":"traceutil/trace.go:171","msg":"trace[1333911740] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"163.914567ms","start":"2026-05-21T03:30:56.796014Z","end":"2026-05-21T03:30:56.959929Z","steps":["trace[1333911740] 'process raft request' (duration: 69.78493ms)","trace[1333911740] 'compare' (duration: 94.008924ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:57.112656Z","caller":"traceutil/trace.go:171","msg":"trace[790936948] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"174.055897ms","start":"2026-05-21T03:30:56.938579Z","end":"2026-05-21T03:30:57.112635Z","steps":["trace[790936948] 'process raft request' (duration: 173.78606ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:57.112564Z","caller":"traceutil/trace.go:171","msg":"trace[1448664626] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"149.941205ms","start":"2026-05-21T03:30:56.962598Z","end":"2026-05-21T03:30:57.112539Z","steps":["trace[1448664626] 'read index received' (duration: 149.725199ms)","trace[1448664626] 'applied index is now lower than readState.Index' (duration: 214.996µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:30:57.113094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.476149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-21T03:30:57.113146Z","caller":"traceutil/trace.go:171","msg":"trace[110305146] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1011; }","duration":"150.539311ms","start":"2026-05-21T03:30:56.962592Z","end":"2026-05-21T03:30:57.113131Z","steps":["trace[110305146] 'agreement among raft nodes before linearized reading' (duration: 150.277363ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:57.113537Z","caller":"traceutil/trace.go:171","msg":"trace[240920351] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"107.911143ms","start":"2026-05-21T03:30:57.005606Z","end":"2026-05-21T03:30:57.113517Z","steps":["trace[240920351] 'process raft request' (duration: 107.79123ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:18.06454Z","caller":"traceutil/trace.go:171","msg":"trace[1193156643] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"248.980969ms","start":"2026-05-21T03:31:17.815539Z","end":"2026-05-21T03:31:18.06452Z","steps":["trace[1193156643] 'read index received' (duration: 14.633067ms)","trace[1193156643] 'applied index is now lower than readState.Index' (duration: 234.347042ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:18.064648Z","caller":"traceutil/trace.go:171","msg":"trace[2027143273] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"312.767524ms","start":"2026-05-21T03:31:17.751854Z","end":"2026-05-21T03:31:18.064622Z","steps":["trace[2027143273] 'process raft request' (duration: 312.547207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:18.064695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.148493ms","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-05-21T03:31:18.064718Z","caller":"traceutil/trace.go:171","msg":"trace[652480688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"249.208045ms","start":"2026-05-21T03:31:17.815502Z","end":"2026-05-21T03:31:18.06471Z","steps":["trace[652480688] 'agreement among raft nodes before linearized reading' (duration: 249.111422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:18.064777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:17.75183Z","time spent":"312.877617ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:31:34.388218Z","caller":"traceutil/trace.go:171","msg":"trace[653959952] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"247.825856ms","start":"2026-05-21T03:31:34.140368Z","end":"2026-05-21T03:31:34.388194Z","steps":["trace[653959952] 'read index received' (duration: 247.603329ms)","trace[653959952] 'applied index is now lower than readState.Index' (duration: 221.387µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:34.388389Z","caller":"traceutil/trace.go:171","msg":"trace[642980917] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"256.378594ms","start":"2026-05-21T03:31:34.131985Z","end":"2026-05-21T03:31:34.388364Z","steps":["trace[642980917] 'process raft request' (duration: 256.067164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:34.38849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.095653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T03:31:34.388525Z","caller":"traceutil/trace.go:171","msg":"trace[134082474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1183; }","duration":"248.158935ms","start":"2026-05-21T03:31:34.140357Z","end":"2026-05-21T03:31:34.388515Z","steps":["trace[134082474] 'agreement among raft nodes before linearized reading' (duration: 248.00126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:34.388653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.08937ms","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-05-21T03:31:34.388707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.711875ms","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-05-21T03:31:34.388702Z","caller":"traceutil/trace.go:171","msg":"trace[1090266316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1183; }","duration":"243.155991ms","start":"2026-05-21T03:31:34.145531Z","end":"2026-05-21T03:31:34.388687Z","steps":["trace[1090266316] 'agreement among raft nodes before linearized reading' (duration: 242.981487ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:34.388732Z","caller":"traceutil/trace.go:171","msg":"trace[1947093964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"115.750837ms","start":"2026-05-21T03:31:34.272972Z","end":"2026-05-21T03:31:34.388722Z","steps":["trace[1947093964] 'agreement among raft nodes before linearized reading' (duration: 115.687625ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:34.537464Z","caller":"traceutil/trace.go:171","msg":"trace[934288475] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"145.955019ms","start":"2026-05-21T03:31:34.391475Z","end":"2026-05-21T03:31:34.53743Z","steps":["trace[934288475] 'process raft request' (duration: 145.913058ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:34.537768Z","caller":"traceutil/trace.go:171","msg":"trace[665151611] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"145.62633ms","start":"2026-05-21T03:31:34.392114Z","end":"2026-05-21T03:31:34.53774Z","steps":["trace[665151611] 'process raft request' (duration: 145.503347ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:34.537805Z","caller":"traceutil/trace.go:171","msg":"trace[1058890291] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"146.331469ms","start":"2026-05-21T03:31:34.391463Z","end":"2026-05-21T03:31:34.537794Z","steps":["trace[1058890291] 'process raft request' (duration: 145.816675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:52.407339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.656212ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-21T03:31:52.407449Z","caller":"traceutil/trace.go:171","msg":"trace[1073616028] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"364.28341ms","start":"2026-05-21T03:31:52.043146Z","end":"2026-05-21T03:31:52.40743Z","steps":["trace[1073616028] 'read index received' (duration: 74.331µs)","trace[1073616028] 'applied index is now lower than readState.Index' (duration: 364.206609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:52.407626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.499935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-21T03:31:52.40766Z","caller":"traceutil/trace.go:171","msg":"trace[1433849744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"364.599088ms","start":"2026-05-21T03:31:52.04305Z","end":"2026-05-21T03:31:52.407649Z","steps":["trace[1433849744] 'agreement among raft nodes before linearized reading' (duration: 364.429574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:52.407695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:52.043017Z","time spent":"364.66897ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T03:31:52.918859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.749909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:31:52.919025Z","caller":"traceutil/trace.go:171","msg":"trace[1983851541] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"272.375698ms","start":"2026-05-21T03:31:52.646633Z","end":"2026-05-21T03:31:52.919009Z","steps":["trace[1983851541] 'process raft request' (duration: 156.788767ms)","trace[1983851541] 'compare' (duration: 114.576675ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:52.918971Z","caller":"traceutil/trace.go:171","msg":"trace[2059113767] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"235.457787ms","start":"2026-05-21T03:31:52.683497Z","end":"2026-05-21T03:31:52.918955Z","steps":["trace[2059113767] 'read index received' (duration: 119.946787ms)","trace[2059113767] 'applied index is now lower than readState.Index' (duration: 115.50914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:52.919912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.29548ms","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-05-21T03:31:52.91996Z","caller":"traceutil/trace.go:171","msg":"trace[1745076575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"297.392563ms","start":"2026-05-21T03:31:52.622555Z","end":"2026-05-21T03:31:52.919947Z","steps":["trace[1745076575] 'agreement among raft nodes before linearized reading' (duration: 297.035603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:52.93151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.195439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:52.931596Z","caller":"traceutil/trace.go:171","msg":"trace[550084541] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1274; }","duration":"218.326011ms","start":"2026-05-21T03:31:52.713251Z","end":"2026-05-21T03:31:52.931577Z","steps":["trace[550084541] 'agreement among raft nodes before linearized reading' (duration: 218.130026ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:52.932087Z","caller":"traceutil/trace.go:171","msg":"trace[1329819960] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"245.01939ms","start":"2026-05-21T03:31:52.687054Z","end":"2026-05-21T03:31:52.932074Z","steps":["trace[1329819960] 'process raft request' (duration: 244.151798ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:03.25296Z","caller":"traceutil/trace.go:171","msg":"trace[1086957586] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1398; }","duration":"242.104403ms","start":"2026-05-21T03:32:03.010833Z","end":"2026-05-21T03:32:03.252937Z","steps":["trace[1086957586] 'read index received' (duration: 241.898147ms)","trace[1086957586] 'applied index is now lower than readState.Index' (duration: 205.376µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:32:03.253037Z","caller":"traceutil/trace.go:171","msg":"trace[330875942] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"273.816716ms","start":"2026-05-21T03:32:02.979181Z","end":"2026-05-21T03:32:03.252998Z","steps":["trace[330875942] 'process raft request' (duration: 273.606311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:03.253191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.332096ms","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-05-21T03:32:03.253244Z","caller":"traceutil/trace.go:171","msg":"trace[904809600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1346; }","duration":"234.442619ms","start":"2026-05-21T03:32:03.018788Z","end":"2026-05-21T03:32:03.253231Z","steps":["trace[904809600] 'agreement among raft nodes before linearized reading' (duration: 234.280495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:03.253423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.571685ms","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-05-21T03:32:03.253569Z","caller":"traceutil/trace.go:171","msg":"trace[425264661] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1346; }","duration":"242.776221ms","start":"2026-05-21T03:32:03.010776Z","end":"2026-05-21T03:32:03.253552Z","steps":["trace[425264661] 'agreement among raft nodes before linearized reading' (duration: 242.276908ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:03.377579Z","caller":"traceutil/trace.go:171","msg":"trace[1643992326] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"119.298061ms","start":"2026-05-21T03:32:03.258255Z","end":"2026-05-21T03:32:03.377553Z","steps":["trace[1643992326] 'process raft request' (duration: 51.432897ms)","trace[1643992326] 'compare' (duration: 67.743471ms)"],"step_count":2}