{"level":"warn","ts":"2026-04-22T19:38:55.293945Z","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-22T19:38:55.294045Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-22T19:38:55.294119Z","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-22T19:38:55.294132Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-22T19:38:55.294157Z","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-22T19:38:55.294765Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-22T19:38:55.29484Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-22T19:38:55.299561Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.528102ms"} {"level":"info","ts":"2026-04-22T19:38:55.31103Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-04-22T19:38:55.311117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T19:38:55.311139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-04-22T19:38:55.311153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T19:38:55.311162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-04-22T19:38:55.311206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-04-22T19:38:55.320467Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T19:38:55.322372Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T19:38:55.32358Z","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-22T19:38:55.32594Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T19:38:55.32605Z","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-22T19:38:55.326139Z","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-22T19:38:55.32615Z","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-22T19:38:55.326143Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T19:38:55.331341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-04-22T19:38:55.331479Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-22T19:38:55.334514Z","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-22T19:38:55.334728Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-22T19:38:55.334774Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-22T19:38:55.334987Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T19:38:55.335034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T19:38:55.912198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T19:38:55.912251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T19:38:55.912289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-04-22T19:38:55.912306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-04-22T19:38:55.912317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-22T19:38:55.912332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-04-22T19:38:55.912346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-22T19:38:55.971219Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T19:38:56.007315Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T19:38:56.007321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T19:38:56.007465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T19:38:56.007858Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T19:38:56.007905Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T19:38:56.010325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-04-22T19:38:56.011177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T19:38:56.054332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T19:38:56.05445Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T19:38:56.054495Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T19:38:57.609805Z","caller":"traceutil/trace.go:171","msg":"trace[94324708] linearizableReadLoop","detail":"{readStateIndex:52; appliedIndex:49; }","duration":"163.381907ms","start":"2026-04-22T19:38:57.446388Z","end":"2026-04-22T19:38:57.60977Z","steps":["trace[94324708] 'read index received' (duration: 147.952854ms)","trace[94324708] 'applied index is now lower than readState.Index' (duration: 15.428253ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:38:57.609945Z","caller":"traceutil/trace.go:171","msg":"trace[1974617286] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"214.525642ms","start":"2026-04-22T19:38:57.395351Z","end":"2026-04-22T19:38:57.609877Z","steps":["trace[1974617286] 'process raft request' (duration: 198.801607ms)","trace[1974617286] 'compare' (duration: 15.299252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:38:57.610156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.671129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T19:38:57.610272Z","caller":"traceutil/trace.go:171","msg":"trace[90238114] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:47; }","duration":"163.919131ms","start":"2026-04-22T19:38:57.446337Z","end":"2026-04-22T19:38:57.610256Z","steps":["trace[90238114] 'agreement among raft nodes before linearized reading' (duration: 163.65569ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:38:57.610266Z","caller":"traceutil/trace.go:171","msg":"trace[1114179006] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"177.212728ms","start":"2026-04-22T19:38:57.433001Z","end":"2026-04-22T19:38:57.610214Z","steps":["trace[1114179006] 'process raft request' (duration: 176.705095ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:38:57.915517Z","caller":"traceutil/trace.go:171","msg":"trace[1263711248] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"123.308103ms","start":"2026-04-22T19:38:57.792181Z","end":"2026-04-22T19:38:57.915489Z","steps":["trace[1263711248] 'process raft request' (duration: 39.880623ms)","trace[1263711248] 'compare' (duration: 83.305979ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:39:19.241721Z","caller":"traceutil/trace.go:171","msg":"trace[2071972653] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"296.453965ms","start":"2026-04-22T19:39:18.945241Z","end":"2026-04-22T19:39:19.241695Z","steps":["trace[2071972653] 'read index received' (duration: 296.288097ms)","trace[2071972653] 'applied index is now lower than readState.Index' (duration: 164.978µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:39:19.241785Z","caller":"traceutil/trace.go:171","msg":"trace[1907453113] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"410.743939ms","start":"2026-04-22T19:39:18.831011Z","end":"2026-04-22T19:39:19.241755Z","steps":["trace[1907453113] 'process raft request' (duration: 410.513677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:19.241885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.631284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T19:39:19.241927Z","caller":"traceutil/trace.go:171","msg":"trace[1369339577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"296.724428ms","start":"2026-04-22T19:39:18.945192Z","end":"2026-04-22T19:39:19.241916Z","steps":["trace[1369339577] 'agreement among raft nodes before linearized reading' (duration: 296.628713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:19.241968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.565834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-22T19:39:19.242077Z","caller":"traceutil/trace.go:171","msg":"trace[2126589425] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:429; }","duration":"282.723661ms","start":"2026-04-22T19:39:18.959324Z","end":"2026-04-22T19:39:19.242048Z","steps":["trace[2126589425] 'agreement among raft nodes before linearized reading' (duration: 282.547303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:19.242779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:18.830993Z","time spent":"410.872875ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:39:24.384787Z","caller":"traceutil/trace.go:171","msg":"trace[1441133993] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"440.223919ms","start":"2026-04-22T19:39:23.944537Z","end":"2026-04-22T19:39:24.384761Z","steps":["trace[1441133993] 'read index received' (duration: 440.179787ms)","trace[1441133993] 'applied index is now lower than readState.Index' (duration: 43.092µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:39:24.384913Z","caller":"traceutil/trace.go:171","msg":"trace[1278898754] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"555.050067ms","start":"2026-04-22T19:39:23.829839Z","end":"2026-04-22T19:39:24.384889Z","steps":["trace[1278898754] 'process raft request' (duration: 554.795485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:24.384947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.390217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T19:39:24.384987Z","caller":"traceutil/trace.go:171","msg":"trace[2135022448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"440.46894ms","start":"2026-04-22T19:39:23.944506Z","end":"2026-04-22T19:39:24.384975Z","steps":["trace[2135022448] 'agreement among raft nodes before linearized reading' (duration: 440.361715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:24.385052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:23.829821Z","time spent":"555.160522ms","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:39:24.385071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:23.944492Z","time spent":"440.570125ms","remote":"127.0.0.1:49458","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-22T19:39:28.569436Z","caller":"traceutil/trace.go:171","msg":"trace[1561342700] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"143.586355ms","start":"2026-04-22T19:39:28.425816Z","end":"2026-04-22T19:39:28.569403Z","steps":["trace[1561342700] 'read index received' (duration: 143.333514ms)","trace[1561342700] 'applied index is now lower than readState.Index' (duration: 251.801µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:39:28.569528Z","caller":"traceutil/trace.go:171","msg":"trace[671794970] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"168.201316ms","start":"2026-04-22T19:39:28.401299Z","end":"2026-04-22T19:39:28.5695Z","steps":["trace[671794970] 'process raft request' (duration: 167.901822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:28.569636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.812305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T19:39:28.569688Z","caller":"traceutil/trace.go:171","msg":"trace[1240027255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"143.944842ms","start":"2026-04-22T19:39:28.425731Z","end":"2026-04-22T19:39:28.569676Z","steps":["trace[1240027255] 'agreement among raft nodes before linearized reading' (duration: 143.772645ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:39:29.70098Z","caller":"traceutil/trace.go:171","msg":"trace[68565379] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"233.818695ms","start":"2026-04-22T19:39:29.467127Z","end":"2026-04-22T19:39:29.700945Z","steps":["trace[68565379] 'process raft request' (duration: 233.565564ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:39:38.792027Z","caller":"traceutil/trace.go:171","msg":"trace[302317249] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"142.158823ms","start":"2026-04-22T19:39:38.649848Z","end":"2026-04-22T19:39:38.792006Z","steps":["trace[302317249] 'read index received' (duration: 142.102181ms)","trace[302317249] 'applied index is now lower than readState.Index' (duration: 55.762µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:39:38.792289Z","caller":"traceutil/trace.go:171","msg":"trace[1791142339] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"155.985793ms","start":"2026-04-22T19:39:38.636291Z","end":"2026-04-22T19:39:38.792277Z","steps":["trace[1791142339] 'process raft request' (duration: 155.583327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:38.79252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.669782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T19:39:38.792561Z","caller":"traceutil/trace.go:171","msg":"trace[501252255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"142.754226ms","start":"2026-04-22T19:39:38.649798Z","end":"2026-04-22T19:39:38.792552Z","steps":["trace[501252255] 'agreement among raft nodes before linearized reading' (duration: 142.636442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:39.293063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512999348917356,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T19:39:39.436195Z","caller":"traceutil/trace.go:171","msg":"trace[1969690409] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"704.367232ms","start":"2026-04-22T19:39:38.731807Z","end":"2026-04-22T19:39:39.436174Z","steps":["trace[1969690409] 'process raft request' (duration: 703.873653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:39.436851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:38.731779Z","time spent":"704.512068ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T19:39:39.513627Z","caller":"traceutil/trace.go:171","msg":"trace[983268006] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:649; }","duration":"721.479007ms","start":"2026-04-22T19:39:38.792095Z","end":"2026-04-22T19:39:39.513574Z","steps":["trace[983268006] 'read index received' (duration: 643.519274ms)","trace[983268006] 'applied index is now lower than readState.Index' (duration: 77.958473ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:39:39.513737Z","caller":"traceutil/trace.go:171","msg":"trace[273630921] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"713.632715ms","start":"2026-04-22T19:39:38.800093Z","end":"2026-04-22T19:39:39.513726Z","steps":["trace[273630921] 'process raft request' (duration: 662.722564ms)","trace[273630921] 'compare' (duration: 50.488605ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:39:39.513878Z","caller":"traceutil/trace.go:171","msg":"trace[1885940363] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"687.966745ms","start":"2026-04-22T19:39:38.825875Z","end":"2026-04-22T19:39:39.513841Z","steps":["trace[1885940363] 'process raft request' (duration: 687.6168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:39.514097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:38.825854Z","time spent":"688.153832ms","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:39:39.514096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.29085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jt5qs\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-22T19:39:39.514335Z","caller":"traceutil/trace.go:171","msg":"trace[1040092419] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jt5qs; range_end:; response_count:1; response_revision:631; }","duration":"723.53459ms","start":"2026-04-22T19:39:38.790715Z","end":"2026-04-22T19:39:39.51425Z","steps":["trace[1040092419] 'agreement among raft nodes before linearized reading' (duration: 723.166775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:39.514466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:38.790684Z","time spent":"723.720347ms","remote":"127.0.0.1:49634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-jt5qs\" "} {"level":"warn","ts":"2026-04-22T19:39:39.514136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:38.800062Z","time spent":"713.702838ms","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:39:39.575275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.910533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-22T19:39:39.575292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.289034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T19:39:39.575371Z","caller":"traceutil/trace.go:171","msg":"trace[1551365156] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:632; }","duration":"614.061999ms","start":"2026-04-22T19:39:38.961286Z","end":"2026-04-22T19:39:39.575348Z","steps":["trace[1551365156] 'agreement among raft nodes before linearized reading' (duration: 613.876721ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:39:39.575386Z","caller":"traceutil/trace.go:171","msg":"trace[931151103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"631.435099ms","start":"2026-04-22T19:39:38.943929Z","end":"2026-04-22T19:39:39.575364Z","steps":["trace[931151103] 'agreement among raft nodes before linearized reading' (duration: 631.243662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:39.575523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:38.943911Z","time spent":"631.603577ms","remote":"127.0.0.1:49458","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-22T19:39:39.575525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:38.961266Z","time spent":"614.244536ms","remote":"127.0.0.1:49480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-04-22T19:39:40.077368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512999348917362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T19:39:40.942183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.689409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T19:39:40.942351Z","caller":"traceutil/trace.go:171","msg":"trace[1125898800] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"1.365510103s","start":"2026-04-22T19:39:39.576827Z","end":"2026-04-22T19:39:40.942338Z","steps":["trace[1125898800] 'read index received' (duration: 679.487042ms)","trace[1125898800] 'applied index is now lower than readState.Index' (duration: 686.021901ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T19:39:40.942431Z","caller":"traceutil/trace.go:171","msg":"trace[1088683503] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.41300573s","start":"2026-04-22T19:39:39.529415Z","end":"2026-04-22T19:39:40.942421Z","steps":["trace[1088683503] 'process raft request' (duration: 726.887134ms)","trace[1088683503] 'compare' (duration: 685.435459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:39:40.942482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:39.529381Z","time spent":"1.413071873s","remote":"127.0.0.1:49634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T19:39:40.942628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:39.576443Z","time spent":"1.366182941s","remote":"127.0.0.1:49480","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-22T19:39:40.942766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365953552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T19:39:40.942793Z","caller":"traceutil/trace.go:171","msg":"trace[393415366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.366019194s","start":"2026-04-22T19:39:39.576764Z","end":"2026-04-22T19:39:40.942784Z","steps":["trace[393415366] 'agreement among raft nodes before linearized reading' (duration: 1.365973262s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:40.942818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:39.576746Z","time spent":"1.366067016s","remote":"127.0.0.1:49464","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-22T19:39:40.943191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.736843ms","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-22T19:39:40.943226Z","caller":"traceutil/trace.go:171","msg":"trace[1681198211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"146.806066ms","start":"2026-04-22T19:39:40.796409Z","end":"2026-04-22T19:39:40.943215Z","steps":["trace[1681198211] 'agreement among raft nodes before linearized reading' (duration: 146.689072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:40.943322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175133979s","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-22T19:39:40.943382Z","caller":"traceutil/trace.go:171","msg":"trace[64030439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.175228413s","start":"2026-04-22T19:39:39.768138Z","end":"2026-04-22T19:39:40.943367Z","steps":["trace[64030439] 'agreement among raft nodes before linearized reading' (duration: 1.175024725s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:40.943412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:39.768126Z","time spent":"1.175280525s","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T19:39:40.943482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184168465s","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-22T19:39:40.943494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357646145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T19:39:40.943532Z","caller":"traceutil/trace.go:171","msg":"trace[1337991135] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:633; }","duration":"1.357731797s","start":"2026-04-22T19:39:39.585791Z","end":"2026-04-22T19:39:40.943523Z","steps":["trace[1337991135] 'agreement among raft nodes before linearized reading' (duration: 1.357678145s)"],"step_count":1} {"level":"info","ts":"2026-04-22T19:39:40.943537Z","caller":"traceutil/trace.go:171","msg":"trace[1257114657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.184256608s","start":"2026-04-22T19:39:39.759263Z","end":"2026-04-22T19:39:40.94352Z","steps":["trace[1257114657] 'agreement among raft nodes before linearized reading' (duration: 1.184158734s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:40.943559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:39.585775Z","time spent":"1.357777519s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-22T19:39:40.943577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:39.759245Z","time spent":"1.184321381s","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T19:39:56.363148Z","caller":"traceutil/trace.go:171","msg":"trace[1691691665] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"479.71328ms","start":"2026-04-22T19:39:55.883407Z","end":"2026-04-22T19:39:56.36312Z","steps":["trace[1691691665] 'read index received' (duration: 479.491452ms)","trace[1691691665] 'applied index is now lower than readState.Index' (duration: 220.898µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T19:39:56.363349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.611914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T19:39:56.363386Z","caller":"traceutil/trace.go:171","msg":"trace[1774405277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"418.687227ms","start":"2026-04-22T19:39:55.944688Z","end":"2026-04-22T19:39:56.363376Z","steps":["trace[1774405277] 'agreement among raft nodes before linearized reading' (duration: 418.615615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:56.363424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:55.944674Z","time spent":"418.742738ms","remote":"127.0.0.1:49458","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-22T19:39:56.363438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.02794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dtt4j\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-22T19:39:56.363495Z","caller":"traceutil/trace.go:171","msg":"trace[284637001] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dtt4j; range_end:; response_count:1; response_revision:791; }","duration":"480.195715ms","start":"2026-04-22T19:39:55.883286Z","end":"2026-04-22T19:39:56.363482Z","steps":["trace[284637001] 'agreement among raft nodes before linearized reading' (duration: 480.01655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:56.363536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:55.883253Z","time spent":"480.272588ms","remote":"127.0.0.1:49634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dtt4j\" "} {"level":"info","ts":"2026-04-22T19:39:56.363546Z","caller":"traceutil/trace.go:171","msg":"trace[1311614485] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"706.633384ms","start":"2026-04-22T19:39:55.656732Z","end":"2026-04-22T19:39:56.363366Z","steps":["trace[1311614485] 'process raft request' (duration: 706.249041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T19:39:56.363758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T19:39:55.656715Z","time spent":"706.958694ms","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}