{"level":"warn","ts":"2026-04-03T02:36:49.186203Z","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-03T02:36:49.186292Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-03T02:36:49.186357Z","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-03T02:36:49.186371Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-03T02:36:49.186398Z","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-03T02:36:49.187036Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-03T02:36:49.187114Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-03T02:36:49.191867Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.531859ms"} {"level":"info","ts":"2026-04-03T02:36:49.19921Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-03T02:36:49.199273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T02:36:49.199305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-03T02:36:49.19932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T02:36:49.19933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-03T02:36:49.199373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-03T02:36:49.206695Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T02:36:49.209763Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T02:36:49.211151Z","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-03T02:36:49.212952Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T02:36:49.213176Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T02:36:49.213591Z","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-03T02:36:49.213771Z","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-03T02:36:49.215739Z","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-03T02:36:49.21648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-03T02:36:49.216749Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-03T02:36:49.218154Z","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-03T02:36:49.218335Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-03T02:36:49.218382Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-03T02:36:49.21854Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T02:36:49.21864Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T02:36:49.599742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T02:36:49.599792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T02:36:49.599809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-03T02:36:49.599822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-03T02:36:49.599847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-03T02:36:49.599856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-03T02:36:49.599865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-03T02:36:49.756081Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T02:36:49.7561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:36:49.75616Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:36:49.756294Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:36:49.756762Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T02:36:49.756845Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T02:36:49.760816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T02:36:49.764491Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-03T02:36:49.960941Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:36:49.961358Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:36:49.961439Z","caller":"traceutil/trace.go:171","msg":"trace[799365553] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"163.946016ms","start":"2026-04-03T02:36:49.79747Z","end":"2026-04-03T02:36:49.961416Z","steps":["trace[799365553] 'read index received' (duration: 163.413854ms)","trace[799365553] 'applied index is now lower than readState.Index' (duration: 530.542µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:36:49.961728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.203644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-03T02:36:49.961756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.232604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T02:36:49.961862Z","caller":"traceutil/trace.go:171","msg":"trace[1677729614] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"164.384238ms","start":"2026-04-03T02:36:49.797458Z","end":"2026-04-03T02:36:49.961842Z","steps":["trace[1677729614] 'agreement among raft nodes before linearized reading' (duration: 164.088641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:36:49.963796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.116587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T02:36:49.961457Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-03T02:36:49.964591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.022454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T02:36:49.964658Z","caller":"traceutil/trace.go:171","msg":"trace[761190455] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"123.115506ms","start":"2026-04-03T02:36:49.841512Z","end":"2026-04-03T02:36:49.964627Z","steps":["trace[761190455] 'agreement among raft nodes before linearized reading' (duration: 123.036254ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:36:49.961792Z","caller":"traceutil/trace.go:171","msg":"trace[973766671] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"164.327017ms","start":"2026-04-03T02:36:49.797452Z","end":"2026-04-03T02:36:49.961779Z","steps":["trace[973766671] 'agreement among raft nodes before linearized reading' (duration: 164.075381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:36:49.966397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.31998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T02:36:49.966507Z","caller":"traceutil/trace.go:171","msg":"trace[1641951305] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"108.878145ms","start":"2026-04-03T02:36:49.857576Z","end":"2026-04-03T02:36:49.966454Z","steps":["trace[1641951305] 'agreement among raft nodes before linearized reading' (duration: 106.395015ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:36:49.963867Z","caller":"traceutil/trace.go:171","msg":"trace[164291882] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"106.217049ms","start":"2026-04-03T02:36:49.857621Z","end":"2026-04-03T02:36:49.963838Z","steps":["trace[164291882] 'agreement among raft nodes before linearized reading' (duration: 105.792969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:36:50.124647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.817595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T02:36:50.124729Z","caller":"traceutil/trace.go:171","msg":"trace[578779771] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"116.912727ms","start":"2026-04-03T02:36:50.007799Z","end":"2026-04-03T02:36:50.124712Z","steps":["trace[578779771] 'count revisions from in-memory index tree' (duration: 116.772144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:36:50.125073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.098171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T02:36:50.125118Z","caller":"traceutil/trace.go:171","msg":"trace[500383507] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"117.166553ms","start":"2026-04-03T02:36:50.007941Z","end":"2026-04-03T02:36:50.125108Z","steps":["trace[500383507] 'range keys from in-memory index tree' (duration: 117.031029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:15.678736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.918263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:37:15.679337Z","caller":"traceutil/trace.go:171","msg":"trace[188818088] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"160.20575ms","start":"2026-04-03T02:37:15.519109Z","end":"2026-04-03T02:37:15.679314Z","steps":["trace[188818088] 'compare' (duration: 152.76101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:48.237683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.510934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:37:48.237885Z","caller":"traceutil/trace.go:171","msg":"trace[1626645527] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"169.378522ms","start":"2026-04-03T02:37:48.068492Z","end":"2026-04-03T02:37:48.237871Z","steps":["trace[1626645527] 'process raft request' (duration: 169.305039ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:37:48.23797Z","caller":"traceutil/trace.go:171","msg":"trace[435209280] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"169.486815ms","start":"2026-04-03T02:37:48.068439Z","end":"2026-04-03T02:37:48.237926Z","steps":["trace[435209280] 'process raft request' (duration: 59.685193ms)","trace[435209280] 'compare' (duration: 109.233818ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:37:52.128146Z","caller":"traceutil/trace.go:171","msg":"trace[147365990] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"369.006309ms","start":"2026-04-03T02:37:51.759122Z","end":"2026-04-03T02:37:52.128128Z","steps":["trace[147365990] 'process raft request' (duration: 333.359056ms)","trace[147365990] 'compare' (duration: 35.447158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:37:52.128304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:37:51.759095Z","time spent":"369.137672ms","remote":"127.0.0.1:35082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T02:37:52.12853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.778104ms","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-04-03T02:37:52.128626Z","caller":"traceutil/trace.go:171","msg":"trace[907204493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"237.96597ms","start":"2026-04-03T02:37:51.890638Z","end":"2026-04-03T02:37:52.128604Z","steps":["trace[907204493] 'agreement among raft nodes before linearized reading' (duration: 237.60441ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:37:52.128694Z","caller":"traceutil/trace.go:171","msg":"trace[1767566293] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"237.367014ms","start":"2026-04-03T02:37:51.890699Z","end":"2026-04-03T02:37:52.128066Z","steps":["trace[1767566293] 'read index received' (duration: 201.771622ms)","trace[1767566293] 'applied index is now lower than readState.Index' (duration: 35.594052ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:37:52.851092Z","caller":"traceutil/trace.go:171","msg":"trace[2096814579] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"294.650146ms","start":"2026-04-03T02:37:52.556403Z","end":"2026-04-03T02:37:52.851053Z","steps":["trace[2096814579] 'process raft request' (duration: 294.299126ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:37:52.972199Z","caller":"traceutil/trace.go:171","msg":"trace[378174539] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"111.809182ms","start":"2026-04-03T02:37:52.860349Z","end":"2026-04-03T02:37:52.972158Z","steps":["trace[378174539] 'read index received' (duration: 110.369586ms)","trace[378174539] 'applied index is now lower than readState.Index' (duration: 1.438436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:37:52.972406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.022999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-03T02:37:52.972452Z","caller":"traceutil/trace.go:171","msg":"trace[1720543703] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:681; }","duration":"112.167242ms","start":"2026-04-03T02:37:52.860271Z","end":"2026-04-03T02:37:52.972438Z","steps":["trace[1720543703] 'agreement among raft nodes before linearized reading' (duration: 112.026827ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:37:52.972665Z","caller":"traceutil/trace.go:171","msg":"trace[1791218839] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"366.72386ms","start":"2026-04-03T02:37:52.605892Z","end":"2026-04-03T02:37:52.972616Z","steps":["trace[1791218839] 'process raft request' (duration: 365.384296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:37:52.972986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:37:52.605853Z","time spent":"367.003988ms","remote":"127.0.0.1:34690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:38:08.667874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.494053ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-03T02:38:08.668039Z","caller":"traceutil/trace.go:171","msg":"trace[6321047] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"373.056481ms","start":"2026-04-03T02:38:08.294955Z","end":"2026-04-03T02:38:08.668011Z","steps":["trace[6321047] 'read index received' (duration: 147.315952ms)","trace[6321047] 'applied index is now lower than readState.Index' (duration: 225.737959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:08.668347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.367609ms","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-04-03T02:38:08.66839Z","caller":"traceutil/trace.go:171","msg":"trace[1688861098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"373.448702ms","start":"2026-04-03T02:38:08.294929Z","end":"2026-04-03T02:38:08.668378Z","steps":["trace[1688861098] 'agreement among raft nodes before linearized reading' (duration: 373.194886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:08.668397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.277786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-03T02:38:08.668417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.551368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:38:08.668599Z","caller":"traceutil/trace.go:171","msg":"trace[1923432901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"222.819065ms","start":"2026-04-03T02:38:08.445759Z","end":"2026-04-03T02:38:08.668578Z","steps":["trace[1923432901] 'agreement among raft nodes before linearized reading' (duration: 222.497278ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:08.668511Z","caller":"traceutil/trace.go:171","msg":"trace[1919845664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:843; }","duration":"251.45796ms","start":"2026-04-03T02:38:08.417031Z","end":"2026-04-03T02:38:08.668489Z","steps":["trace[1919845664] 'agreement among raft nodes before linearized reading' (duration: 251.185124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:08.668442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:08.294916Z","time spent":"373.516683ms","remote":"127.0.0.1:34830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-03T02:38:43.116803Z","caller":"traceutil/trace.go:171","msg":"trace[707029834] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"236.557828ms","start":"2026-04-03T02:38:42.880216Z","end":"2026-04-03T02:38:43.116774Z","steps":["trace[707029834] 'read index received' (duration: 236.489357ms)","trace[707029834] 'applied index is now lower than readState.Index' (duration: 66.741µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:43.117091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.845405ms","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-04-03T02:38:43.117189Z","caller":"traceutil/trace.go:171","msg":"trace[1646692602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"237.024059ms","start":"2026-04-03T02:38:42.880145Z","end":"2026-04-03T02:38:43.117169Z","steps":["trace[1646692602] 'agreement among raft nodes before linearized reading' (duration: 236.752152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:43.393715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.801604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:38:43.393896Z","caller":"traceutil/trace.go:171","msg":"trace[439952378] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"276.997297ms","start":"2026-04-03T02:38:43.116874Z","end":"2026-04-03T02:38:43.393871Z","steps":["trace[439952378] 'read index received' (duration: 164.897944ms)","trace[439952378] 'applied index is now lower than readState.Index' (duration: 112.097603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:43.394123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.090981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-03T02:38:43.394153Z","caller":"traceutil/trace.go:171","msg":"trace[1339992753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1052; }","duration":"507.158992ms","start":"2026-04-03T02:38:42.886984Z","end":"2026-04-03T02:38:43.394143Z","steps":["trace[1339992753] 'agreement among raft nodes before linearized reading' (duration: 506.963318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:43.394193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:42.886977Z","time spent":"507.206333ms","remote":"127.0.0.1:34830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T02:38:43.394273Z","caller":"traceutil/trace.go:171","msg":"trace[1535173604] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"373.787418ms","start":"2026-04-03T02:38:43.020444Z","end":"2026-04-03T02:38:43.394232Z","steps":["trace[1535173604] 'process raft request' (duration: 261.314107ms)","trace[1535173604] 'compare' (duration: 111.60887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:43.39435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.046826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-03T02:38:43.394484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:43.020419Z","time spent":"373.922642ms","remote":"127.0.0.1:34830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:38:43.394505Z","caller":"traceutil/trace.go:171","msg":"trace[2048550066] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1052; }","duration":"468.288623ms","start":"2026-04-03T02:38:42.926179Z","end":"2026-04-03T02:38:43.394468Z","steps":["trace[2048550066] 'agreement among raft nodes before linearized reading' (duration: 468.030506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:43.394577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:38:42.926154Z","time spent":"468.410026ms","remote":"127.0.0.1:34816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-04-03T02:38:43.402952Z","caller":"traceutil/trace.go:171","msg":"trace[1822345162] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"281.058209ms","start":"2026-04-03T02:38:43.121877Z","end":"2026-04-03T02:38:43.402935Z","steps":["trace[1822345162] 'process raft request' (duration: 280.997418ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:43.40298Z","caller":"traceutil/trace.go:171","msg":"trace[425468028] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"284.832096ms","start":"2026-04-03T02:38:43.11813Z","end":"2026-04-03T02:38:43.402963Z","steps":["trace[425468028] 'process raft request' (duration: 284.56265ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:43.554226Z","caller":"traceutil/trace.go:171","msg":"trace[1563024922] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"147.710768ms","start":"2026-04-03T02:38:43.406475Z","end":"2026-04-03T02:38:43.554186Z","steps":["trace[1563024922] 'read index received' (duration: 145.120352ms)","trace[1563024922] 'applied index is now lower than readState.Index' (duration: 2.588656ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:38:43.554459Z","caller":"traceutil/trace.go:171","msg":"trace[461310346] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"155.686002ms","start":"2026-04-03T02:38:43.398721Z","end":"2026-04-03T02:38:43.554407Z","steps":["trace[461310346] 'process raft request' (duration: 152.90737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:43.554496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.948474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-03T02:38:43.554566Z","caller":"traceutil/trace.go:171","msg":"trace[7045281] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1055; }","duration":"148.149139ms","start":"2026-04-03T02:38:43.406402Z","end":"2026-04-03T02:38:43.554551Z","steps":["trace[7045281] 'agreement among raft nodes before linearized reading' (duration: 147.923683ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:09.730688Z","caller":"traceutil/trace.go:171","msg":"trace[1543457664] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"179.52689ms","start":"2026-04-03T02:39:09.551129Z","end":"2026-04-03T02:39:09.730656Z","steps":["trace[1543457664] 'process raft request' (duration: 166.402825ms)","trace[1543457664] 'compare' (duration: 12.919109ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:12.453654Z","caller":"traceutil/trace.go:171","msg":"trace[1955507851] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"211.989151ms","start":"2026-04-03T02:39:12.241639Z","end":"2026-04-03T02:39:12.453628Z","steps":["trace[1955507851] 'process raft request' (duration: 146.183117ms)","trace[1955507851] 'compare' (duration: 65.534567ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:41.451924Z","caller":"traceutil/trace.go:171","msg":"trace[1608948328] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"257.472284ms","start":"2026-04-03T02:39:41.194392Z","end":"2026-04-03T02:39:41.451864Z","steps":["trace[1608948328] 'process raft request' (duration: 257.122546ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:41.452257Z","caller":"traceutil/trace.go:171","msg":"trace[935445353] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"254.191965ms","start":"2026-04-03T02:39:41.198051Z","end":"2026-04-03T02:39:41.452243Z","steps":["trace[935445353] 'process raft request' (duration: 253.665582ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:42.607442Z","caller":"traceutil/trace.go:171","msg":"trace[1119871715] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"167.890809ms","start":"2026-04-03T02:39:42.439529Z","end":"2026-04-03T02:39:42.60742Z","steps":["trace[1119871715] 'read index received' (duration: 159.838873ms)","trace[1119871715] 'applied index is now lower than readState.Index' (duration: 8.050016ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:42.607448Z","caller":"traceutil/trace.go:171","msg":"trace[908430166] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"238.202496ms","start":"2026-04-03T02:39:42.369221Z","end":"2026-04-03T02:39:42.607424Z","steps":["trace[908430166] 'process raft request' (duration: 230.243633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:42.607629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.086693ms","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-04-03T02:39:42.608155Z","caller":"traceutil/trace.go:171","msg":"trace[1285783228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"168.639387ms","start":"2026-04-03T02:39:42.43949Z","end":"2026-04-03T02:39:42.60813Z","steps":["trace[1285783228] 'agreement among raft nodes before linearized reading' (duration: 168.006171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:42.607739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.048494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:39:42.60825Z","caller":"traceutil/trace.go:171","msg":"trace[1517800738] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1320; }","duration":"136.589358ms","start":"2026-04-03T02:39:42.47164Z","end":"2026-04-03T02:39:42.608229Z","steps":["trace[1517800738] 'agreement among raft nodes before linearized reading' (duration: 136.015753ms)"],"step_count":1}