{"level":"warn","ts":"2026-01-27T00:46:19.39519Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-27T00:46:19.395319Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-01-27T00:46:19.395409Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-27T00:46:19.395423Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-01-27T00:46:19.395444Z","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-01-27T00:46:19.399995Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-01-27T00:46:19.400189Z","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":2,"max-cpu-available":2,"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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-01-27T00:46:19.404843Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.352643ms"} {"level":"info","ts":"2026-01-27T00:46:19.41556Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-01-27T00:46:19.415698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-01-27T00:46:19.415781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-01-27T00:46:19.4158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-27T00:46:19.415808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-01-27T00:46:19.4159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-01-27T00:46:19.421158Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-27T00:46:19.430985Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-27T00:46:19.434394Z","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-01-27T00:46:19.438636Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-27T00:46:19.439807Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-27T00:46:19.449856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-01-27T00:46:19.450285Z","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-01-27T00:46:19.450331Z","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-01-27T00:46:19.450348Z","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-01-27T00:46:19.451487Z","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-01-27T00:46:19.45166Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-27T00:46:19.451696Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-27T00:46:19.451757Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-01-27T00:46:19.451838Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-01-27T00:46:19.45185Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-01-27T00:46:20.316191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-01-27T00:46:20.316249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-01-27T00:46:20.316278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-01-27T00:46:20.316294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-01-27T00:46:20.316302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-01-27T00:46:20.316321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-01-27T00:46:20.31633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-01-27T00:46:20.317211Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T00:46:20.317962Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-01-27T00:46:20.31815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-27T00:46:20.318462Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-27T00:46:20.318591Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-27T00:46:20.318608Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-27T00:46:20.320302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-01-27T00:46:20.32186Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-27T00:46:20.326226Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T00:46:20.326288Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-27T00:46:20.326301Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-01-27T00:46:29.818936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.548853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4593"} {"level":"info","ts":"2026-01-27T00:46:29.819308Z","caller":"traceutil/trace.go:171","msg":"trace[1987890390] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:309; }","duration":"428.912087ms","start":"2026-01-27T00:46:29.390374Z","end":"2026-01-27T00:46:29.819286Z","steps":["trace[1987890390] 'range keys from in-memory index tree' (duration: 428.21038ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.819388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.390372Z","time spent":"429.004457ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4615,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-01-27T00:46:29.818952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.493172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-01-27T00:46:29.819806Z","caller":"traceutil/trace.go:171","msg":"trace[218765818] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:309; }","duration":"429.34911ms","start":"2026-01-27T00:46:29.390449Z","end":"2026-01-27T00:46:29.819798Z","steps":["trace[218765818] 'range keys from in-memory index tree' (duration: 428.157209ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.819844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.390447Z","time spent":"429.3905ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6916,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-01-27T00:46:29.818966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.686913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-01-27T00:46:29.820091Z","caller":"traceutil/trace.go:171","msg":"trace[688115621] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:309; }","duration":"429.814853ms","start":"2026-01-27T00:46:29.39027Z","end":"2026-01-27T00:46:29.820085Z","steps":["trace[688115621] 'range keys from in-memory index tree' (duration: 428.29667ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.820133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.390267Z","time spent":"429.859385ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7250,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-01-27T00:46:29.818979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.755874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4115"} {"level":"info","ts":"2026-01-27T00:46:29.820383Z","caller":"traceutil/trace.go:171","msg":"trace[339559111] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:309; }","duration":"430.188407ms","start":"2026-01-27T00:46:29.390188Z","end":"2026-01-27T00:46:29.820376Z","steps":["trace[339559111] 'range keys from in-memory index tree' (duration: 428.262349ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.820426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.390162Z","time spent":"430.256297ms","remote":"127.0.0.1:47264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4137,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-01-27T00:46:29.818988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.660223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-01-27T00:46:29.820716Z","caller":"traceutil/trace.go:171","msg":"trace[1195097026] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:309; }","duration":"430.390208ms","start":"2026-01-27T00:46:29.390319Z","end":"2026-01-27T00:46:29.820709Z","steps":["trace[1195097026] 'range keys from in-memory index tree' (duration: 428.26598ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.820765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.390316Z","time spent":"430.440089ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-01-27T00:46:29.818998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.684903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-01-27T00:46:29.824581Z","caller":"traceutil/trace.go:171","msg":"trace[639939081] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:309; }","duration":"434.268401ms","start":"2026-01-27T00:46:29.390302Z","end":"2026-01-27T00:46:29.82457Z","steps":["trace[639939081] 'range keys from in-memory index tree' (duration: 428.27015ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.824675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.390299Z","time spent":"434.365572ms","remote":"127.0.0.1:47380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-01-27T00:46:29.819008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.577692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-01-27T00:46:29.824845Z","caller":"traceutil/trace.go:171","msg":"trace[105984162] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:309; }","duration":"434.420482ms","start":"2026-01-27T00:46:29.390415Z","end":"2026-01-27T00:46:29.824836Z","steps":["trace[105984162] 'range keys from in-memory index tree' (duration: 428.21232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.824884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.390413Z","time spent":"434.463983ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4278,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-01-27T00:46:29.81902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.385014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-27T00:46:29.82505Z","caller":"traceutil/trace.go:171","msg":"trace[350496711] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:309; }","duration":"396.440697ms","start":"2026-01-27T00:46:29.428599Z","end":"2026-01-27T00:46:29.82504Z","steps":["trace[350496711] 'range keys from in-memory index tree' (duration: 390.029941ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.825093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.428584Z","time spent":"396.499697ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-01-27T00:46:29.819029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.55905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-01-27T00:46:29.82528Z","caller":"traceutil/trace.go:171","msg":"trace[1402073265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:309; }","duration":"308.830635ms","start":"2026-01-27T00:46:29.516442Z","end":"2026-01-27T00:46:29.825272Z","steps":["trace[1402073265] 'range keys from in-memory index tree' (duration: 302.180747ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.825315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.51641Z","time spent":"308.898535ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-01-27T00:46:29.819069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.622413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5259"} {"level":"info","ts":"2026-01-27T00:46:29.825539Z","caller":"traceutil/trace.go:171","msg":"trace[86565541] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:309; }","duration":"435.088319ms","start":"2026-01-27T00:46:29.390443Z","end":"2026-01-27T00:46:29.825531Z","steps":["trace[86565541] 'range keys from in-memory index tree' (duration: 428.22044ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.825597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.390432Z","time spent":"435.157899ms","remote":"127.0.0.1:47280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5281,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-01-27T00:46:29.819077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.402169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-27T00:46:29.825775Z","caller":"traceutil/trace.go:171","msg":"trace[1372351708] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:309; }","duration":"209.115307ms","start":"2026-01-27T00:46:29.61665Z","end":"2026-01-27T00:46:29.825765Z","steps":["trace[1372351708] 'range keys from in-memory index tree' (duration: 202.023106ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.819085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.52462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-01-27T00:46:29.825941Z","caller":"traceutil/trace.go:171","msg":"trace[1032541383] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:309; }","duration":"358.40688ms","start":"2026-01-27T00:46:29.467526Z","end":"2026-01-27T00:46:29.825933Z","steps":["trace[1032541383] 'range keys from in-memory index tree' (duration: 351.103767ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.825979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:46:29.467499Z","time spent":"358.472501ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-01-27T00:46:29.819094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.56585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-27T00:46:29.826194Z","caller":"traceutil/trace.go:171","msg":"trace[551530264] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:309; }","duration":"259.675281ms","start":"2026-01-27T00:46:29.566499Z","end":"2026-01-27T00:46:29.826174Z","steps":["trace[551530264] 'range keys from in-memory index tree' (duration: 252.160887ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.819103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.261966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-01-27T00:46:29.826359Z","caller":"traceutil/trace.go:171","msg":"trace[2059216596] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:309; }","duration":"160.536449ms","start":"2026-01-27T00:46:29.665814Z","end":"2026-01-27T00:46:29.82635Z","steps":["trace[2059216596] 'range keys from in-memory index tree' (duration: 152.868073ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:29.818889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.216738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-01-27T00:46:29.826472Z","caller":"traceutil/trace.go:171","msg":"trace[400370807] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:309; }","duration":"109.880114ms","start":"2026-01-27T00:46:29.716574Z","end":"2026-01-27T00:46:29.826455Z","steps":["trace[400370807] 'range keys from in-memory index tree' (duration: 101.978426ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T00:46:35.292295Z","caller":"traceutil/trace.go:171","msg":"trace[2137700469] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"267.753903ms","start":"2026-01-27T00:46:35.024523Z","end":"2026-01-27T00:46:35.292277Z","steps":["trace[2137700469] 'process raft request' (duration: 267.3107ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:46:35.292609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.269032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4115"} {"level":"info","ts":"2026-01-27T00:46:35.292632Z","caller":"traceutil/trace.go:171","msg":"trace[1841717176] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:365; }","duration":"195.357214ms","start":"2026-01-27T00:46:35.097268Z","end":"2026-01-27T00:46:35.292626Z","steps":["trace[1841717176] 'agreement among raft nodes before linearized reading' (duration: 195.298963ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T00:46:35.292527Z","caller":"traceutil/trace.go:171","msg":"trace[108790530] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"195.192122ms","start":"2026-01-27T00:46:35.097326Z","end":"2026-01-27T00:46:35.292518Z","steps":["trace[108790530] 'read index received' (duration: 194.558347ms)","trace[108790530] 'applied index is now lower than readState.Index' (duration: 174.861µs)"],"step_count":2} {"level":"info","ts":"2026-01-27T00:46:35.360937Z","caller":"traceutil/trace.go:171","msg":"trace[1616719073] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"106.820845ms","start":"2026-01-27T00:46:35.25409Z","end":"2026-01-27T00:46:35.360911Z","steps":["trace[1616719073] 'process raft request' (duration: 103.7342ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T00:47:00.339526Z","caller":"traceutil/trace.go:171","msg":"trace[1155455567] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"138.431006ms","start":"2026-01-27T00:47:00.201071Z","end":"2026-01-27T00:47:00.339502Z","steps":["trace[1155455567] 'process raft request' (duration: 138.270885ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T00:47:00.35034Z","caller":"traceutil/trace.go:171","msg":"trace[153047071] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"145.555763ms","start":"2026-01-27T00:47:00.204767Z","end":"2026-01-27T00:47:00.350323Z","steps":["trace[153047071] 'process raft request' (duration: 138.169006ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T00:47:00.350478Z","caller":"traceutil/trace.go:171","msg":"trace[2014275793] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"142.0691ms","start":"2026-01-27T00:47:00.208404Z","end":"2026-01-27T00:47:00.350473Z","steps":["trace[2014275793] 'read index received' (duration: 134.510802ms)","trace[2014275793] 'applied index is now lower than readState.Index' (duration: 7.557838ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T00:47:00.350577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.156831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-27T00:47:00.350599Z","caller":"traceutil/trace.go:171","msg":"trace[1735186351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"142.216401ms","start":"2026-01-27T00:47:00.208377Z","end":"2026-01-27T00:47:00.350593Z","steps":["trace[1735186351] 'agreement among raft nodes before linearized reading' (duration: 142.11777ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:47:00.87526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217445934374019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-27T00:47:01.096185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.330181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-27T00:47:01.096275Z","caller":"traceutil/trace.go:171","msg":"trace[2105573317] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"721.2389ms","start":"2026-01-27T00:47:00.37502Z","end":"2026-01-27T00:47:01.096259Z","steps":["trace[2105573317] 'read index received' (duration: 542.317805ms)","trace[2105573317] 'applied index is now lower than readState.Index' (duration: 178.920365ms)"],"step_count":2} {"level":"info","ts":"2026-01-27T00:47:01.096434Z","caller":"traceutil/trace.go:171","msg":"trace[1736793046] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"731.949989ms","start":"2026-01-27T00:47:00.364477Z","end":"2026-01-27T00:47:01.096427Z","steps":["trace[1736793046] 'process raft request' (duration: 552.919563ms)","trace[1736793046] 'compare' (duration: 178.250871ms)"],"step_count":2} {"level":"warn","ts":"2026-01-27T00:47:01.096469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:47:00.364461Z","time spent":"731.98878ms","remote":"127.0.0.1:47380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-27T00:47:01.096673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.261669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-27T00:47:01.096694Z","caller":"traceutil/trace.go:171","msg":"trace[750128571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"369.304319ms","start":"2026-01-27T00:47:00.72738Z","end":"2026-01-27T00:47:01.096684Z","steps":["trace[750128571] 'agreement among raft nodes before linearized reading' (duration: 369.273499ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:47:01.096724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:47:00.727364Z","time spent":"369.355769ms","remote":"127.0.0.1:47066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-27T00:47:01.096706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.647503ms","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-01-27T00:47:01.096788Z","caller":"traceutil/trace.go:171","msg":"trace[1830663303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"721.775074ms","start":"2026-01-27T00:47:00.374991Z","end":"2026-01-27T00:47:01.096766Z","steps":["trace[1830663303] 'agreement among raft nodes before linearized reading' (duration: 721.599222ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:47:01.096811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.168042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-01-27T00:47:01.096823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:47:00.374979Z","time spent":"721.837514ms","remote":"127.0.0.1:47380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-27T00:47:01.096834Z","caller":"traceutil/trace.go:171","msg":"trace[270578175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"320.219172ms","start":"2026-01-27T00:47:00.776609Z","end":"2026-01-27T00:47:01.096828Z","steps":["trace[270578175] 'agreement among raft nodes before linearized reading' (duration: 320.182962ms)"],"step_count":1} {"level":"warn","ts":"2026-01-27T00:47:01.096922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-27T00:47:00.776592Z","time spent":"320.325892ms","remote":"127.0.0.1:47380","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-01-27T00:48:27.13076Z","caller":"traceutil/trace.go:171","msg":"trace[1850114170] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"138.183787ms","start":"2026-01-27T00:48:26.992552Z","end":"2026-01-27T00:48:27.130736Z","steps":["trace[1850114170] 'process raft request' (duration: 138.076185ms)"],"step_count":1} {"level":"info","ts":"2026-01-27T00:48:27.232621Z","caller":"traceutil/trace.go:171","msg":"trace[474231162] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"128.002963ms","start":"2026-01-27T00:48:27.104595Z","end":"2026-01-27T00:48:27.232598Z","steps":["trace[474231162] 'process raft request' (duration: 127.824519ms)"],"step_count":1}