{"level":"warn","ts":"2026-02-17T06:36:16.510053Z","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-17T06:36:16.510139Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-17T06:36:16.511632Z","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-17T06:36:16.511649Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-02-17T06:36:16.511949Z","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-17T06:36:16.512662Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-02-17T06:36:16.514759Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-17T06:36:16.523411Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.229505ms"} {"level":"info","ts":"2026-02-17T06:36:16.550473Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-02-17T06:36:16.550563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T06:36:16.550587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-02-17T06:36:16.550604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T06:36:16.550631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-02-17T06:36:16.550668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-02-17T06:36:16.555226Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T06:36:16.560062Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T06:36:16.561456Z","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-17T06:36:16.565144Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T06:36:16.569203Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T06:36:16.569363Z","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-17T06:36:16.569422Z","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-17T06:36:16.56944Z","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-17T06:36:16.569677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-02-17T06:36:16.570962Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-02-17T06:36:16.574561Z","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-17T06:36:16.57483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T06:36:16.574863Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T06:36:16.575409Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-02-17T06:36:16.579516Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-02-17T06:36:17.552112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T06:36:17.552206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T06:36:17.552243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-02-17T06:36:17.552265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-02-17T06:36:17.55228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-02-17T06:36:17.552297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-02-17T06:36:17.552313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-02-17T06:36:17.558122Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T06:36:17.558222Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T06:36:17.558395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T06:36:17.558542Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T06:36:17.558888Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T06:36:17.558906Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T06:36:17.560353Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-02-17T06:36:17.561129Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T06:36:17.561429Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T06:36:17.561501Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T06:36:17.56153Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-17T06:36:26.238684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.720055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:26.239072Z","caller":"traceutil/trace.go:171","msg":"trace[1158012650] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:330; }","duration":"359.202396ms","start":"2026-02-17T06:36:25.879854Z","end":"2026-02-17T06:36:26.239056Z","steps":["trace[1158012650] 'range keys from in-memory index tree' (duration: 357.210475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:26.239127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:25.879833Z","time spent":"359.283319ms","remote":"127.0.0.1:41754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"warn","ts":"2026-02-17T06:36:26.238867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.252409ms","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-02-17T06:36:26.239276Z","caller":"traceutil/trace.go:171","msg":"trace[1080439324] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:330; }","duration":"158.701761ms","start":"2026-02-17T06:36:26.080566Z","end":"2026-02-17T06:36:26.239267Z","steps":["trace[1080439324] 'range keys from in-memory index tree' (duration: 158.172326ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:36:53.538631Z","caller":"traceutil/trace.go:171","msg":"trace[374666960] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"163.350672ms","start":"2026-02-17T06:36:53.375245Z","end":"2026-02-17T06:36:53.538596Z","steps":["trace[374666960] 'process raft request' (duration: 162.86716ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:36:53.912951Z","caller":"traceutil/trace.go:171","msg":"trace[286949648] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"358.919616ms","start":"2026-02-17T06:36:53.554011Z","end":"2026-02-17T06:36:53.912931Z","steps":["trace[286949648] 'process raft request' (duration: 358.777062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:53.91361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:53.553995Z","time spent":"359.021288ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:37:08.124191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.862482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T06:37:08.124313Z","caller":"traceutil/trace.go:171","msg":"trace[1773663375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:700; }","duration":"110.987985ms","start":"2026-02-17T06:37:08.013276Z","end":"2026-02-17T06:37:08.124264Z","steps":["trace[1773663375] 'range keys from in-memory index tree' (duration: 110.728659ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:37:14.019348Z","caller":"traceutil/trace.go:171","msg":"trace[160218331] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"217.268931ms","start":"2026-02-17T06:37:13.802061Z","end":"2026-02-17T06:37:14.01933Z","steps":["trace[160218331] 'process raft request' (duration: 217.149408ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:37:33.118156Z","caller":"traceutil/trace.go:171","msg":"trace[921277350] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"161.337451ms","start":"2026-02-17T06:37:32.9568Z","end":"2026-02-17T06:37:33.118137Z","steps":["trace[921277350] 'process raft request' (duration: 161.229328ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:37:35.249049Z","caller":"traceutil/trace.go:171","msg":"trace[745638746] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"123.18875ms","start":"2026-02-17T06:37:35.12583Z","end":"2026-02-17T06:37:35.249018Z","steps":["trace[745638746] 'process raft request' (duration: 123.023956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:37:35.616165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.165025ms","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-17T06:37:35.616238Z","caller":"traceutil/trace.go:171","msg":"trace[361164322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"159.285888ms","start":"2026-02-17T06:37:35.456936Z","end":"2026-02-17T06:37:35.616222Z","steps":["trace[361164322] 'range keys from in-memory index tree' (duration: 158.9872ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:37:35.773815Z","caller":"traceutil/trace.go:171","msg":"trace[1059159516] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"153.5487ms","start":"2026-02-17T06:37:35.620244Z","end":"2026-02-17T06:37:35.773793Z","steps":["trace[1059159516] 'process raft request' (duration: 153.422827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:37:52.802295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.12933ms","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-17T06:37:52.802363Z","caller":"traceutil/trace.go:171","msg":"trace[1504567013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:893; }","duration":"174.235183ms","start":"2026-02-17T06:37:52.628115Z","end":"2026-02-17T06:37:52.80235Z","steps":["trace[1504567013] 'range keys from in-memory index tree' (duration: 173.993416ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:37:53.58143Z","caller":"traceutil/trace.go:171","msg":"trace[1064302684] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"215.409892ms","start":"2026-02-17T06:37:53.366007Z","end":"2026-02-17T06:37:53.581416Z","steps":["trace[1064302684] 'process raft request' (duration: 215.318859ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:38:03.743633Z","caller":"traceutil/trace.go:171","msg":"trace[1603421868] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"101.492231ms","start":"2026-02-17T06:38:03.642066Z","end":"2026-02-17T06:38:03.743558Z","steps":["trace[1603421868] 'process raft request' (duration: 101.170072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:04.100407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.382869ms","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-17T06:38:04.100457Z","caller":"traceutil/trace.go:171","msg":"trace[1963403072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"183.468082ms","start":"2026-02-17T06:38:03.916976Z","end":"2026-02-17T06:38:04.100444Z","steps":["trace[1963403072] 'range keys from in-memory index tree' (duration: 183.270325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:04.100604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.493029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:38:04.100654Z","caller":"traceutil/trace.go:171","msg":"trace[1913436602] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:924; }","duration":"207.576412ms","start":"2026-02-17T06:38:03.893065Z","end":"2026-02-17T06:38:04.100641Z","steps":["trace[1913436602] 'count revisions from in-memory index tree' (duration: 207.449038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:04.342527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.839417ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:38:04.342609Z","caller":"traceutil/trace.go:171","msg":"trace[552792804] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:925; }","duration":"139.93001ms","start":"2026-02-17T06:38:04.202666Z","end":"2026-02-17T06:38:04.342596Z","steps":["trace[552792804] 'range keys from in-memory index tree' (duration: 139.826907ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:38:19.946891Z","caller":"traceutil/trace.go:171","msg":"trace[171983822] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"132.88918ms","start":"2026-02-17T06:38:19.813985Z","end":"2026-02-17T06:38:19.946875Z","steps":["trace[171983822] 'process raft request' (duration: 132.799437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:21.232224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.765336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T06:38:21.232298Z","caller":"traceutil/trace.go:171","msg":"trace[1139015508] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1031; }","duration":"146.89023ms","start":"2026-02-17T06:38:21.08539Z","end":"2026-02-17T06:38:21.23228Z","steps":["trace[1139015508] 'range keys from in-memory index tree' (duration: 146.585682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:21.232326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.574558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:38:21.232384Z","caller":"traceutil/trace.go:171","msg":"trace[1731604425] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1031; }","duration":"121.67596ms","start":"2026-02-17T06:38:21.110693Z","end":"2026-02-17T06:38:21.232369Z","steps":["trace[1731604425] 'count revisions from in-memory index tree' (duration: 121.474175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:21.232622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.184478ms","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-17T06:38:21.232658Z","caller":"traceutil/trace.go:171","msg":"trace[1474956486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1031; }","duration":"304.274639ms","start":"2026-02-17T06:38:20.928373Z","end":"2026-02-17T06:38:21.232648Z","steps":["trace[1474956486] 'range keys from in-memory index tree' (duration: 304.096956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:21.232687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:20.928358Z","time spent":"304.320481ms","remote":"127.0.0.1:41838","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-02-17T06:39:12.946332Z","caller":"traceutil/trace.go:171","msg":"trace[83087952] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"258.585171ms","start":"2026-02-17T06:39:12.687723Z","end":"2026-02-17T06:39:12.946309Z","steps":["trace[83087952] 'process raft request' (duration: 258.084628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:14.476556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.487555ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:39:14.47667Z","caller":"traceutil/trace.go:171","msg":"trace[678006240] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1271; }","duration":"273.612118ms","start":"2026-02-17T06:39:14.203041Z","end":"2026-02-17T06:39:14.476653Z","steps":["trace[678006240] 'range keys from in-memory index tree' (duration: 273.476465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:14.48891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192572307s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:39:14.489066Z","caller":"traceutil/trace.go:171","msg":"trace[475875888] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.537685211s","start":"2026-02-17T06:39:12.951371Z","end":"2026-02-17T06:39:14.489056Z","steps":["trace[475875888] 'process raft request' (duration: 333.101304ms)","trace[475875888] 'compare' (duration: 1.190986116s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:39:14.489201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:12.950838Z","time spent":"1.538266286s","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:39:14.489587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371945936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T06:39:14.489734Z","caller":"traceutil/trace.go:171","msg":"trace[1458238012] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"1.371317369s","start":"2026-02-17T06:39:13.11764Z","end":"2026-02-17T06:39:14.488957Z","steps":["trace[1458238012] 'read index received' (duration: 166.815964ms)","trace[1458238012] 'applied index is now lower than readState.Index' (duration: 1.204500595s)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:39:14.490309Z","caller":"traceutil/trace.go:171","msg":"trace[2062443414] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1272; }","duration":"1.372017838s","start":"2026-02-17T06:39:13.117588Z","end":"2026-02-17T06:39:14.489606Z","steps":["trace[2062443414] 'agreement among raft nodes before linearized reading' (duration: 1.371972077s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:14.490339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:13.117568Z","time spent":"1.372763607s","remote":"127.0.0.1:41930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-17T06:39:14.990287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391440115708232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:39:15.491475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391440115708232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:39:15.856195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.134680088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-17T06:39:15.856595Z","caller":"traceutil/trace.go:171","msg":"trace[1230194617] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.135137899s","start":"2026-02-17T06:39:13.721442Z","end":"2026-02-17T06:39:15.85658Z","steps":["trace[1230194617] 'agreement among raft nodes before linearized reading' (duration: 2.134712939s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:15.856635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:13.721424Z","time spent":"2.135203322s","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T06:39:15.992384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391440115708232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:39:16.306901Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.383436093s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T06:39:16.311125Z","caller":"traceutil/trace.go:171","msg":"trace[1821300755] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"2.387742325s","start":"2026-02-17T06:39:13.923363Z","end":"2026-02-17T06:39:16.311105Z","steps":["trace[1821300755] 'process raft request' (duration: 2.384646295s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.311253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:13.923339Z","time spent":"2.387837177s","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:39:16.311891Z","caller":"traceutil/trace.go:171","msg":"trace[1480459153] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"991.142121ms","start":"2026-02-17T06:39:15.320741Z","end":"2026-02-17T06:39:16.311883Z","steps":["trace[1480459153] 'process raft request' (duration: 988.454121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.311956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:15.320724Z","time spent":"991.190983ms","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:39:16.356453Z","caller":"traceutil/trace.go:171","msg":"trace[500937043] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1325; }","duration":"1.866683281s","start":"2026-02-17T06:39:14.489747Z","end":"2026-02-17T06:39:16.35643Z","steps":["trace[500937043] 'read index received' (duration: 1.818272592s)","trace[500937043] 'applied index is now lower than readState.Index' (duration: 48.409309ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:39:16.356984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.92093934s","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-17T06:39:16.357034Z","caller":"traceutil/trace.go:171","msg":"trace[1786614354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"1.921045723s","start":"2026-02-17T06:39:14.435965Z","end":"2026-02-17T06:39:16.35701Z","steps":["trace[1786614354] 'agreement among raft nodes before linearized reading' (duration: 1.920888329s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.357067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:14.435945Z","time spent":"1.921114305s","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T06:39:16.357205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.174629586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:39:16.35723Z","caller":"traceutil/trace.go:171","msg":"trace[363694362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"2.174685788s","start":"2026-02-17T06:39:14.182537Z","end":"2026-02-17T06:39:16.357223Z","steps":["trace[363694362] 'agreement among raft nodes before linearized reading' (duration: 2.174606255s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.357254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:14.182518Z","time spent":"2.174730629s","remote":"127.0.0.1:41838","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":"warn","ts":"2026-02-17T06:39:16.357364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.295363641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-17T06:39:16.357388Z","caller":"traceutil/trace.go:171","msg":"trace[1303286377] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1274; }","duration":"2.295421913s","start":"2026-02-17T06:39:14.061957Z","end":"2026-02-17T06:39:16.357379Z","steps":["trace[1303286377] 'agreement among raft nodes before linearized reading' (duration: 2.295354761s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.357411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:14.06194Z","time spent":"2.295465754s","remote":"127.0.0.1:41664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-17T06:39:16.357597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.846166801s","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-17T06:39:16.357626Z","caller":"traceutil/trace.go:171","msg":"trace[1581391277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"2.846231123s","start":"2026-02-17T06:39:13.511387Z","end":"2026-02-17T06:39:16.357618Z","steps":["trace[1581391277] 'agreement among raft nodes before linearized reading' (duration: 2.846152861s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.35765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:13.511366Z","time spent":"2.846278554s","remote":"127.0.0.1:41838","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":"warn","ts":"2026-02-17T06:39:16.357718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.012670616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:39:16.357753Z","caller":"traceutil/trace.go:171","msg":"trace[285934225] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1274; }","duration":"3.012738457s","start":"2026-02-17T06:39:13.344994Z","end":"2026-02-17T06:39:16.357732Z","steps":["trace[285934225] 'agreement among raft nodes before linearized reading' (duration: 3.012702957s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.357779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:13.34497Z","time spent":"3.012801439s","remote":"127.0.0.1:41866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-17T06:39:16.357952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408012618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-17T06:39:16.35798Z","caller":"traceutil/trace.go:171","msg":"trace[440411393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1274; }","duration":"1.408067149s","start":"2026-02-17T06:39:14.949905Z","end":"2026-02-17T06:39:16.357972Z","steps":["trace[440411393] 'agreement among raft nodes before linearized reading' (duration: 1.407992907s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.358003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:14.949891Z","time spent":"1.40810714s","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T06:39:16.358275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.20185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-17T06:39:16.358302Z","caller":"traceutil/trace.go:171","msg":"trace[623475961] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1274; }","duration":"428.245961ms","start":"2026-02-17T06:39:15.930048Z","end":"2026-02-17T06:39:16.358294Z","steps":["trace[623475961] 'agreement among raft nodes before linearized reading' (duration: 428.166279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.35833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:15.93004Z","time spent":"428.284452ms","remote":"127.0.0.1:41838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-17T06:39:16.358438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.442018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:39:16.358461Z","caller":"traceutil/trace.go:171","msg":"trace[868912187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"500.48037ms","start":"2026-02-17T06:39:15.857973Z","end":"2026-02-17T06:39:16.358454Z","steps":["trace[868912187] 'agreement among raft nodes before linearized reading' (duration: 500.446729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:16.358482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:39:15.857963Z","time spent":"500.514921ms","remote":"127.0.0.1:41548","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-02-17T06:41:21.082289Z","caller":"traceutil/trace.go:171","msg":"trace[853731410] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"159.779469ms","start":"2026-02-17T06:41:20.922491Z","end":"2026-02-17T06:41:21.08227Z","steps":["trace[853731410] 'process raft request' (duration: 132.059407ms)","trace[853731410] 'compare' (duration: 27.584038ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:41:21.082477Z","caller":"traceutil/trace.go:171","msg":"trace[1406945647] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"159.643765ms","start":"2026-02-17T06:41:20.922824Z","end":"2026-02-17T06:41:21.082468Z","steps":["trace[1406945647] 'read index received' (duration: 26.954763ms)","trace[1406945647] 'applied index is now lower than readState.Index' (duration: 132.688382ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:41:21.082709Z","caller":"traceutil/trace.go:171","msg":"trace[1151669868] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"158.940258ms","start":"2026-02-17T06:41:20.923762Z","end":"2026-02-17T06:41:21.082703Z","steps":["trace[1151669868] 'process raft request' (duration: 158.465586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:41:21.08296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.118956ms","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-02-17T06:41:21.082993Z","caller":"traceutil/trace.go:171","msg":"trace[2012644818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1876; }","duration":"160.174427ms","start":"2026-02-17T06:41:20.922804Z","end":"2026-02-17T06:41:21.082979Z","steps":["trace[2012644818] 'agreement among raft nodes before linearized reading' (duration: 159.993353ms)"],"step_count":1}