{"level":"warn","ts":"2026-03-07T02:45:39.443193Z","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-07T02:45:39.443281Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-07T02:45:39.443346Z","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-07T02:45:39.443358Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-03-07T02:45:39.443375Z","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-07T02:45:39.443976Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-03-07T02:45:39.444052Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-07T02:45:39.449838Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.575471ms"} {"level":"info","ts":"2026-03-07T02:45:39.463469Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-03-07T02:45:39.463579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-03-07T02:45:39.463622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-03-07T02:45:39.463642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-07T02:45:39.463661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-03-07T02:45:39.463716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-03-07T02:45:39.474204Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-07T02:45:39.475965Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-07T02:45:39.477413Z","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-07T02:45:39.48265Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-07T02:45:39.482835Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-07T02:45:39.485714Z","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-07T02:45:39.485866Z","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-07T02:45:39.485933Z","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-07T02:45:39.486722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-03-07T02:45:39.486998Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-03-07T02:45:39.496519Z","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-07T02:45:39.496828Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-07T02:45:39.496875Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-07T02:45:39.497996Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-03-07T02:45:39.498085Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-03-07T02:45:40.264542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-03-07T02:45:40.264629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-07T02:45:40.264666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-03-07T02:45:40.264687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-03-07T02:45:40.264702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-03-07T02:45:40.264718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-03-07T02:45:40.264735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-03-07T02:45:40.265652Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T02:45:40.266681Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-07T02:45:40.266801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-07T02:45:40.266841Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-07T02:45:40.267072Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-07T02:45:40.26709Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-07T02:45:40.267268Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T02:45:40.26736Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T02:45:40.267439Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-07T02:45:40.268716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-03-07T02:45:40.271076Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-07T02:45:58.109473Z","caller":"traceutil/trace.go:171","msg":"trace[618577801] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"571.000681ms","start":"2026-03-07T02:45:57.538434Z","end":"2026-03-07T02:45:58.109435Z","steps":["trace[618577801] 'process raft request' (duration: 570.58626ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T02:45:58.109538Z","caller":"traceutil/trace.go:171","msg":"trace[1965791132] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"335.363435ms","start":"2026-03-07T02:45:57.774027Z","end":"2026-03-07T02:45:58.109391Z","steps":["trace[1965791132] 'read index received' (duration: 334.867704ms)","trace[1965791132] 'applied index is now lower than readState.Index' (duration: 493.291µs)"],"step_count":2} {"level":"warn","ts":"2026-03-07T02:45:58.10974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.684556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-07T02:45:58.109866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.02308ms","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-07T02:45:58.109882Z","caller":"traceutil/trace.go:171","msg":"trace[1161366672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"335.877357ms","start":"2026-03-07T02:45:57.77398Z","end":"2026-03-07T02:45:58.109857Z","steps":["trace[1161366672] 'agreement among raft nodes before linearized reading' (duration: 335.630996ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T02:45:58.109921Z","caller":"traceutil/trace.go:171","msg":"trace[1221282536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"334.128531ms","start":"2026-03-07T02:45:57.775773Z","end":"2026-03-07T02:45:58.109901Z","steps":["trace[1221282536] 'agreement among raft nodes before linearized reading' (duration: 333.94595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:45:58.10994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:45:57.538419Z","time spent":"571.153732ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T02:45:58.109934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:45:57.77396Z","time spent":"335.963318ms","remote":"127.0.0.1:37948","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-03-07T02:45:58.109979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:45:57.775762Z","time spent":"334.205471ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-07T02:45:58.110137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.287564ms","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-07T02:45:58.110217Z","caller":"traceutil/trace.go:171","msg":"trace[924921808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"255.398765ms","start":"2026-03-07T02:45:57.854797Z","end":"2026-03-07T02:45:58.110196Z","steps":["trace[924921808] 'agreement among raft nodes before linearized reading' (duration: 255.292004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:45:58.636836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.403376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-07T02:45:58.636943Z","caller":"traceutil/trace.go:171","msg":"trace[854896937] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"521.588335ms","start":"2026-03-07T02:45:58.115336Z","end":"2026-03-07T02:45:58.636924Z","steps":["trace[854896937] 'process raft request' (duration: 250.897908ms)","trace[854896937] 'compare' (duration: 270.295735ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T02:45:58.637001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:45:58.115326Z","time spent":"521.649595ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T02:45:59.540304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.230727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-07T02:45:59.54055Z","caller":"traceutil/trace.go:171","msg":"trace[1139627505] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.423726098s","start":"2026-03-07T02:45:58.116791Z","end":"2026-03-07T02:45:59.540517Z","steps":["trace[1139627505] 'process raft request' (duration: 900.18879ms)","trace[1139627505] 'compare' (duration: 523.105987ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T02:45:59.5407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:45:58.116767Z","time spent":"1.423851819s","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T02:45:59.540831Z","caller":"traceutil/trace.go:171","msg":"trace[1066204674] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.421324131s","start":"2026-03-07T02:45:58.11949Z","end":"2026-03-07T02:45:59.540814Z","steps":["trace[1066204674] 'process raft request' (duration: 1.42104409s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:45:59.540869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:45:58.119473Z","time spent":"1.421376241s","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T02:45:59.540941Z","caller":"traceutil/trace.go:171","msg":"trace[1044108011] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.424072359s","start":"2026-03-07T02:45:58.116863Z","end":"2026-03-07T02:45:59.540935Z","steps":["trace[1044108011] 'process raft request' (duration: 1.423596458s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:45:59.540981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:45:58.116856Z","time spent":"1.424106649s","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T02:45:59.541236Z","caller":"traceutil/trace.go:171","msg":"trace[1697536323] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.420756548s","start":"2026-03-07T02:45:58.120474Z","end":"2026-03-07T02:45:59.541231Z","steps":["trace[1697536323] 'process raft request' (duration: 1.420130756s)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:45:59.541268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:45:58.120468Z","time spent":"1.420785438s","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T02:46:00.216871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.187429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-07T02:46:00.216943Z","caller":"traceutil/trace.go:171","msg":"trace[1876510475] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"163.362409ms","start":"2026-03-07T02:46:00.053564Z","end":"2026-03-07T02:46:00.216926Z","steps":["trace[1876510475] 'read index received' (duration: 69.05µs)","trace[1876510475] 'applied index is now lower than readState.Index' (duration: 163.292379ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T02:46:00.216991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.421159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T02:46:00.217003Z","caller":"traceutil/trace.go:171","msg":"trace[173355391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"163.473989ms","start":"2026-03-07T02:46:00.053525Z","end":"2026-03-07T02:46:00.216999Z","steps":["trace[173355391] 'agreement among raft nodes before linearized reading' (duration: 163.435059ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T02:46:09.547694Z","caller":"traceutil/trace.go:171","msg":"trace[332659292] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"149.988174ms","start":"2026-03-07T02:46:09.397683Z","end":"2026-03-07T02:46:09.547671Z","steps":["trace[332659292] 'process raft request' (duration: 149.853463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:10.674709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.371854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T02:46:10.674794Z","caller":"traceutil/trace.go:171","msg":"trace[729130921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"621.508804ms","start":"2026-03-07T02:46:10.053268Z","end":"2026-03-07T02:46:10.674777Z","steps":["trace[729130921] 'range keys from in-memory index tree' (duration: 621.306344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:10.674827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:10.053248Z","time spent":"621.572094ms","remote":"127.0.0.1:37700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-07T02:46:18.144743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051160605658224,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-07T02:46:18.218412Z","caller":"traceutil/trace.go:171","msg":"trace[305834643] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"898.98652ms","start":"2026-03-07T02:46:17.319399Z","end":"2026-03-07T02:46:18.218385Z","steps":["trace[305834643] 'process raft request' (duration: 898.83501ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T02:46:18.218427Z","caller":"traceutil/trace.go:171","msg":"trace[462202689] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:532; }","duration":"574.226521ms","start":"2026-03-07T02:46:17.644162Z","end":"2026-03-07T02:46:18.218388Z","steps":["trace[462202689] 'read index received' (duration: 574.217781ms)","trace[462202689] 'applied index is now lower than readState.Index' (duration: 7.25µs)"],"step_count":2} {"level":"warn","ts":"2026-03-07T02:46:18.218583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:17.31938Z","time spent":"899.111661ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T02:46:18.218722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.812623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-07T02:46:18.218727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.600048ms","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-07T02:46:18.218762Z","caller":"traceutil/trace.go:171","msg":"trace[75860203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"166.882653ms","start":"2026-03-07T02:46:18.051866Z","end":"2026-03-07T02:46:18.218749Z","steps":["trace[75860203] 'agreement among raft nodes before linearized reading' (duration: 166.825123ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T02:46:18.218773Z","caller":"traceutil/trace.go:171","msg":"trace[1003990864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"552.687658ms","start":"2026-03-07T02:46:17.666074Z","end":"2026-03-07T02:46:18.218761Z","steps":["trace[1003990864] 'agreement among raft nodes before linearized reading' (duration: 552.598598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:18.218801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:17.666052Z","time spent":"552.741208ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-07T02:46:18.218857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.524752ms","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-07T02:46:18.219124Z","caller":"traceutil/trace.go:171","msg":"trace[672649085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"574.857025ms","start":"2026-03-07T02:46:17.644126Z","end":"2026-03-07T02:46:18.218983Z","steps":["trace[672649085] 'agreement among raft nodes before linearized reading' (duration: 574.346822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:18.219429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:17.644109Z","time spent":"575.303796ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-07T02:46:18.219808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.069523ms","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-07T02:46:18.219871Z","caller":"traceutil/trace.go:171","msg":"trace[890611824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"548.168844ms","start":"2026-03-07T02:46:17.671684Z","end":"2026-03-07T02:46:18.219853Z","steps":["trace[890611824] 'agreement among raft nodes before linearized reading' (duration: 547.037459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:18.219919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:17.671667Z","time spent":"548.232654ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-07T02:46:18.992054Z","caller":"traceutil/trace.go:171","msg":"trace[524984283] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"769.011988ms","start":"2026-03-07T02:46:18.223016Z","end":"2026-03-07T02:46:18.992028Z","steps":["trace[524984283] 'process raft request' (duration: 768.876118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:18.992241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:18.222994Z","time spent":"769.16976ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T02:46:19.002484Z","caller":"traceutil/trace.go:171","msg":"trace[983211626] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"779.170933ms","start":"2026-03-07T02:46:18.223292Z","end":"2026-03-07T02:46:19.002463Z","steps":["trace[983211626] 'process raft request' (duration: 779.035652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:19.002628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:18.223273Z","time spent":"779.267553ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T02:46:19.002876Z","caller":"traceutil/trace.go:171","msg":"trace[2118906024] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"779.493744ms","start":"2026-03-07T02:46:18.223348Z","end":"2026-03-07T02:46:19.002842Z","steps":["trace[2118906024] 'process raft request' (duration: 779.085493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:19.003057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:18.223328Z","time spent":"779.657534ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T02:46:19.042929Z","caller":"traceutil/trace.go:171","msg":"trace[1875939633] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:532; }","duration":"248.46343ms","start":"2026-03-07T02:46:18.794436Z","end":"2026-03-07T02:46:19.0429Z","steps":["trace[1875939633] 'read index received' (duration: 197.465287ms)","trace[1875939633] 'applied index is now lower than readState.Index' (duration: 50.996943ms)"],"step_count":2} {"level":"warn","ts":"2026-03-07T02:46:19.043168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.68706ms","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-03-07T02:46:19.043264Z","caller":"traceutil/trace.go:171","msg":"trace[1707759149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"248.831611ms","start":"2026-03-07T02:46:18.794409Z","end":"2026-03-07T02:46:19.04324Z","steps":["trace[1707759149] 'agreement among raft nodes before linearized reading' (duration: 248.57627ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T02:46:22.917705Z","caller":"traceutil/trace.go:171","msg":"trace[1820565036] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"411.992156ms","start":"2026-03-07T02:46:22.505681Z","end":"2026-03-07T02:46:22.917674Z","steps":["trace[1820565036] 'process raft request' (duration: 411.844825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:22.917868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:22.505656Z","time spent":"412.135936ms","remote":"127.0.0.1:37748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-07T02:46:22.924045Z","caller":"traceutil/trace.go:171","msg":"trace[1258327129] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"363.402053ms","start":"2026-03-07T02:46:22.560619Z","end":"2026-03-07T02:46:22.924021Z","steps":["trace[1258327129] 'process raft request' (duration: 362.978592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:46:22.924183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:46:22.560597Z","time spent":"363.512673ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-07T02:46:22.924058Z","caller":"traceutil/trace.go:171","msg":"trace[1909696482] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"217.786123ms","start":"2026-03-07T02:46:22.706254Z","end":"2026-03-07T02:46:22.924041Z","steps":["trace[1909696482] 'process raft request' (duration: 217.479422ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T02:47:43.733102Z","caller":"traceutil/trace.go:171","msg":"trace[100407559] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:974; }","duration":"100.815778ms","start":"2026-03-07T02:47:43.632237Z","end":"2026-03-07T02:47:43.733052Z","steps":["trace[100407559] 'read index received' (duration: 50.389524ms)","trace[100407559] 'applied index is now lower than readState.Index' (duration: 50.425264ms)"],"step_count":2} {"level":"info","ts":"2026-03-07T02:47:43.733174Z","caller":"traceutil/trace.go:171","msg":"trace[1940757413] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"117.333022ms","start":"2026-03-07T02:47:43.615781Z","end":"2026-03-07T02:47:43.733114Z","steps":["trace[1940757413] 'process raft request' (duration: 115.327365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:47:43.733279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.993918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-07T02:47:43.733605Z","caller":"traceutil/trace.go:171","msg":"trace[974750186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"101.106938ms","start":"2026-03-07T02:47:43.63221Z","end":"2026-03-07T02:47:43.733317Z","steps":["trace[974750186] 'agreement among raft nodes before linearized reading' (duration: 100.988818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:47:52.26289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656051160605659305,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-07T02:47:52.472865Z","caller":"traceutil/trace.go:171","msg":"trace[1235361609] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"710.77636ms","start":"2026-03-07T02:47:51.762062Z","end":"2026-03-07T02:47:52.472839Z","steps":["trace[1235361609] 'read index received' (duration: 710.530569ms)","trace[1235361609] 'applied index is now lower than readState.Index' (duration: 244.821µs)"],"step_count":2} {"level":"info","ts":"2026-03-07T02:47:52.472999Z","caller":"traceutil/trace.go:171","msg":"trace[21734013] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"757.438393ms","start":"2026-03-07T02:47:51.715547Z","end":"2026-03-07T02:47:52.472986Z","steps":["trace[21734013] 'process raft request' (duration: 757.108532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:47:52.473104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:47:51.715526Z","time spent":"757.502303ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-07T02:47:52.473285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.498436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-07T02:47:52.473431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.345742ms","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-03-07T02:47:52.47347Z","caller":"traceutil/trace.go:171","msg":"trace[1540596958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"420.624107ms","start":"2026-03-07T02:47:52.052718Z","end":"2026-03-07T02:47:52.473342Z","steps":["trace[1540596958] 'agreement among raft nodes before linearized reading' (duration: 420.491816ms)"],"step_count":1} {"level":"info","ts":"2026-03-07T02:47:52.473505Z","caller":"traceutil/trace.go:171","msg":"trace[1965362478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"711.490953ms","start":"2026-03-07T02:47:51.761995Z","end":"2026-03-07T02:47:52.473486Z","steps":["trace[1965362478] 'agreement among raft nodes before linearized reading' (duration: 711.219632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-07T02:47:52.473551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:47:51.761972Z","time spent":"711.570743ms","remote":"127.0.0.1:37948","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-03-07T02:47:52.473573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-07T02:47:52.052698Z","time spent":"420.812727ms","remote":"127.0.0.1:37700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}