{"level":"warn","ts":"2026-04-13T20:46:34.274449Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T20:46:34.274557Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.177: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.19.213.177:2380","--initial-cluster=instance=https://199.19.213.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.177: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-04-13T20:46:34.274631Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T20:46:34.274641Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-04-13T20:46:34.274667Z","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-04-13T20:46:34.275298Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"]} {"level":"info","ts":"2026-04-13T20:46:34.275381Z","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.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.177: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-04-13T20:46:34.302386Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"26.712542ms"} {"level":"info","ts":"2026-04-13T20:46:34.340901Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f577bc5ae95d61e1","cluster-id":"735860b212d0f0b6"} {"level":"info","ts":"2026-04-13T20:46:34.34118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T20:46:34.341248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 0"} {"level":"info","ts":"2026-04-13T20:46:34.341297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f577bc5ae95d61e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T20:46:34.341338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 1"} {"level":"info","ts":"2026-04-13T20:46:34.341479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"warn","ts":"2026-04-13T20:46:34.449872Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T20:46:34.453342Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T20:46:34.505839Z","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-04-13T20:46:34.544791Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f577bc5ae95d61e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T20:46:34.545655Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f577bc5ae95d61e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T20:46:34.545892Z","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-04-13T20:46:34.54635Z","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-04-13T20:46:34.546377Z","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-04-13T20:46:34.553026Z","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-04-13T20:46:34.553481Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f577bc5ae95d61e1","initial-advertise-peer-urls":["https://199.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T20:46:34.553207Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-04-13T20:46:34.554203Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-04-13T20:46:34.553614Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T20:46:34.575652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"info","ts":"2026-04-13T20:46:34.575932Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","added-peer-id":"f577bc5ae95d61e1","added-peer-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-04-13T20:46:35.350072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T20:46:35.350166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T20:46:35.350309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgPreVoteResp from f577bc5ae95d61e1 at term 1"} {"level":"info","ts":"2026-04-13T20:46:35.350348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became candidate at term 2"} {"level":"info","ts":"2026-04-13T20:46:35.350361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgVoteResp from f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-04-13T20:46:35.350387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became leader at term 2"} {"level":"info","ts":"2026-04-13T20:46:35.350403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f577bc5ae95d61e1 elected leader f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-04-13T20:46:35.361915Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f577bc5ae95d61e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.177:2379]}","request-path":"/0/members/f577bc5ae95d61e1/attributes","cluster-id":"735860b212d0f0b6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T20:46:35.361955Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T20:46:35.362104Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:46:35.362453Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T20:46:35.362499Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T20:46:35.362681Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T20:46:35.366176Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T20:46:35.36642Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.177:2379"} {"level":"info","ts":"2026-04-13T20:46:35.390991Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:46:35.39118Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:46:35.391224Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:46:37.825265Z","caller":"traceutil/trace.go:171","msg":"trace[1289388023] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"108.657748ms","start":"2026-04-13T20:46:37.716577Z","end":"2026-04-13T20:46:37.825235Z","steps":["trace[1289388023] 'read index received' (duration: 92.294608ms)","trace[1289388023] 'applied index is now lower than readState.Index' (duration: 16.36097ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:46:37.825405Z","caller":"traceutil/trace.go:171","msg":"trace[118426814] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"127.774381ms","start":"2026-04-13T20:46:37.697572Z","end":"2026-04-13T20:46:37.825346Z","steps":["trace[118426814] 'process raft request' (duration: 111.250907ms)","trace[118426814] 'compare' (duration: 16.230976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:46:37.825957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.313908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-13T20:46:37.82607Z","caller":"traceutil/trace.go:171","msg":"trace[1396273002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"109.511534ms","start":"2026-04-13T20:46:37.716535Z","end":"2026-04-13T20:46:37.826046Z","steps":["trace[1396273002] 'agreement among raft nodes before linearized reading' (duration: 108.833343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:00.128978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.389645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T20:47:00.129016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.446943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:47:00.129073Z","caller":"traceutil/trace.go:171","msg":"trace[780587592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"321.536909ms","start":"2026-04-13T20:46:59.807514Z","end":"2026-04-13T20:47:00.129051Z","steps":["trace[780587592] 'range keys from in-memory index tree' (duration: 321.276162ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:00.129106Z","caller":"traceutil/trace.go:171","msg":"trace[1297772219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"186.583176ms","start":"2026-04-13T20:46:59.9425Z","end":"2026-04-13T20:47:00.129084Z","steps":["trace[1297772219] 'range keys from in-memory index tree' (duration: 186.345791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:00.129121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:46:59.807491Z","time spent":"321.619951ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T20:47:05.090536Z","caller":"traceutil/trace.go:171","msg":"trace[481767413] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"152.447593ms","start":"2026-04-13T20:47:04.938071Z","end":"2026-04-13T20:47:05.090519Z","steps":["trace[481767413] 'process raft request' (duration: 152.34099ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:05.1665Z","caller":"traceutil/trace.go:171","msg":"trace[1612428526] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"113.015796ms","start":"2026-04-13T20:47:05.053462Z","end":"2026-04-13T20:47:05.166478Z","steps":["trace[1612428526] 'process raft request' (duration: 112.874023ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:05.166779Z","caller":"traceutil/trace.go:171","msg":"trace[2026886237] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"107.389465ms","start":"2026-04-13T20:47:05.059366Z","end":"2026-04-13T20:47:05.166756Z","steps":["trace[2026886237] 'process raft request' (duration: 107.116279ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:05.166871Z","caller":"traceutil/trace.go:171","msg":"trace[1292877740] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"107.461618ms","start":"2026-04-13T20:47:05.059388Z","end":"2026-04-13T20:47:05.16685Z","steps":["trace[1292877740] 'process raft request' (duration: 107.184651ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:05.166929Z","caller":"traceutil/trace.go:171","msg":"trace[1087131447] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"107.615141ms","start":"2026-04-13T20:47:05.059307Z","end":"2026-04-13T20:47:05.166922Z","steps":["trace[1087131447] 'process raft request' (duration: 107.12753ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:05.1669Z","caller":"traceutil/trace.go:171","msg":"trace[727576487] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"107.640972ms","start":"2026-04-13T20:47:05.059249Z","end":"2026-04-13T20:47:05.16689Z","steps":["trace[727576487] 'process raft request' (duration: 107.15074ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:05.166445Z","caller":"traceutil/trace.go:171","msg":"trace[125109638] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"117.583252ms","start":"2026-04-13T20:47:05.048835Z","end":"2026-04-13T20:47:05.166419Z","steps":["trace[125109638] 'process raft request' (duration: 117.350556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:06.204266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091701439951233,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T20:47:06.323285Z","caller":"traceutil/trace.go:171","msg":"trace[1022707531] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"679.522243ms","start":"2026-04-13T20:47:05.643735Z","end":"2026-04-13T20:47:06.323258Z","steps":["trace[1022707531] 'process raft request' (duration: 664.739514ms)","trace[1022707531] 'compare' (duration: 14.615615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:47:06.323996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:05.643709Z","time spent":"679.647786ms","remote":"127.0.0.1:46284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:47:06.325301Z","caller":"traceutil/trace.go:171","msg":"trace[322993026] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:590; }","duration":"622.025531ms","start":"2026-04-13T20:47:05.703261Z","end":"2026-04-13T20:47:06.325286Z","steps":["trace[322993026] 'read index received' (duration: 605.276857ms)","trace[322993026] 'applied index is now lower than readState.Index' (duration: 16.745164ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:47:06.325125Z","caller":"traceutil/trace.go:171","msg":"trace[205624422] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"610.535646ms","start":"2026-04-13T20:47:05.714547Z","end":"2026-04-13T20:47:06.325083Z","steps":["trace[205624422] 'process raft request' (duration: 609.777708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:06.325502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:05.714533Z","time spent":"610.883613ms","remote":"127.0.0.1:46284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:47:06.325645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.368389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:47:06.325721Z","caller":"traceutil/trace.go:171","msg":"trace[889925217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"622.467862ms","start":"2026-04-13T20:47:05.70323Z","end":"2026-04-13T20:47:06.325698Z","steps":["trace[889925217] 'agreement among raft nodes before linearized reading' (duration: 622.271877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:06.325732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.614069ms","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-04-13T20:47:06.325771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:05.703213Z","time spent":"622.546223ms","remote":"127.0.0.1:46234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T20:47:06.32578Z","caller":"traceutil/trace.go:171","msg":"trace[1913555317] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"428.846719ms","start":"2026-04-13T20:47:05.896924Z","end":"2026-04-13T20:47:06.32577Z","steps":["trace[1913555317] 'process raft request' (duration: 427.824526ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:06.325777Z","caller":"traceutil/trace.go:171","msg":"trace[1454974104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"114.686481ms","start":"2026-04-13T20:47:06.211073Z","end":"2026-04-13T20:47:06.32576Z","steps":["trace[1454974104] 'agreement among raft nodes before linearized reading' (duration: 114.599489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:06.325836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:05.896893Z","time spent":"428.913201ms","remote":"127.0.0.1:46284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:47:11.711096Z","caller":"traceutil/trace.go:171","msg":"trace[1045577829] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"219.950247ms","start":"2026-04-13T20:47:11.491104Z","end":"2026-04-13T20:47:11.711054Z","steps":["trace[1045577829] 'process raft request' (duration: 219.335203ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:12.130245Z","caller":"traceutil/trace.go:171","msg":"trace[993486751] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"367.874919ms","start":"2026-04-13T20:47:11.762349Z","end":"2026-04-13T20:47:12.130224Z","steps":["trace[993486751] 'process raft request' (duration: 362.004222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:12.130353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:11.762305Z","time spent":"367.996132ms","remote":"127.0.0.1:46674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:47:12.14064Z","caller":"traceutil/trace.go:171","msg":"trace[527010831] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"120.548013ms","start":"2026-04-13T20:47:12.020046Z","end":"2026-04-13T20:47:12.140594Z","steps":["trace[527010831] 'process raft request' (duration: 120.383419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:13.205606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053091701439951389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:47:13.399148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043142112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T20:47:13.39919Z","caller":"traceutil/trace.go:171","msg":"trace[7880838] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"876.882074ms","start":"2026-04-13T20:47:12.522294Z","end":"2026-04-13T20:47:13.399176Z","steps":["trace[7880838] 'process raft request' (duration: 858.190401ms)","trace[7880838] 'compare' (duration: 18.287195ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:47:13.399228Z","caller":"traceutil/trace.go:171","msg":"trace[1842011511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.043258825s","start":"2026-04-13T20:47:12.35595Z","end":"2026-04-13T20:47:13.399209Z","steps":["trace[1842011511] 'range keys from in-memory index tree' (duration: 1.042936258s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:13.399262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:12.355896Z","time spent":"1.043359057s","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T20:47:13.399266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.485636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:47:13.399292Z","caller":"traceutil/trace.go:171","msg":"trace[337968952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"694.550698ms","start":"2026-04-13T20:47:12.704734Z","end":"2026-04-13T20:47:13.399285Z","steps":["trace[337968952] 'agreement among raft nodes before linearized reading' (duration: 694.496107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:13.399305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:12.522268Z","time spent":"876.974617ms","remote":"127.0.0.1:46332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:47:13.399319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:12.704716Z","time spent":"694.598928ms","remote":"127.0.0.1:46234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T20:47:13.399189Z","caller":"traceutil/trace.go:171","msg":"trace[1758797821] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"694.396984ms","start":"2026-04-13T20:47:12.70477Z","end":"2026-04-13T20:47:13.399167Z","steps":["trace[1758797821] 'read index received' (duration: 675.435425ms)","trace[1758797821] 'applied index is now lower than readState.Index' (duration: 18.960649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:47:13.39918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152065762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tk6sv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-13T20:47:13.400314Z","caller":"traceutil/trace.go:171","msg":"trace[174903956] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tk6sv; range_end:; response_count:1; response_revision:623; }","duration":"1.153224827s","start":"2026-04-13T20:47:12.247048Z","end":"2026-04-13T20:47:13.400273Z","steps":["trace[174903956] 'range keys from in-memory index tree' (duration: 1.151863438s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:13.400443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:12.247029Z","time spent":"1.153376031s","remote":"127.0.0.1:46398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-tk6sv\" "} {"level":"warn","ts":"2026-04-13T20:47:13.399651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.925431ms","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-04-13T20:47:13.400708Z","caller":"traceutil/trace.go:171","msg":"trace[268719853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"124.008825ms","start":"2026-04-13T20:47:13.276686Z","end":"2026-04-13T20:47:13.400695Z","steps":["trace[268719853] 'agreement among raft nodes before linearized reading' (duration: 122.923922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:13.399668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.115125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T20:47:13.400806Z","caller":"traceutil/trace.go:171","msg":"trace[920699175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"130.27279ms","start":"2026-04-13T20:47:13.2705Z","end":"2026-04-13T20:47:13.400772Z","steps":["trace[920699175] 'agreement among raft nodes before linearized reading' (duration: 129.112825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:13.399725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.382102ms","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-04-13T20:47:13.40087Z","caller":"traceutil/trace.go:171","msg":"trace[2142918952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"277.580878ms","start":"2026-04-13T20:47:13.123279Z","end":"2026-04-13T20:47:13.40086Z","steps":["trace[2142918952] 'agreement among raft nodes before linearized reading' (duration: 276.3172ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:29.727399Z","caller":"traceutil/trace.go:171","msg":"trace[76579998] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"308.074578ms","start":"2026-04-13T20:47:29.419292Z","end":"2026-04-13T20:47:29.727367Z","steps":["trace[76579998] 'read index received' (duration: 307.849444ms)","trace[76579998] 'applied index is now lower than readState.Index' (duration: 224.214µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:47:29.727441Z","caller":"traceutil/trace.go:171","msg":"trace[1888473306] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"367.76092ms","start":"2026-04-13T20:47:29.359658Z","end":"2026-04-13T20:47:29.727418Z","steps":["trace[1888473306] 'process raft request' (duration: 367.556847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:29.727607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.528839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T20:47:29.7276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:29.359631Z","time spent":"367.863052ms","remote":"127.0.0.1:46444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:47:29.727646Z","caller":"traceutil/trace.go:171","msg":"trace[2134086126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"240.59858ms","start":"2026-04-13T20:47:29.487038Z","end":"2026-04-13T20:47:29.727636Z","steps":["trace[2134086126] 'agreement among raft nodes before linearized reading' (duration: 240.472167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:29.727802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.046373ms","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-04-13T20:47:29.727834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.813207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T20:47:29.727861Z","caller":"traceutil/trace.go:171","msg":"trace[1850291206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"236.156945ms","start":"2026-04-13T20:47:29.491687Z","end":"2026-04-13T20:47:29.727844Z","steps":["trace[1850291206] 'agreement among raft nodes before linearized reading' (duration: 235.997322ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:47:29.727895Z","caller":"traceutil/trace.go:171","msg":"trace[1395661448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"237.925449ms","start":"2026-04-13T20:47:29.489953Z","end":"2026-04-13T20:47:29.727879Z","steps":["trace[1395661448] 'agreement among raft nodes before linearized reading' (duration: 237.756076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:29.727892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.714893ms","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-04-13T20:47:29.727939Z","caller":"traceutil/trace.go:171","msg":"trace[1002766457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"239.781294ms","start":"2026-04-13T20:47:29.488145Z","end":"2026-04-13T20:47:29.727926Z","steps":["trace[1002766457] 'agreement among raft nodes before linearized reading' (duration: 239.635861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:29.727919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.602558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T20:47:29.728Z","caller":"traceutil/trace.go:171","msg":"trace[749489258] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:784; }","duration":"308.727371ms","start":"2026-04-13T20:47:29.419253Z","end":"2026-04-13T20:47:29.72798Z","steps":["trace[749489258] 'agreement among raft nodes before linearized reading' (duration: 308.233552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:47:29.728092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:47:29.419233Z","time spent":"308.797672ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.177\" "}