{"level":"warn","ts":"2026-05-07T03:16:23.664511Z","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-07T03:16:23.664626Z","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-05-07T03:16:23.66471Z","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-07T03:16:23.664727Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-05-07T03:16:23.664752Z","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-07T03:16:23.665611Z","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-05-07T03:16:23.66576Z","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-05-07T03:16:23.670606Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.504803ms"} {"level":"info","ts":"2026-05-07T03:16:23.676243Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-05-07T03:16:23.676324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T03:16:23.676346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-05-07T03:16:23.676362Z","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-05-07T03:16:23.67637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-05-07T03:16:23.676404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-05-07T03:16:23.681663Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T03:16:23.682914Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T03:16:23.683893Z","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-07T03:16:23.686149Z","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-05-07T03:16:23.68647Z","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-05-07T03:16:23.686581Z","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-07T03:16:23.686647Z","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-07T03:16:23.686657Z","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-07T03:16:23.689595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-05-07T03:16:23.689755Z","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-05-07T03:16:23.691992Z","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-07T03:16:23.692202Z","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-05-07T03:16:23.692235Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T03:16:23.692348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-05-07T03:16:23.692365Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-05-07T03:16:24.277377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T03:16:24.277435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T03:16:24.277458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-05-07T03:16:24.277492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-05-07T03:16:24.277507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-05-07T03:16:24.277523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-05-07T03:16:24.277543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-05-07T03:16:24.278642Z","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-05-07T03:16:24.278649Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:16:24.278699Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:16:24.2787Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:16:24.279181Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T03:16:24.279218Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T03:16:24.279996Z","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-05-07T03:16:24.280368Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:16:24.280471Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:16:24.282271Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T03:16:24.283405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-05-07T03:16:25.731703Z","caller":"traceutil/trace.go:171","msg":"trace[1730598025] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"149.010386ms","start":"2026-05-07T03:16:25.58267Z","end":"2026-05-07T03:16:25.73168Z","steps":["trace[1730598025] 'read index received' (duration: 148.868765ms)","trace[1730598025] 'applied index is now lower than readState.Index' (duration: 140.541µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:16:25.731735Z","caller":"traceutil/trace.go:171","msg":"trace[491926241] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"149.784299ms","start":"2026-05-07T03:16:25.581923Z","end":"2026-05-07T03:16:25.731707Z","steps":["trace[491926241] 'process raft request' (duration: 149.577502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:16:25.732072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.27226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:16:25.732183Z","caller":"traceutil/trace.go:171","msg":"trace[595819849] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"149.501076ms","start":"2026-05-07T03:16:25.582656Z","end":"2026-05-07T03:16:25.732157Z","steps":["trace[595819849] 'agreement among raft nodes before linearized reading' (duration: 149.145958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:16:25.750561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.868722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:16:25.750619Z","caller":"traceutil/trace.go:171","msg":"trace[1344110983] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"167.944587ms","start":"2026-05-07T03:16:25.582659Z","end":"2026-05-07T03:16:25.750604Z","steps":["trace[1344110983] 'agreement among raft nodes before linearized reading' (duration: 167.805615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:16:25.750661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.751658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:16:25.750723Z","caller":"traceutil/trace.go:171","msg":"trace[1546021078] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"113.853231ms","start":"2026-05-07T03:16:25.636855Z","end":"2026-05-07T03:16:25.750708Z","steps":["trace[1546021078] 'agreement among raft nodes before linearized reading' (duration: 113.764837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:16:25.751108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.150112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:16:25.751183Z","caller":"traceutil/trace.go:171","msg":"trace[227910065] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"122.233487ms","start":"2026-05-07T03:16:25.628915Z","end":"2026-05-07T03:16:25.751149Z","steps":["trace[227910065] 'agreement among raft nodes before linearized reading' (duration: 122.164861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:16:25.751357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.610225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:16:25.751425Z","caller":"traceutil/trace.go:171","msg":"trace[1636678447] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"114.708729ms","start":"2026-05-07T03:16:25.636699Z","end":"2026-05-07T03:16:25.751408Z","steps":["trace[1636678447] 'agreement among raft nodes before linearized reading' (duration: 114.582097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:16:25.751961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.049741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:16:25.752029Z","caller":"traceutil/trace.go:171","msg":"trace[395888853] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"168.123797ms","start":"2026-05-07T03:16:25.583884Z","end":"2026-05-07T03:16:25.752008Z","steps":["trace[395888853] 'agreement among raft nodes before linearized reading' (duration: 167.2399ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:17:16.818993Z","caller":"traceutil/trace.go:171","msg":"trace[985908098] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"329.354569ms","start":"2026-05-07T03:17:16.489595Z","end":"2026-05-07T03:17:16.818949Z","steps":["trace[985908098] 'read index received' (duration: 329.305869ms)","trace[985908098] 'applied index is now lower than readState.Index' (duration: 47.8µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:17:16.819106Z","caller":"traceutil/trace.go:171","msg":"trace[1861390102] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"331.519535ms","start":"2026-05-07T03:17:16.487541Z","end":"2026-05-07T03:17:16.819061Z","steps":["trace[1861390102] 'process raft request' (duration: 331.291243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:16.819162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.545929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:17:16.819276Z","caller":"traceutil/trace.go:171","msg":"trace[446403011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"329.670242ms","start":"2026-05-07T03:17:16.489562Z","end":"2026-05-07T03:17:16.819232Z","steps":["trace[446403011] 'agreement among raft nodes before linearized reading' (duration: 329.507877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:16.819336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:16.48955Z","time spent":"329.77773ms","remote":"127.0.0.1:40420","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-05-07T03:17:16.820672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:16.487528Z","time spent":"331.697338ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4360,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:17:18.692798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219660079464522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:17:19.193292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219660079464522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:17:19.693491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219660079464522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:17:20.053102Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.53700494s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:17:20.053557Z","caller":"traceutil/trace.go:171","msg":"trace[1452655651] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"1.861571094s","start":"2026-05-07T03:17:18.191936Z","end":"2026-05-07T03:17:20.053507Z","steps":["trace[1452655651] 'read index received' (duration: 1.861350992s)","trace[1452655651] 'applied index is now lower than readState.Index' (duration: 218.973µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:17:20.053672Z","caller":"traceutil/trace.go:171","msg":"trace[1375663194] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"2.53766346s","start":"2026-05-07T03:17:17.515998Z","end":"2026-05-07T03:17:20.053662Z","steps":["trace[1375663194] 'process raft request' (duration: 2.537379481s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:20.05383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:17.515981Z","time spent":"2.537734685s","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:17:20.053938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772607025s","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-05-07T03:17:20.054011Z","caller":"traceutil/trace.go:171","msg":"trace[672713442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:458; }","duration":"1.77272317s","start":"2026-05-07T03:17:18.281269Z","end":"2026-05-07T03:17:20.053992Z","steps":["trace[672713442] 'agreement among raft nodes before linearized reading' (duration: 1.772604926s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:20.054045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:18.281245Z","time spent":"1.772792535s","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:17:20.054045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562027422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:17:20.054135Z","caller":"traceutil/trace.go:171","msg":"trace[105508699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"1.562153486s","start":"2026-05-07T03:17:18.491949Z","end":"2026-05-07T03:17:20.054102Z","steps":["trace[105508699] 'agreement among raft nodes before linearized reading' (duration: 1.562004258s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:20.054167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73541816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-07T03:17:20.054181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:18.491935Z","time spent":"1.562237967s","remote":"127.0.0.1:40420","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-05-07T03:17:20.054194Z","caller":"traceutil/trace.go:171","msg":"trace[193139721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:458; }","duration":"1.735481876s","start":"2026-05-07T03:17:18.318704Z","end":"2026-05-07T03:17:20.054186Z","steps":["trace[193139721] 'agreement among raft nodes before linearized reading' (duration: 1.735431486s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:20.054219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:18.318685Z","time spent":"1.735528616s","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:17:20.054243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862255038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-05-07T03:17:20.054316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736435883s","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-05-07T03:17:20.054365Z","caller":"traceutil/trace.go:171","msg":"trace[2126076607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"1.736520145s","start":"2026-05-07T03:17:18.317828Z","end":"2026-05-07T03:17:20.054349Z","steps":["trace[2126076607] 'agreement among raft nodes before linearized reading' (duration: 1.736436393s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:20.054396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:18.317788Z","time spent":"1.736601488s","remote":"127.0.0.1:40722","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-05-07T03:17:20.054379Z","caller":"traceutil/trace.go:171","msg":"trace[1113738375] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:458; }","duration":"1.862446156s","start":"2026-05-07T03:17:18.191901Z","end":"2026-05-07T03:17:20.054347Z","steps":["trace[1113738375] 'agreement among raft nodes before linearized reading' (duration: 1.862144101s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:20.054479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:18.191879Z","time spent":"1.862585857s","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4078,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-05-07T03:17:33.051449Z","caller":"traceutil/trace.go:171","msg":"trace[1926251814] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"105.207133ms","start":"2026-05-07T03:17:32.946221Z","end":"2026-05-07T03:17:33.051428Z","steps":["trace[1926251814] 'process raft request' (duration: 105.034802ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:17:38.336129Z","caller":"traceutil/trace.go:171","msg":"trace[419318956] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"196.762396ms","start":"2026-05-07T03:17:38.139329Z","end":"2026-05-07T03:17:38.336091Z","steps":["trace[419318956] 'process raft request' (duration: 196.527588ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:17:38.336498Z","caller":"traceutil/trace.go:171","msg":"trace[1449511164] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:671; }","duration":"194.596789ms","start":"2026-05-07T03:17:38.141886Z","end":"2026-05-07T03:17:38.336483Z","steps":["trace[1449511164] 'read index received' (duration: 59.410965ms)","trace[1449511164] 'applied index is now lower than readState.Index' (duration: 135.185044ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:17:38.336791Z","caller":"traceutil/trace.go:171","msg":"trace[1161010815] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"195.474418ms","start":"2026-05-07T03:17:38.141282Z","end":"2026-05-07T03:17:38.336756Z","steps":["trace[1161010815] 'process raft request' (duration: 194.934632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:38.336819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.909168ms","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-05-07T03:17:38.33689Z","caller":"traceutil/trace.go:171","msg":"trace[210280133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"195.012324ms","start":"2026-05-07T03:17:38.14186Z","end":"2026-05-07T03:17:38.336872Z","steps":["trace[210280133] 'agreement among raft nodes before linearized reading' (duration: 194.779277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:38.337221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.883804ms","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-05-07T03:17:38.337397Z","caller":"traceutil/trace.go:171","msg":"trace[1407657759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"195.204971ms","start":"2026-05-07T03:17:38.142175Z","end":"2026-05-07T03:17:38.33738Z","steps":["trace[1407657759] 'agreement among raft nodes before linearized reading' (duration: 194.556259ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:17:39.073397Z","caller":"traceutil/trace.go:171","msg":"trace[433594788] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"270.378267ms","start":"2026-05-07T03:17:38.802982Z","end":"2026-05-07T03:17:39.07336Z","steps":["trace[433594788] 'process raft request' (duration: 269.641615ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:17:39.305766Z","caller":"traceutil/trace.go:171","msg":"trace[702584433] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"151.411013ms","start":"2026-05-07T03:17:39.154322Z","end":"2026-05-07T03:17:39.305733Z","steps":["trace[702584433] 'process raft request' (duration: 126.04043ms)","trace[702584433] 'compare' (duration: 25.123859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:17:54.550266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.256357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k56qn\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-05-07T03:17:54.550313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.866804ms","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-05-07T03:17:54.550368Z","caller":"traceutil/trace.go:171","msg":"trace[1549992146] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k56qn; range_end:; response_count:1; response_revision:820; }","duration":"209.407114ms","start":"2026-05-07T03:17:54.340939Z","end":"2026-05-07T03:17:54.550346Z","steps":["trace[1549992146] 'range keys from in-memory index tree' (duration: 209.07367ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:17:54.550405Z","caller":"traceutil/trace.go:171","msg":"trace[12979744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"134.003716ms","start":"2026-05-07T03:17:54.416379Z","end":"2026-05-07T03:17:54.550383Z","steps":["trace[12979744] 'range keys from in-memory index tree' (duration: 133.681976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:54.550465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.847256ms","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-05-07T03:17:54.550558Z","caller":"traceutil/trace.go:171","msg":"trace[601544953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:820; }","duration":"132.98819ms","start":"2026-05-07T03:17:54.417549Z","end":"2026-05-07T03:17:54.550538Z","steps":["trace[601544953] 'range keys from in-memory index tree' (duration: 132.641572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:54.551041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.505771ms","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-05-07T03:17:54.551105Z","caller":"traceutil/trace.go:171","msg":"trace[1250303912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:820; }","duration":"133.581115ms","start":"2026-05-07T03:17:54.417506Z","end":"2026-05-07T03:17:54.551088Z","steps":["trace[1250303912] 'range keys from in-memory index tree' (duration: 132.530357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:57.857359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219660079465319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:17:58.358409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219660079465319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:17:58.858773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219660079465319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:17:59.304063Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.038618231s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T03:17:59.304444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:57.265301Z","time spent":"2.039138276s","remote":"127.0.0.1:40478","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T03:17:59.305199Z","caller":"traceutil/trace.go:171","msg":"trace[975310443] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:866; }","duration":"1.948583855s","start":"2026-05-07T03:17:57.356588Z","end":"2026-05-07T03:17:59.305171Z","steps":["trace[975310443] 'read index received' (duration: 1.947742775s)","trace[975310443] 'applied index is now lower than readState.Index' (duration: 839.23µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:17:59.305603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.948966682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k56qn\" ","response":"range_response_count:1 size:4222"} {"level":"info","ts":"2026-05-07T03:17:59.305737Z","caller":"traceutil/trace.go:171","msg":"trace[1602081926] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k56qn; range_end:; response_count:1; response_revision:833; }","duration":"1.949147432s","start":"2026-05-07T03:17:57.356566Z","end":"2026-05-07T03:17:59.305713Z","steps":["trace[1602081926] 'agreement among raft nodes before linearized reading' (duration: 1.948833056s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:59.305781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01335565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T03:17:59.305784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:57.356555Z","time spent":"1.949218932s","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":4244,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k56qn\" "} {"level":"info","ts":"2026-05-07T03:17:59.305892Z","caller":"traceutil/trace.go:171","msg":"trace[1719930179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:833; }","duration":"1.013523016s","start":"2026-05-07T03:17:58.292349Z","end":"2026-05-07T03:17:59.305872Z","steps":["trace[1719930179] 'agreement among raft nodes before linearized reading' (duration: 1.01324718s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:59.305943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:58.292285Z","time spent":"1.01364462s","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T03:17:59.305704Z","caller":"traceutil/trace.go:171","msg":"trace[408905938] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"1.412112101s","start":"2026-05-07T03:17:57.893492Z","end":"2026-05-07T03:17:59.305604Z","steps":["trace[408905938] 'process raft request' (duration: 1.41152996s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:59.306107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.167696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:17:59.306158Z","caller":"traceutil/trace.go:171","msg":"trace[658183502] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:833; }","duration":"664.275216ms","start":"2026-05-07T03:17:58.641869Z","end":"2026-05-07T03:17:59.306144Z","steps":["trace[658183502] 'agreement among raft nodes before linearized reading' (duration: 664.196244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:59.306204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:58.641857Z","time spent":"664.333792ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:17:59.306272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.084046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-07T03:17:59.306497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.532093ms","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-07T03:17:59.306487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.15212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:17:59.306313Z","caller":"traceutil/trace.go:171","msg":"trace[115250051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:833; }","duration":"694.146103ms","start":"2026-05-07T03:17:58.612157Z","end":"2026-05-07T03:17:59.306303Z","steps":["trace[115250051] 'agreement among raft nodes before linearized reading' (duration: 694.043524ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:17:59.306553Z","caller":"traceutil/trace.go:171","msg":"trace[1321429798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:833; }","duration":"701.602572ms","start":"2026-05-07T03:17:58.604936Z","end":"2026-05-07T03:17:59.306539Z","steps":["trace[1321429798] 'agreement among raft nodes before linearized reading' (duration: 701.465585ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:17:59.306567Z","caller":"traceutil/trace.go:171","msg":"trace[1532994591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"815.274014ms","start":"2026-05-07T03:17:58.491275Z","end":"2026-05-07T03:17:59.306549Z","steps":["trace[1532994591] 'agreement among raft nodes before linearized reading' (duration: 815.139727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:59.306583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:58.612146Z","time spent":"694.426801ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:17:59.306593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:58.60493Z","time spent":"701.653175ms","remote":"127.0.0.1:40722","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":"warn","ts":"2026-05-07T03:17:59.306603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:58.491254Z","time spent":"815.342162ms","remote":"127.0.0.1:40420","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-05-07T03:17:59.306512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:57.893469Z","time spent":"1.412629133s","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:17:59.306735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.573677ms","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-07T03:17:59.307263Z","caller":"traceutil/trace.go:171","msg":"trace[2032121617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:833; }","duration":"688.102112ms","start":"2026-05-07T03:17:58.619141Z","end":"2026-05-07T03:17:59.307243Z","steps":["trace[2032121617] 'agreement among raft nodes before linearized reading' (duration: 687.530386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:59.307308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:58.619135Z","time spent":"688.163409ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T03:17:59.30681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.475156ms","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-05-07T03:17:59.307366Z","caller":"traceutil/trace.go:171","msg":"trace[1281176136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:833; }","duration":"692.045134ms","start":"2026-05-07T03:17:58.615305Z","end":"2026-05-07T03:17:59.30735Z","steps":["trace[1281176136] 'agreement among raft nodes before linearized reading' (duration: 691.457362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:17:59.30745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:17:58.615299Z","time spent":"692.135278ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:20:10.386301Z","caller":"traceutil/trace.go:171","msg":"trace[1856539510] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"410.805805ms","start":"2026-05-07T03:20:09.975475Z","end":"2026-05-07T03:20:10.38628Z","steps":["trace[1856539510] 'process raft request' (duration: 410.054515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:10.386429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:09.975463Z","time spent":"410.910123ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:10.386416Z","caller":"traceutil/trace.go:171","msg":"trace[216903687] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"376.932044ms","start":"2026-05-07T03:20:10.009454Z","end":"2026-05-07T03:20:10.386386Z","steps":["trace[216903687] 'process raft request' (duration: 376.836706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:10.386616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:10.009434Z","time spent":"377.062019ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:10.386428Z","caller":"traceutil/trace.go:171","msg":"trace[1300843749] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1502; }","duration":"402.122173ms","start":"2026-05-07T03:20:09.984265Z","end":"2026-05-07T03:20:10.386387Z","steps":["trace[1300843749] 'read index received' (duration: 401.252978ms)","trace[1300843749] 'applied index is now lower than readState.Index' (duration: 864.186µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:10.386696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.318698ms","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-07T03:20:10.386933Z","caller":"traceutil/trace.go:171","msg":"trace[643430341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1441; }","duration":"402.576468ms","start":"2026-05-07T03:20:09.984248Z","end":"2026-05-07T03:20:10.386824Z","steps":["trace[643430341] 'agreement among raft nodes before linearized reading' (duration: 402.22571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:10.387065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.761825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-qrr7z\" ","response":"range_response_count:1 size:3720"} {"level":"warn","ts":"2026-05-07T03:20:10.387077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:09.98424Z","time spent":"402.742997ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T03:20:10.386944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.564889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T03:20:10.387183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.835965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4660"} {"level":"info","ts":"2026-05-07T03:20:10.387224Z","caller":"traceutil/trace.go:171","msg":"trace[1505239633] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:1441; }","duration":"357.909495ms","start":"2026-05-07T03:20:10.029302Z","end":"2026-05-07T03:20:10.387212Z","steps":["trace[1505239633] 'agreement among raft nodes before linearized reading' (duration: 357.838384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:10.387271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:10.029284Z","time spent":"357.974748ms","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4682,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-05-07T03:20:10.387202Z","caller":"traceutil/trace.go:171","msg":"trace[135639470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1441; }","duration":"402.797351ms","start":"2026-05-07T03:20:09.984361Z","end":"2026-05-07T03:20:10.387159Z","steps":["trace[135639470] 'agreement among raft nodes before linearized reading' (duration: 402.48014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:10.387479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:09.984313Z","time spent":"403.13165ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:20:10.387116Z","caller":"traceutil/trace.go:171","msg":"trace[915808957] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-qrr7z; range_end:; response_count:1; response_revision:1441; }","duration":"356.832506ms","start":"2026-05-07T03:20:10.030267Z","end":"2026-05-07T03:20:10.3871Z","steps":["trace[915808957] 'agreement among raft nodes before linearized reading' (duration: 356.71764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:10.387605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:10.030253Z","time spent":"357.332445ms","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3742,"request content":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-qrr7z\" "}