{"level":"warn","ts":"2026-02-05T15:15:05.272269Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T15:15:05.27234Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.242:2380","--initial-cluster=instance=https://199.19.213.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.242: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-02-05T15:15:05.272401Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T15:15:05.272409Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-02-05T15:15:05.272426Z","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-02-05T15:15:05.273137Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"]} {"level":"info","ts":"2026-02-05T15:15:05.273204Z","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.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.242: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-02-05T15:15:05.27734Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.959893ms"} {"level":"info","ts":"2026-02-05T15:15:05.291576Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ad427454a89beddc","cluster-id":"edc6037504b39883"} {"level":"info","ts":"2026-02-05T15:15:05.292086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T15:15:05.292114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 0"} {"level":"info","ts":"2026-02-05T15:15:05.292302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ad427454a89beddc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T15:15:05.292319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 1"} {"level":"info","ts":"2026-02-05T15:15:05.292449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"warn","ts":"2026-02-05T15:15:05.30716Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T15:15:05.313371Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T15:15:05.327483Z","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-02-05T15:15:05.351316Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ad427454a89beddc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T15:15:05.351451Z","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-02-05T15:15:05.351572Z","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-02-05T15:15:05.351583Z","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-02-05T15:15:05.351975Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ad427454a89beddc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T15:15:05.359276Z","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-02-05T15:15:05.359398Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-02-05T15:15:05.359414Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-02-05T15:15:05.360033Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ad427454a89beddc","initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T15:15:05.36006Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T15:15:05.368285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"info","ts":"2026-02-05T15:15:05.368407Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","added-peer-id":"ad427454a89beddc","added-peer-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-02-05T15:15:05.893715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T15:15:05.893886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T15:15:05.893956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgPreVoteResp from ad427454a89beddc at term 1"} {"level":"info","ts":"2026-02-05T15:15:05.89403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became candidate at term 2"} {"level":"info","ts":"2026-02-05T15:15:05.894069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgVoteResp from ad427454a89beddc at term 2"} {"level":"info","ts":"2026-02-05T15:15:05.894128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became leader at term 2"} {"level":"info","ts":"2026-02-05T15:15:05.894173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ad427454a89beddc elected leader ad427454a89beddc at term 2"} {"level":"info","ts":"2026-02-05T15:15:05.906667Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ad427454a89beddc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.242:2379]}","request-path":"/0/members/ad427454a89beddc/attributes","cluster-id":"edc6037504b39883","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T15:15:05.906807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T15:15:05.906792Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T15:15:05.907532Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:15:05.910184Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T15:15:05.915063Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T15:15:05.916583Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T15:15:05.915315Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.242:2379"} {"level":"info","ts":"2026-02-05T15:15:05.966806Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:15:05.972103Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:15:05.972788Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-05T15:15:10.702082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139746004841909752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:15:11.138822Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.142939645s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T15:15:11.13912Z","caller":"traceutil/trace.go:171","msg":"trace[1032111567] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:216; }","duration":"937.368616ms","start":"2026-02-05T15:15:10.201734Z","end":"2026-02-05T15:15:11.139102Z","steps":["trace[1032111567] 'read index received' (duration: 937.211923ms)","trace[1032111567] 'applied index is now lower than readState.Index' (duration: 156.083µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:15:11.139316Z","caller":"traceutil/trace.go:171","msg":"trace[1199307193] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"1.143516185s","start":"2026-02-05T15:15:09.995793Z","end":"2026-02-05T15:15:11.139309Z","steps":["trace[1199307193] 'process raft request' (duration: 1.143212249s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:11.139651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:09.995775Z","time spent":"1.143557335s","remote":"127.0.0.1:60280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3383,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:15:11.139769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.036938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:11.139833Z","caller":"traceutil/trace.go:171","msg":"trace[1318189027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:212; }","duration":"938.1515ms","start":"2026-02-05T15:15:10.201673Z","end":"2026-02-05T15:15:11.139825Z","steps":["trace[1318189027] 'agreement among raft nodes before linearized reading' (duration: 938.076299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:11.13985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:10.201629Z","time spent":"938.216471ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T15:15:11.482089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.109086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:15:11.482336Z","caller":"traceutil/trace.go:171","msg":"trace[1875987894] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"331.375749ms","start":"2026-02-05T15:15:11.150935Z","end":"2026-02-05T15:15:11.482311Z","steps":["trace[1875987894] 'process raft request' (duration: 331.284797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:11.482398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:11.150927Z","time spent":"331.44678ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T15:15:11.48264Z","caller":"traceutil/trace.go:171","msg":"trace[1998823385] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"331.775777ms","start":"2026-02-05T15:15:11.15085Z","end":"2026-02-05T15:15:11.482626Z","steps":["trace[1998823385] 'read index received' (duration: 145.606791ms)","trace[1998823385] 'applied index is now lower than readState.Index' (duration: 186.167746ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:15:11.482621Z","caller":"traceutil/trace.go:171","msg":"trace[1639765898] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"332.253766ms","start":"2026-02-05T15:15:11.150302Z","end":"2026-02-05T15:15:11.482556Z","steps":["trace[1639765898] 'process raft request' (duration: 146.224322ms)","trace[1639765898] 'compare' (duration: 184.76845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:11.482771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.90489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-pcdw6j\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:11.482806Z","caller":"traceutil/trace.go:171","msg":"trace[2105765180] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-pcdw6j; range_end:; response_count:0; response_revision:214; }","duration":"331.9786ms","start":"2026-02-05T15:15:11.150815Z","end":"2026-02-05T15:15:11.482794Z","steps":["trace[2105765180] 'agreement among raft nodes before linearized reading' (duration: 331.849958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:11.48282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:11.150273Z","time spent":"332.461889ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T15:15:11.482837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:11.150796Z","time spent":"332.034652ms","remote":"127.0.0.1:60196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-pcdw6j\" "} {"level":"info","ts":"2026-02-05T15:15:46.038414Z","caller":"traceutil/trace.go:171","msg":"trace[1496499324] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"328.781565ms","start":"2026-02-05T15:15:45.709602Z","end":"2026-02-05T15:15:46.038384Z","steps":["trace[1496499324] 'process raft request' (duration: 328.606462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:46.038576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:45.709584Z","time spent":"328.907867ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:15:46.113049Z","caller":"traceutil/trace.go:171","msg":"trace[525519925] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"291.548207ms","start":"2026-02-05T15:15:45.82147Z","end":"2026-02-05T15:15:46.113018Z","steps":["trace[525519925] 'read index received' (duration: 216.911366ms)","trace[525519925] 'applied index is now lower than readState.Index' (duration: 74.635401ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:15:46.113202Z","caller":"traceutil/trace.go:171","msg":"trace[1744671763] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"401.891259ms","start":"2026-02-05T15:15:45.711301Z","end":"2026-02-05T15:15:46.113193Z","steps":["trace[1744671763] 'process raft request' (duration: 376.89164ms)","trace[1744671763] 'compare' (duration: 24.691482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:46.113325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:45.711293Z","time spent":"401.935359ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:15:46.113469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.938244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:46.113552Z","caller":"traceutil/trace.go:171","msg":"trace[550076792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"164.067247ms","start":"2026-02-05T15:15:45.949462Z","end":"2026-02-05T15:15:46.11353Z","steps":["trace[550076792] 'agreement among raft nodes before linearized reading' (duration: 163.944924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:46.113951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.476293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5g9ht\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-05T15:15:46.113997Z","caller":"traceutil/trace.go:171","msg":"trace[267506541] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5g9ht; range_end:; response_count:1; response_revision:522; }","duration":"292.540024ms","start":"2026-02-05T15:15:45.821435Z","end":"2026-02-05T15:15:46.113975Z","steps":["trace[267506541] 'agreement among raft nodes before linearized reading' (duration: 292.393262ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:47.082765Z","caller":"traceutil/trace.go:171","msg":"trace[1292359029] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"135.821018ms","start":"2026-02-05T15:15:46.946928Z","end":"2026-02-05T15:15:47.082749Z","steps":["trace[1292359029] 'read index received' (duration: 126.382869ms)","trace[1292359029] 'applied index is now lower than readState.Index' (duration: 9.437549ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:47.082891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.948821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:47.082909Z","caller":"traceutil/trace.go:171","msg":"trace[984449784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"136.006282ms","start":"2026-02-05T15:15:46.946898Z","end":"2026-02-05T15:15:47.082904Z","steps":["trace[984449784] 'agreement among raft nodes before linearized reading' (duration: 135.951711ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:47.08308Z","caller":"traceutil/trace.go:171","msg":"trace[1176721496] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"944.555089ms","start":"2026-02-05T15:15:46.138519Z","end":"2026-02-05T15:15:47.083074Z","steps":["trace[1176721496] 'process raft request' (duration: 934.842764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:47.083127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:46.138512Z","time spent":"944.58031ms","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:15:47.083229Z","caller":"traceutil/trace.go:171","msg":"trace[1114943098] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"941.343392ms","start":"2026-02-05T15:15:46.141881Z","end":"2026-02-05T15:15:47.083224Z","steps":["trace[1114943098] 'process raft request' (duration: 940.828743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:47.083253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:46.141851Z","time spent":"941.388392ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:15:49.395301Z","caller":"traceutil/trace.go:171","msg":"trace[1162172300] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"227.51724ms","start":"2026-02-05T15:15:49.16776Z","end":"2026-02-05T15:15:49.395277Z","steps":["trace[1162172300] 'process raft request' (duration: 227.405968ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:49.401745Z","caller":"traceutil/trace.go:171","msg":"trace[1274025707] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"233.353395ms","start":"2026-02-05T15:15:49.168382Z","end":"2026-02-05T15:15:49.401735Z","steps":["trace[1274025707] 'process raft request' (duration: 233.045679ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:49.401973Z","caller":"traceutil/trace.go:171","msg":"trace[4150899] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"135.672295ms","start":"2026-02-05T15:15:49.266286Z","end":"2026-02-05T15:15:49.401959Z","steps":["trace[4150899] 'process raft request' (duration: 135.248496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:49.402307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.447529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:49.404095Z","caller":"traceutil/trace.go:171","msg":"trace[164701106] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"232.576381ms","start":"2026-02-05T15:15:49.169779Z","end":"2026-02-05T15:15:49.402355Z","steps":["trace[164701106] 'agreement among raft nodes before linearized reading' (duration: 232.438079ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:49.40168Z","caller":"traceutil/trace.go:171","msg":"trace[1445418175] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"231.828867ms","start":"2026-02-05T15:15:49.16983Z","end":"2026-02-05T15:15:49.401659Z","steps":["trace[1445418175] 'read index received' (duration: 225.506014ms)","trace[1445418175] 'applied index is now lower than readState.Index' (duration: 6.322053ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:49.405289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.11491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T15:15:49.405373Z","caller":"traceutil/trace.go:171","msg":"trace[1780175897] range","detail":"{range_begin:/registry/masterleases/199.19.213.242; range_end:; response_count:1; response_revision:550; }","duration":"171.016917ms","start":"2026-02-05T15:15:49.234347Z","end":"2026-02-05T15:15:49.405364Z","steps":["trace[1780175897] 'agreement among raft nodes before linearized reading' (duration: 170.11613ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:56.352205Z","caller":"traceutil/trace.go:171","msg":"trace[1604819627] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"105.704636ms","start":"2026-02-05T15:15:56.24648Z","end":"2026-02-05T15:15:56.352185Z","steps":["trace[1604819627] 'process raft request' (duration: 105.385419ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:17:16.04286Z","caller":"traceutil/trace.go:171","msg":"trace[905085766] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"107.036584ms","start":"2026-02-05T15:17:15.935801Z","end":"2026-02-05T15:17:16.042837Z","steps":["trace[905085766] 'read index received' (duration: 106.963483ms)","trace[905085766] 'applied index is now lower than readState.Index' (duration: 72.091µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:17:16.043342Z","caller":"traceutil/trace.go:171","msg":"trace[970222172] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"108.871947ms","start":"2026-02-05T15:17:15.934457Z","end":"2026-02-05T15:17:16.043329Z","steps":["trace[970222172] 'process raft request' (duration: 108.105193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:17:16.043811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.987031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T15:17:16.043981Z","caller":"traceutil/trace.go:171","msg":"trace[899365409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"108.117253ms","start":"2026-02-05T15:17:15.935784Z","end":"2026-02-05T15:17:16.043901Z","steps":["trace[899365409] 'agreement among raft nodes before linearized reading' (duration: 107.906809ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:17:18.319852Z","caller":"traceutil/trace.go:171","msg":"trace[949322760] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"217.824461ms","start":"2026-02-05T15:17:18.102003Z","end":"2026-02-05T15:17:18.319828Z","steps":["trace[949322760] 'read index received' (duration: 217.589886ms)","trace[949322760] 'applied index is now lower than readState.Index' (duration: 233.825µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:17:18.320179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.150806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T15:17:18.320243Z","caller":"traceutil/trace.go:171","msg":"trace[1003014360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"218.299949ms","start":"2026-02-05T15:17:18.101912Z","end":"2026-02-05T15:17:18.320212Z","steps":["trace[1003014360] 'agreement among raft nodes before linearized reading' (duration: 218.046644ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:17:18.320796Z","caller":"traceutil/trace.go:171","msg":"trace[343150642] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"266.948654ms","start":"2026-02-05T15:17:18.053835Z","end":"2026-02-05T15:17:18.320783Z","steps":["trace[343150642] 'process raft request' (duration: 265.835353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:17:27.148691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.146254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:17:27.148869Z","caller":"traceutil/trace.go:171","msg":"trace[1354490074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"199.381809ms","start":"2026-02-05T15:17:26.949468Z","end":"2026-02-05T15:17:27.14885Z","steps":["trace[1354490074] 'range keys from in-memory index tree' (duration: 199.061193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:17:27.148785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.918665ms","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-02-05T15:17:27.149113Z","caller":"traceutil/trace.go:171","msg":"trace[1751895597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"293.216981ms","start":"2026-02-05T15:17:26.855818Z","end":"2026-02-05T15:17:27.149035Z","steps":["trace[1751895597] 'range keys from in-memory index tree' (duration: 292.728872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:17:27.150198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.790355ms","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-02-05T15:17:27.150299Z","caller":"traceutil/trace.go:171","msg":"trace[1567250378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"438.945968ms","start":"2026-02-05T15:17:26.71134Z","end":"2026-02-05T15:17:27.150286Z","steps":["trace[1567250378] 'range keys from in-memory index tree' (duration: 438.607041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:17:27.150389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:17:26.711322Z","time spent":"439.053819ms","remote":"127.0.0.1:60362","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-02-05T15:17:27.150907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.838594ms","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-02-05T15:17:27.150972Z","caller":"traceutil/trace.go:171","msg":"trace[1781451888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"573.904845ms","start":"2026-02-05T15:17:26.577059Z","end":"2026-02-05T15:17:27.150964Z","steps":["trace[1781451888] 'range keys from in-memory index tree' (duration: 573.721981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:17:27.15101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:17:26.576994Z","time spent":"574.008987ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T15:17:27.151591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.011999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T15:17:27.151657Z","caller":"traceutil/trace.go:171","msg":"trace[1376746518] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:989; }","duration":"298.889385ms","start":"2026-02-05T15:17:26.852757Z","end":"2026-02-05T15:17:27.151646Z","steps":["trace[1376746518] 'count revisions from in-memory index tree' (duration: 297.929328ms)"],"step_count":1}