{"level":"warn","ts":"2026-03-20T05:28:43.381317Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:28:43.381415Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-20T05:28:43.381542Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:28:43.381559Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-20T05:28:43.3816Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-20T05:28:43.382417Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-03-20T05:28:43.382572Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-20T05:28:43.386915Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.046838ms"} {"level":"info","ts":"2026-03-20T05:28:43.39349Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-03-20T05:28:43.393591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T05:28:43.393644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-03-20T05:28:43.393664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T05:28:43.393681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-03-20T05:28:43.393784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-03-20T05:28:43.410507Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T05:28:43.412304Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T05:28:43.413513Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-20T05:28:43.416416Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T05:28:43.416651Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T05:28:43.416753Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:28:43.416829Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:28:43.416841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T05:28:43.417387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-03-20T05:28:43.417612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-20T05:28:43.426066Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-20T05:28:43.426149Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-20T05:28:43.426169Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-20T05:28:43.427154Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T05:28:43.427107Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T05:28:44.294416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T05:28:44.294482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T05:28:44.294526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-03-20T05:28:44.294554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-03-20T05:28:44.294572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-20T05:28:44.294589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-03-20T05:28:44.294608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-20T05:28:44.29575Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:28:44.295801Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:28:44.295721Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T05:28:44.295898Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:28:44.296518Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T05:28:44.29659Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T05:28:44.296623Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:28:44.296709Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:28:44.296738Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:28:44.29834Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-03-20T05:28:44.298449Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-20T05:29:11.846251Z","caller":"traceutil/trace.go:171","msg":"trace[1179267170] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"149.850224ms","start":"2026-03-20T05:29:11.696373Z","end":"2026-03-20T05:29:11.846223Z","steps":["trace[1179267170] 'process raft request' (duration: 149.690072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:12.181218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.045932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-20T05:29:12.181159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.460276ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:29:12.181329Z","caller":"traceutil/trace.go:171","msg":"trace[1580461735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"175.214224ms","start":"2026-03-20T05:29:12.006086Z","end":"2026-03-20T05:29:12.1813Z","steps":["trace[1580461735] 'range keys from in-memory index tree' (duration: 174.957505ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:12.181348Z","caller":"traceutil/trace.go:171","msg":"trace[1304771203] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:506; }","duration":"177.690973ms","start":"2026-03-20T05:29:12.003628Z","end":"2026-03-20T05:29:12.181319Z","steps":["trace[1304771203] 'range keys from in-memory index tree' (duration: 177.428604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:12.181338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.286213ms","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-03-20T05:29:12.181436Z","caller":"traceutil/trace.go:171","msg":"trace[397661469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"182.415183ms","start":"2026-03-20T05:29:11.998996Z","end":"2026-03-20T05:29:12.181411Z","steps":["trace[397661469] 'range keys from in-memory index tree' (duration: 182.149413ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:12.558112Z","caller":"traceutil/trace.go:171","msg":"trace[1763575461] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"371.921009ms","start":"2026-03-20T05:29:12.186157Z","end":"2026-03-20T05:29:12.558078Z","steps":["trace[1763575461] 'process raft request' (duration: 371.739885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:12.558994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:12.186133Z","time spent":"372.06534ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:29:12.972327Z","caller":"traceutil/trace.go:171","msg":"trace[366254878] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"786.058222ms","start":"2026-03-20T05:29:12.186227Z","end":"2026-03-20T05:29:12.972285Z","steps":["trace[366254878] 'process raft request' (duration: 712.486526ms)","trace[366254878] 'compare' (duration: 73.388472ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:29:12.972528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:12.186205Z","time spent":"786.220525ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:29:12.976666Z","caller":"traceutil/trace.go:171","msg":"trace[682738105] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"672.315789ms","start":"2026-03-20T05:29:12.304306Z","end":"2026-03-20T05:29:12.976621Z","steps":["trace[682738105] 'read index received' (duration: 253.577615ms)","trace[682738105] 'applied index is now lower than readState.Index' (duration: 418.737104ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:29:12.976989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.669386ms","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-03-20T05:29:12.977185Z","caller":"traceutil/trace.go:171","msg":"trace[1217038961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"672.904565ms","start":"2026-03-20T05:29:12.304264Z","end":"2026-03-20T05:29:12.977169Z","steps":["trace[1217038961] 'agreement among raft nodes before linearized reading' (duration: 672.655996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:12.977095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.26921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"warn","ts":"2026-03-20T05:29:12.977245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:12.304228Z","time spent":"673.006012ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-20T05:29:12.977276Z","caller":"traceutil/trace.go:171","msg":"trace[1634218468] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"480.502178ms","start":"2026-03-20T05:29:12.496744Z","end":"2026-03-20T05:29:12.977246Z","steps":["trace[1634218468] 'agreement among raft nodes before linearized reading' (duration: 480.238408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:12.977319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:12.496718Z","time spent":"480.594086ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4396,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-20T05:29:18.418151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879705516443035,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:29:18.444524Z","caller":"traceutil/trace.go:171","msg":"trace[1028461331] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"527.039687ms","start":"2026-03-20T05:29:17.917459Z","end":"2026-03-20T05:29:18.444498Z","steps":["trace[1028461331] 'read index received' (duration: 526.853782ms)","trace[1028461331] 'applied index is now lower than readState.Index' (duration: 185.005µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:29:18.444646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.157496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:29:18.444687Z","caller":"traceutil/trace.go:171","msg":"trace[1054764144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"527.265034ms","start":"2026-03-20T05:29:17.917408Z","end":"2026-03-20T05:29:18.444673Z","steps":["trace[1054764144] 'agreement among raft nodes before linearized reading' (duration: 527.180358ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:18.444652Z","caller":"traceutil/trace.go:171","msg":"trace[1674359776] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"548.016524ms","start":"2026-03-20T05:29:17.896599Z","end":"2026-03-20T05:29:18.444616Z","steps":["trace[1674359776] 'process raft request' (duration: 547.757524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:18.444862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:17.896576Z","time spent":"548.185616ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:29:18.444727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:17.917385Z","time spent":"527.33447ms","remote":"127.0.0.1:54526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:29:18.841155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.52734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T05:29:18.841211Z","caller":"traceutil/trace.go:171","msg":"trace[872894366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"250.599876ms","start":"2026-03-20T05:29:18.590599Z","end":"2026-03-20T05:29:18.841198Z","steps":["trace[872894366] 'range keys from in-memory index tree' (duration: 250.39438ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:19.766627Z","caller":"traceutil/trace.go:171","msg":"trace[548464135] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"264.524697ms","start":"2026-03-20T05:29:19.502076Z","end":"2026-03-20T05:29:19.766601Z","steps":["trace[548464135] 'process raft request' (duration: 264.374775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:20.673762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.505837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-20T05:29:20.67385Z","caller":"traceutil/trace.go:171","msg":"trace[847525877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"225.641427ms","start":"2026-03-20T05:29:20.448189Z","end":"2026-03-20T05:29:20.673831Z","steps":["trace[847525877] 'range keys from in-memory index tree' (duration: 225.319943ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:21.354982Z","caller":"traceutil/trace.go:171","msg":"trace[844187681] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"500.396492ms","start":"2026-03-20T05:29:20.854559Z","end":"2026-03-20T05:29:21.354956Z","steps":["trace[844187681] 'read index received' (duration: 500.24459ms)","trace[844187681] 'applied index is now lower than readState.Index' (duration: 150.882µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:29:21.355159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.673581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:29:21.355204Z","caller":"traceutil/trace.go:171","msg":"trace[499025357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"438.774139ms","start":"2026-03-20T05:29:20.916418Z","end":"2026-03-20T05:29:21.355192Z","steps":["trace[499025357] 'agreement among raft nodes before linearized reading' (duration: 438.681462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:21.355215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.641422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-20T05:29:21.355242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:20.916396Z","time spent":"438.838194ms","remote":"127.0.0.1:54526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:29:21.355255Z","caller":"traceutil/trace.go:171","msg":"trace[1277538624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"500.743689ms","start":"2026-03-20T05:29:20.854502Z","end":"2026-03-20T05:29:21.355245Z","steps":["trace[1277538624] 'agreement among raft nodes before linearized reading' (duration: 500.560165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:21.355288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:20.85448Z","time spent":"500.802233ms","remote":"127.0.0.1:54778","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-03-20T05:29:21.355285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.085801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-03-20T05:29:21.355325Z","caller":"traceutil/trace.go:171","msg":"trace[1312780530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"341.171607ms","start":"2026-03-20T05:29:21.014142Z","end":"2026-03-20T05:29:21.355313Z","steps":["trace[1312780530] 'agreement among raft nodes before linearized reading' (duration: 341.024426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:21.355357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:21.014121Z","time spent":"341.228022ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":524,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:29:21.355461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.743836ms","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-03-20T05:29:21.355531Z","caller":"traceutil/trace.go:171","msg":"trace[1956850092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"356.858394ms","start":"2026-03-20T05:29:20.998655Z","end":"2026-03-20T05:29:21.355513Z","steps":["trace[1956850092] 'agreement among raft nodes before linearized reading' (duration: 356.627436ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:21.355037Z","caller":"traceutil/trace.go:171","msg":"trace[708489859] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"677.158373ms","start":"2026-03-20T05:29:20.677843Z","end":"2026-03-20T05:29:21.355002Z","steps":["trace[708489859] 'process raft request' (duration: 676.976709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:29:21.355572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:20.998638Z","time spent":"356.925839ms","remote":"127.0.0.1:54778","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-03-20T05:29:21.35566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:29:20.677821Z","time spent":"677.76112ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:29:23.319453Z","caller":"traceutil/trace.go:171","msg":"trace[2019064107] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"193.400679ms","start":"2026-03-20T05:29:23.126029Z","end":"2026-03-20T05:29:23.319429Z","steps":["trace[2019064107] 'process raft request' (duration: 193.265088ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:29:26.167733Z","caller":"traceutil/trace.go:171","msg":"trace[1224552042] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"122.874771ms","start":"2026-03-20T05:29:26.044815Z","end":"2026-03-20T05:29:26.16769Z","steps":["trace[1224552042] 'process raft request' (duration: 121.32867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:30:46.283323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879705516444082,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:30:46.448952Z","caller":"traceutil/trace.go:171","msg":"trace[1716566098] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"666.021776ms","start":"2026-03-20T05:30:45.782904Z","end":"2026-03-20T05:30:46.448926Z","steps":["trace[1716566098] 'read index received' (duration: 665.877441ms)","trace[1716566098] 'applied index is now lower than readState.Index' (duration: 143.265µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:30:46.449085Z","caller":"traceutil/trace.go:171","msg":"trace[462281474] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"668.835681ms","start":"2026-03-20T05:30:45.780236Z","end":"2026-03-20T05:30:46.449072Z","steps":["trace[462281474] 'process raft request' (duration: 668.537581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:30:46.449292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:45.780188Z","time spent":"668.925824ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:30:46.449522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.606307ms","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-03-20T05:30:46.449526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.3699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:30:46.449574Z","caller":"traceutil/trace.go:171","msg":"trace[2020643116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"666.680779ms","start":"2026-03-20T05:30:45.782882Z","end":"2026-03-20T05:30:46.449563Z","steps":["trace[2020643116] 'agreement among raft nodes before linearized reading' (duration: 666.522503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:30:46.449598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:45.782874Z","time spent":"666.72041ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:30:46.449547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.591948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T05:30:46.449586Z","caller":"traceutil/trace.go:171","msg":"trace[849029236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"531.492204ms","start":"2026-03-20T05:30:45.91808Z","end":"2026-03-20T05:30:46.449572Z","steps":["trace[849029236] 'agreement among raft nodes before linearized reading' (duration: 531.389171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:30:46.449749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.62235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-20T05:30:46.449742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:45.91805Z","time spent":"531.679101ms","remote":"127.0.0.1:54526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:30:46.44984Z","caller":"traceutil/trace.go:171","msg":"trace[575392291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"647.750154ms","start":"2026-03-20T05:30:45.802071Z","end":"2026-03-20T05:30:46.449821Z","steps":["trace[575392291] 'agreement among raft nodes before linearized reading' (duration: 647.461784ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:30:46.449702Z","caller":"traceutil/trace.go:171","msg":"trace[467703490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"661.751654ms","start":"2026-03-20T05:30:45.787931Z","end":"2026-03-20T05:30:46.449682Z","steps":["trace[467703490] 'agreement among raft nodes before linearized reading' (duration: 661.482315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:30:46.449938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:45.7879Z","time spent":"662.025113ms","remote":"127.0.0.1:54778","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-03-20T05:30:46.449885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:30:45.80206Z","time spent":"647.816687ms","remote":"127.0.0.1:54778","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\" "}