{"level":"warn","ts":"2026-04-12T18:29:39.407404Z","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-12T18:29:39.407491Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-12T18:29:39.40764Z","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-12T18:29:39.407655Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-12T18:29:39.40768Z","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-12T18:29:39.408625Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-04-12T18:29:39.408714Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-12T18:29:39.412713Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.742132ms"} {"level":"info","ts":"2026-04-12T18:29:39.422154Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-04-12T18:29:39.422276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T18:29:39.422342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-04-12T18:29:39.422371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T18:29:39.422417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-04-12T18:29:39.422494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-04-12T18:29:39.578431Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T18:29:39.590605Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T18:29:39.595667Z","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-12T18:29:39.601148Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T18:29:39.601694Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T18:29:39.601844Z","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-12T18:29:39.602599Z","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-12T18:29:39.602746Z","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-12T18:29:39.603476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-04-12T18:29:39.60415Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-12T18:29:39.612275Z","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-12T18:29:39.61256Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T18:29:39.61259Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T18:29:39.61334Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-12T18:29:39.613414Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-12T18:29:39.724054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T18:29:39.724126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T18:29:39.724149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-04-12T18:29:39.724167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-04-12T18:29:39.724178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-12T18:29:39.724194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-04-12T18:29:39.724253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-12T18:29:39.724984Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:29:39.725655Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T18:29:39.725767Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T18:29:39.726286Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:29:39.726489Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:29:39.726536Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T18:29:39.729175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T18:29:39.729925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T18:29:39.730089Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T18:29:39.730185Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T18:29:39.733061Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-04-12T18:29:48.6619Z","caller":"traceutil/trace.go:171","msg":"trace[815836905] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"267.025372ms","start":"2026-04-12T18:29:48.39485Z","end":"2026-04-12T18:29:48.661875Z","steps":["trace[815836905] 'process raft request' (duration: 266.167809ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.00349Z","caller":"traceutil/trace.go:171","msg":"trace[1965275724] transaction","detail":"{read_only:false; number_of_response:1; response_revision:481; }","duration":"168.415257ms","start":"2026-04-12T18:30:09.835048Z","end":"2026-04-12T18:30:10.003463Z","steps":["trace[1965275724] 'process raft request' (duration: 143.610183ms)","trace[1965275724] 'compare' (duration: 24.732132ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:30:10.059637Z","caller":"traceutil/trace.go:171","msg":"trace[1386125040] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"202.820453ms","start":"2026-04-12T18:30:09.856792Z","end":"2026-04-12T18:30:10.059612Z","steps":["trace[1386125040] 'process raft request' (duration: 202.660269ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.059777Z","caller":"traceutil/trace.go:171","msg":"trace[1399647426] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"214.450119ms","start":"2026-04-12T18:30:09.84527Z","end":"2026-04-12T18:30:10.05972Z","steps":["trace[1399647426] 'process raft request' (duration: 214.067419ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.060096Z","caller":"traceutil/trace.go:171","msg":"trace[1032921651] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"214.961613ms","start":"2026-04-12T18:30:09.845063Z","end":"2026-04-12T18:30:10.060024Z","steps":["trace[1032921651] 'process raft request' (duration: 214.052589ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.062383Z","caller":"traceutil/trace.go:171","msg":"trace[388756426] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"203.816969ms","start":"2026-04-12T18:30:09.858487Z","end":"2026-04-12T18:30:10.062304Z","steps":["trace[388756426] 'process raft request' (duration: 201.078957ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.063644Z","caller":"traceutil/trace.go:171","msg":"trace[1747823253] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"198.815628ms","start":"2026-04-12T18:30:09.864809Z","end":"2026-04-12T18:30:10.063625Z","steps":["trace[1747823253] 'process raft request' (duration: 195.256305ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.070606Z","caller":"traceutil/trace.go:171","msg":"trace[973465031] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"197.252306ms","start":"2026-04-12T18:30:09.87331Z","end":"2026-04-12T18:30:10.070562Z","steps":["trace[973465031] 'process raft request' (duration: 197.049421ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.070742Z","caller":"traceutil/trace.go:171","msg":"trace[1591143642] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"195.378666ms","start":"2026-04-12T18:30:09.875332Z","end":"2026-04-12T18:30:10.070711Z","steps":["trace[1591143642] 'process raft request' (duration: 195.160291ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.070769Z","caller":"traceutil/trace.go:171","msg":"trace[608384078] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"102.383356ms","start":"2026-04-12T18:30:09.968373Z","end":"2026-04-12T18:30:10.070756Z","steps":["trace[608384078] 'process raft request' (duration: 102.198452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:10.415749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.782748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T18:30:10.416033Z","caller":"traceutil/trace.go:171","msg":"trace[1565314460] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"265.636888ms","start":"2026-04-12T18:30:10.150378Z","end":"2026-04-12T18:30:10.416015Z","steps":["trace[1565314460] 'process raft request' (duration: 265.505485ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.416004Z","caller":"traceutil/trace.go:171","msg":"trace[2074546232] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"271.955456ms","start":"2026-04-12T18:30:10.144004Z","end":"2026-04-12T18:30:10.415959Z","steps":["trace[2074546232] 'process raft request' (duration: 149.360096ms)","trace[2074546232] 'compare' (duration: 121.622473ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:30:10.416223Z","caller":"traceutil/trace.go:171","msg":"trace[928987454] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"264.323514ms","start":"2026-04-12T18:30:10.151858Z","end":"2026-04-12T18:30:10.416181Z","steps":["trace[928987454] 'process raft request' (duration: 264.099299ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.416642Z","caller":"traceutil/trace.go:171","msg":"trace[965224875] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"248.401274ms","start":"2026-04-12T18:30:10.168231Z","end":"2026-04-12T18:30:10.416632Z","steps":["trace[965224875] 'process raft request' (duration: 247.895821ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:10.416901Z","caller":"traceutil/trace.go:171","msg":"trace[887044316] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:516; }","duration":"107.693418ms","start":"2026-04-12T18:30:10.309191Z","end":"2026-04-12T18:30:10.416884Z","steps":["trace[887044316] 'read index received' (duration: 105.630083ms)","trace[887044316] 'applied index is now lower than readState.Index' (duration: 2.062215ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:30:10.416921Z","caller":"traceutil/trace.go:171","msg":"trace[485377380] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"180.68007ms","start":"2026-04-12T18:30:10.236223Z","end":"2026-04-12T18:30:10.416903Z","steps":["trace[485377380] 'process raft request' (duration: 180.130276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:10.417064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.859442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:30:10.417109Z","caller":"traceutil/trace.go:171","msg":"trace[1332381057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"107.956734ms","start":"2026-04-12T18:30:10.309141Z","end":"2026-04-12T18:30:10.417098Z","steps":["trace[1332381057] 'agreement among raft nodes before linearized reading' (duration: 107.839881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:10.417181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.444605ms","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-12T18:30:10.417213Z","caller":"traceutil/trace.go:171","msg":"trace[672417868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"103.511567ms","start":"2026-04-12T18:30:10.313694Z","end":"2026-04-12T18:30:10.417206Z","steps":["trace[672417868] 'agreement among raft nodes before linearized reading' (duration: 103.385094ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:16.425917Z","caller":"traceutil/trace.go:171","msg":"trace[382187580] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"119.070467ms","start":"2026-04-12T18:30:16.306824Z","end":"2026-04-12T18:30:16.425894Z","steps":["trace[382187580] 'read index received' (duration: 118.871142ms)","trace[382187580] 'applied index is now lower than readState.Index' (duration: 198.425µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:30:16.426103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.269313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:30:16.426143Z","caller":"traceutil/trace.go:171","msg":"trace[1246668530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"119.367295ms","start":"2026-04-12T18:30:16.306765Z","end":"2026-04-12T18:30:16.426133Z","steps":["trace[1246668530] 'agreement among raft nodes before linearized reading' (duration: 119.218651ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:30:16.426159Z","caller":"traceutil/trace.go:171","msg":"trace[1704468393] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"126.00382ms","start":"2026-04-12T18:30:16.300111Z","end":"2026-04-12T18:30:16.426115Z","steps":["trace[1704468393] 'process raft request' (duration: 125.64352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:16.941037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.242426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T18:30:16.941341Z","caller":"traceutil/trace.go:171","msg":"trace[1414064680] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"483.500429ms","start":"2026-04-12T18:30:16.457804Z","end":"2026-04-12T18:30:16.941304Z","steps":["trace[1414064680] 'process raft request' (duration: 282.895583ms)","trace[1414064680] 'compare' (duration: 194.548647ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T18:30:16.941515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:16.457792Z","time spent":"483.641642ms","remote":"127.0.0.1:53326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T18:30:32.287652Z","caller":"traceutil/trace.go:171","msg":"trace[511345150] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"113.763568ms","start":"2026-04-12T18:30:32.17386Z","end":"2026-04-12T18:30:32.287624Z","steps":["trace[511345150] 'process raft request' (duration: 113.402048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:38.807492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811216324060604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:40.862194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811216324060604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:40.862545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.555345932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-12T18:30:40.862578Z","caller":"traceutil/trace.go:171","msg":"trace[1130165019] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.555419204s","start":"2026-04-12T18:30:38.307144Z","end":"2026-04-12T18:30:40.862563Z","steps":["trace[1130165019] 'agreement among raft nodes before linearized reading' (duration: 2.555380563s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:40.862602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:38.307125Z","time spent":"2.555472265s","remote":"127.0.0.1:53234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T18:30:41.363429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811216324060604,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T18:30:41.396193Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.253594062s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T18:30:41.415305Z","caller":"traceutil/trace.go:171","msg":"trace[2146675494] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:825; }","duration":"3.108071743s","start":"2026-04-12T18:30:38.307186Z","end":"2026-04-12T18:30:41.415258Z","steps":["trace[2146675494] 'read index received' (duration: 3.089262658s)","trace[2146675494] 'applied index is now lower than readState.Index' (duration: 18.807095ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:30:41.415393Z","caller":"traceutil/trace.go:171","msg":"trace[543699471] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"2.970704034s","start":"2026-04-12T18:30:38.444667Z","end":"2026-04-12T18:30:41.415371Z","steps":["trace[543699471] 'process raft request' (duration: 2.970380076s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.415579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:38.444633Z","time spent":"2.970819577s","remote":"127.0.0.1:53524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T18:30:41.41564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.061013493s","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-12T18:30:41.415787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.649922ms","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-04-12T18:30:41.415821Z","caller":"traceutil/trace.go:171","msg":"trace[62467406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"551.694103ms","start":"2026-04-12T18:30:40.864117Z","end":"2026-04-12T18:30:41.415812Z","steps":["trace[62467406] 'agreement among raft nodes before linearized reading' (duration: 551.619801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.415849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:40.864105Z","time spent":"551.735414ms","remote":"127.0.0.1:53524","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-04-12T18:30:41.416067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.371696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-12T18:30:41.416117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.972131ms","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-12T18:30:41.416139Z","caller":"traceutil/trace.go:171","msg":"trace[1954637700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"552.030533ms","start":"2026-04-12T18:30:40.864102Z","end":"2026-04-12T18:30:41.416133Z","steps":["trace[1954637700] 'agreement among raft nodes before linearized reading' (duration: 551.969461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.416155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:40.864084Z","time spent":"552.066853ms","remote":"127.0.0.1:53524","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":"info","ts":"2026-04-12T18:30:41.416146Z","caller":"traceutil/trace.go:171","msg":"trace[226221199] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:800; }","duration":"220.474978ms","start":"2026-04-12T18:30:41.195631Z","end":"2026-04-12T18:30:41.416106Z","steps":["trace[226221199] 'agreement among raft nodes before linearized reading' (duration: 220.338615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.41617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.047533ms","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-04-12T18:30:41.416252Z","caller":"traceutil/trace.go:171","msg":"trace[1917986883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"552.148056ms","start":"2026-04-12T18:30:40.864082Z","end":"2026-04-12T18:30:41.41623Z","steps":["trace[1917986883] 'agreement among raft nodes before linearized reading' (duration: 551.9176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.416305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.253325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:30:41.416322Z","caller":"traceutil/trace.go:171","msg":"trace[155980696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"553.286526ms","start":"2026-04-12T18:30:40.863031Z","end":"2026-04-12T18:30:41.416317Z","steps":["trace[155980696] 'agreement among raft nodes before linearized reading' (duration: 553.258175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.416313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:40.864075Z","time spent":"552.221497ms","remote":"127.0.0.1:53524","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-04-12T18:30:41.416336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:40.863022Z","time spent":"553.311707ms","remote":"127.0.0.1:53226","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-04-12T18:30:41.416505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.385292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-12T18:30:41.416494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.827214966s","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-12T18:30:41.416522Z","caller":"traceutil/trace.go:171","msg":"trace[1585136427] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:800; }","duration":"552.409832ms","start":"2026-04-12T18:30:40.864107Z","end":"2026-04-12T18:30:41.416517Z","steps":["trace[1585136427] 'agreement among raft nodes before linearized reading' (duration: 552.365071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.416538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:40.864103Z","time spent":"552.431853ms","remote":"127.0.0.1:53524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-04-12T18:30:41.416546Z","caller":"traceutil/trace.go:171","msg":"trace[55640441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"2.827271117s","start":"2026-04-12T18:30:38.589262Z","end":"2026-04-12T18:30:41.416533Z","steps":["trace[55640441] 'agreement among raft nodes before linearized reading' (duration: 2.827089403s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.416584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:38.589197Z","time spent":"2.827377289s","remote":"127.0.0.1:53524","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-12T18:30:41.415746Z","caller":"traceutil/trace.go:171","msg":"trace[1122070953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"3.061169127s","start":"2026-04-12T18:30:38.354559Z","end":"2026-04-12T18:30:41.415728Z","steps":["trace[1122070953] 'agreement among raft nodes before linearized reading' (duration: 3.060953092s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:30:41.416833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T18:30:38.354538Z","time spent":"3.062279127s","remote":"127.0.0.1:53524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-12T18:31:03.60361Z","caller":"traceutil/trace.go:171","msg":"trace[520166856] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"108.052416ms","start":"2026-04-12T18:31:03.495528Z","end":"2026-04-12T18:31:03.60358Z","steps":["trace[520166856] 'process raft request' (duration: 107.684236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:32:04.21721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.266868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-12T18:32:04.217261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.176227ms","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-12T18:32:04.217284Z","caller":"traceutil/trace.go:171","msg":"trace[804453588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"123.385651ms","start":"2026-04-12T18:32:04.093883Z","end":"2026-04-12T18:32:04.217269Z","steps":["trace[804453588] 'range keys from in-memory index tree' (duration: 123.139764ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:32:04.217311Z","caller":"traceutil/trace.go:171","msg":"trace[1417477618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"117.238119ms","start":"2026-04-12T18:32:04.100059Z","end":"2026-04-12T18:32:04.217297Z","steps":["trace[1417477618] 'range keys from in-memory index tree' (duration: 117.092745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:32:04.21721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.073703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-12T18:32:04.217381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.373925ms","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-12T18:32:04.217416Z","caller":"traceutil/trace.go:171","msg":"trace[529481715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1206; }","duration":"113.322589ms","start":"2026-04-12T18:32:04.104085Z","end":"2026-04-12T18:32:04.217408Z","steps":["trace[529481715] 'range keys from in-memory index tree' (duration: 112.943699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:32:04.217451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.976937ms","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-04-12T18:32:04.217481Z","caller":"traceutil/trace.go:171","msg":"trace[2091593178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"109.028499ms","start":"2026-04-12T18:32:04.108445Z","end":"2026-04-12T18:32:04.217473Z","steps":["trace[2091593178] 'range keys from in-memory index tree' (duration: 108.848813ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:32:04.217468Z","caller":"traceutil/trace.go:171","msg":"trace[1747721836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1206; }","duration":"111.501268ms","start":"2026-04-12T18:32:04.105948Z","end":"2026-04-12T18:32:04.217449Z","steps":["trace[1747721836] 'range keys from in-memory index tree' (duration: 111.120188ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:32:04.371455Z","caller":"traceutil/trace.go:171","msg":"trace[1957114490] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"150.366008ms","start":"2026-04-12T18:32:04.22106Z","end":"2026-04-12T18:32:04.371426Z","steps":["trace[1957114490] 'process raft request' (duration: 150.206064ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:32:04.466889Z","caller":"traceutil/trace.go:171","msg":"trace[1771146445] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"244.384076ms","start":"2026-04-12T18:32:04.222476Z","end":"2026-04-12T18:32:04.46686Z","steps":["trace[1771146445] 'process raft request' (duration: 244.136689ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:32:04.467236Z","caller":"traceutil/trace.go:171","msg":"trace[1396119493] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"244.130809ms","start":"2026-04-12T18:32:04.222708Z","end":"2026-04-12T18:32:04.466839Z","steps":["trace[1396119493] 'process raft request' (duration: 244.048647ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:32:04.467293Z","caller":"traceutil/trace.go:171","msg":"trace[1943893915] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"244.519329ms","start":"2026-04-12T18:32:04.222766Z","end":"2026-04-12T18:32:04.467286Z","steps":["trace[1943893915] 'process raft request' (duration: 244.312554ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:32:04.467271Z","caller":"traceutil/trace.go:171","msg":"trace[2034792293] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1254; }","duration":"158.197257ms","start":"2026-04-12T18:32:04.309052Z","end":"2026-04-12T18:32:04.467249Z","steps":["trace[2034792293] 'read index received' (duration: 62.261476ms)","trace[2034792293] 'applied index is now lower than readState.Index' (duration: 95.934961ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T18:32:04.467331Z","caller":"traceutil/trace.go:171","msg":"trace[1723971365] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"244.458638ms","start":"2026-04-12T18:32:04.222798Z","end":"2026-04-12T18:32:04.467257Z","steps":["trace[1723971365] 'process raft request' (duration: 243.999635ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T18:32:04.467411Z","caller":"traceutil/trace.go:171","msg":"trace[1949960103] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"229.563331ms","start":"2026-04-12T18:32:04.23784Z","end":"2026-04-12T18:32:04.467403Z","steps":["trace[1949960103] 'process raft request' (duration: 229.368785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T18:32:04.467385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.331852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T18:32:04.467573Z","caller":"traceutil/trace.go:171","msg":"trace[1045648098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"158.585239ms","start":"2026-04-12T18:32:04.308975Z","end":"2026-04-12T18:32:04.46756Z","steps":["trace[1045648098] 'agreement among raft nodes before linearized reading' (duration: 158.352573ms)"],"step_count":1}