{"level":"warn","ts":"2026-02-10T21:27:16.005905Z","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-10T21:27:16.006293Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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://162.253.55.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-10T21:27:16.006468Z","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-10T21:27:16.006536Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-10T21:27:16.006606Z","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-10T21:27:16.007532Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-02-10T21:27:16.007721Z","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://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-10T21:27:16.012225Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.087351ms"} {"level":"info","ts":"2026-02-10T21:27:16.037026Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-02-10T21:27:16.037268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T21:27:16.037354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-02-10T21:27:16.037409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T21:27:16.037445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-02-10T21:27:16.03761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-02-10T21:27:16.048936Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T21:27:16.05314Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T21:27:16.056379Z","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-10T21:27:16.062135Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T21:27:16.062971Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T21:27:16.063176Z","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-10T21:27:16.063254Z","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-10T21:27:16.063318Z","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-10T21:27:16.064624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-02-10T21:27:16.06478Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-10T21:27:16.074325Z","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-10T21:27:16.07448Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-10T21:27:16.075156Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-10T21:27:16.077271Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T21:27:16.077367Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T21:27:16.639769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T21:27:16.639837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T21:27:16.639859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-02-10T21:27:16.639874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-02-10T21:27:16.639881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-10T21:27:16.639892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-02-10T21:27:16.639901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-10T21:27:16.648713Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:27:16.649024Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T21:27:16.649184Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:27:16.649674Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:27:16.654455Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T21:27:16.654531Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T21:27:16.670255Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T21:27:16.670386Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:27:16.670458Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:27:16.670486Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:27:16.671656Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-02-10T21:27:27.266008Z","caller":"traceutil/trace.go:171","msg":"trace[689581694] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"250.213897ms","start":"2026-02-10T21:27:27.015777Z","end":"2026-02-10T21:27:27.265991Z","steps":["trace[689581694] 'read index received' (duration: 229.714838ms)","trace[689581694] 'applied index is now lower than readState.Index' (duration: 20.488299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:27.266701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.572507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-10t1lg\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-02-10T21:27:27.266754Z","caller":"traceutil/trace.go:171","msg":"trace[2073008586] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-10t1lg; range_end:; response_count:1; response_revision:354; }","duration":"196.651978ms","start":"2026-02-10T21:27:27.070092Z","end":"2026-02-10T21:27:27.266744Z","steps":["trace[2073008586] 'agreement among raft nodes before linearized reading' (duration: 196.562436ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:27.266899Z","caller":"traceutil/trace.go:171","msg":"trace[1625590136] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"273.295386ms","start":"2026-02-10T21:27:26.993594Z","end":"2026-02-10T21:27:27.266889Z","steps":["trace[1625590136] 'process raft request' (duration: 251.960904ms)","trace[1625590136] 'compare' (duration: 20.262855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:27.266977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.209872ms","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-02-10T21:27:27.266994Z","caller":"traceutil/trace.go:171","msg":"trace[1908552018] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:354; }","duration":"251.291373ms","start":"2026-02-10T21:27:27.015697Z","end":"2026-02-10T21:27:27.266989Z","steps":["trace[1908552018] 'agreement among raft nodes before linearized reading' (duration: 251.253553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.267254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.210968ms","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-02-10T21:27:27.267275Z","caller":"traceutil/trace.go:171","msg":"trace[1019492494] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:354; }","duration":"103.236679ms","start":"2026-02-10T21:27:27.164032Z","end":"2026-02-10T21:27:27.267268Z","steps":["trace[1019492494] 'agreement among raft nodes before linearized reading' (duration: 103.192768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.267367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.257908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-10T21:27:27.267386Z","caller":"traceutil/trace.go:171","msg":"trace[1932779460] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:354; }","duration":"152.297858ms","start":"2026-02-10T21:27:27.115081Z","end":"2026-02-10T21:27:27.267379Z","steps":["trace[1932779460] 'agreement among raft nodes before linearized reading' (duration: 152.263338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.268221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.231842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-10T21:27:27.268252Z","caller":"traceutil/trace.go:171","msg":"trace[31779890] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:354; }","duration":"198.278923ms","start":"2026-02-10T21:27:27.069964Z","end":"2026-02-10T21:27:27.268243Z","steps":["trace[31779890] 'agreement among raft nodes before linearized reading' (duration: 198.195282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.268605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.560636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-10T21:27:27.268635Z","caller":"traceutil/trace.go:171","msg":"trace[873834529] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:354; }","duration":"198.603777ms","start":"2026-02-10T21:27:27.070024Z","end":"2026-02-10T21:27:27.268628Z","steps":["trace[873834529] 'agreement among raft nodes before linearized reading' (duration: 198.533186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.270181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.16091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"info","ts":"2026-02-10T21:27:27.270212Z","caller":"traceutil/trace.go:171","msg":"trace[535702424] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:354; }","duration":"200.210112ms","start":"2026-02-10T21:27:27.069995Z","end":"2026-02-10T21:27:27.270205Z","steps":["trace[535702424] 'agreement among raft nodes before linearized reading' (duration: 200.132571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.270826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.132646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-10T21:27:27.270959Z","caller":"traceutil/trace.go:171","msg":"trace[781828738] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:354; }","duration":"201.286038ms","start":"2026-02-10T21:27:27.069661Z","end":"2026-02-10T21:27:27.270947Z","steps":["trace[781828738] 'agreement among raft nodes before linearized reading' (duration: 201.133636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.271228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.499712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4597"} {"level":"info","ts":"2026-02-10T21:27:27.271258Z","caller":"traceutil/trace.go:171","msg":"trace[1400539069] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:354; }","duration":"201.545572ms","start":"2026-02-10T21:27:27.069706Z","end":"2026-02-10T21:27:27.271251Z","steps":["trace[1400539069] 'agreement among raft nodes before linearized reading' (duration: 201.452021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.271733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.101231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-10T21:27:27.271763Z","caller":"traceutil/trace.go:171","msg":"trace[298535459] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:354; }","duration":"202.146752ms","start":"2026-02-10T21:27:27.069609Z","end":"2026-02-10T21:27:27.271755Z","steps":["trace[298535459] 'agreement among raft nodes before linearized reading' (duration: 202.079731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:27.272027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.218371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:27:27.272058Z","caller":"traceutil/trace.go:171","msg":"trace[1607015477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:354; }","duration":"235.285481ms","start":"2026-02-10T21:27:27.036765Z","end":"2026-02-10T21:27:27.272051Z","steps":["trace[1607015477] 'agreement among raft nodes before linearized reading' (duration: 229.846349ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:48.970006Z","caller":"traceutil/trace.go:171","msg":"trace[1503644478] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"186.233467ms","start":"2026-02-10T21:27:48.783739Z","end":"2026-02-10T21:27:48.969973Z","steps":["trace[1503644478] 'process raft request' (duration: 186.101275ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:49.1174Z","caller":"traceutil/trace.go:171","msg":"trace[178520777] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"189.874496ms","start":"2026-02-10T21:27:48.927505Z","end":"2026-02-10T21:27:49.117379Z","steps":["trace[178520777] 'read index received' (duration: 42.278319ms)","trace[178520777] 'applied index is now lower than readState.Index' (duration: 147.594787ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:27:49.117567Z","caller":"traceutil/trace.go:171","msg":"trace[496491614] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"333.125602ms","start":"2026-02-10T21:27:48.784412Z","end":"2026-02-10T21:27:49.117538Z","steps":["trace[496491614] 'process raft request' (duration: 318.053924ms)","trace[496491614] 'compare' (duration: 14.682841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:49.117602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.07543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T21:27:49.117634Z","caller":"traceutil/trace.go:171","msg":"trace[971393571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"190.164981ms","start":"2026-02-10T21:27:48.927459Z","end":"2026-02-10T21:27:49.117624Z","steps":["trace[971393571] 'agreement among raft nodes before linearized reading' (duration: 190.007698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:49.117969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:48.784406Z","time spent":"333.221285ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:27:50.551445Z","caller":"traceutil/trace.go:171","msg":"trace[1000236754] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"165.453086ms","start":"2026-02-10T21:27:50.385973Z","end":"2026-02-10T21:27:50.551426Z","steps":["trace[1000236754] 'process raft request' (duration: 165.317003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:55.608296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.171364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:27:55.608449Z","caller":"traceutil/trace.go:171","msg":"trace[820715842] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"455.279762ms","start":"2026-02-10T21:27:55.153137Z","end":"2026-02-10T21:27:55.608417Z","steps":["trace[820715842] 'process raft request' (duration: 272.563799ms)","trace[820715842] 'compare' (duration: 181.840577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:27:55.608555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:27:55.153123Z","time spent":"455.380683ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:08.518669Z","caller":"traceutil/trace.go:171","msg":"trace[1590554747] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"108.069389ms","start":"2026-02-10T21:28:08.41058Z","end":"2026-02-10T21:28:08.518649Z","steps":["trace[1590554747] 'process raft request' (duration: 106.675093ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:13.258357Z","caller":"traceutil/trace.go:171","msg":"trace[1757636348] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"162.489321ms","start":"2026-02-10T21:28:13.095845Z","end":"2026-02-10T21:28:13.258334Z","steps":["trace[1757636348] 'process raft request' (duration: 162.364819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:13.745579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.283883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:28:13.745717Z","caller":"traceutil/trace.go:171","msg":"trace[508182683] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"379.766671ms","start":"2026-02-10T21:28:13.36593Z","end":"2026-02-10T21:28:13.745697Z","steps":["trace[508182683] 'read index received' (duration: 95.164372ms)","trace[508182683] 'applied index is now lower than readState.Index' (duration: 284.600918ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:13.745901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.983054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T21:28:13.745929Z","caller":"traceutil/trace.go:171","msg":"trace[899973976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"380.050625ms","start":"2026-02-10T21:28:13.365868Z","end":"2026-02-10T21:28:13.745919Z","steps":["trace[899973976] 'agreement among raft nodes before linearized reading' (duration: 379.881282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:13.745966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:13.365841Z","time spent":"380.113957ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T21:28:13.746242Z","caller":"traceutil/trace.go:171","msg":"trace[113808990] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"566.863346ms","start":"2026-02-10T21:28:13.179343Z","end":"2026-02-10T21:28:13.746207Z","steps":["trace[113808990] 'process raft request' (duration: 281.822118ms)","trace[113808990] 'compare' (duration: 283.991228ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:13.746304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:13.179315Z","time spent":"566.956947ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:14.240742Z","caller":"traceutil/trace.go:171","msg":"trace[1089852397] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"440.799804ms","start":"2026-02-10T21:28:13.799923Z","end":"2026-02-10T21:28:14.240722Z","steps":["trace[1089852397] 'read index received' (duration: 440.599961ms)","trace[1089852397] 'applied index is now lower than readState.Index' (duration: 199.083µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:14.241044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.730012ms","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-10T21:28:14.241069Z","caller":"traceutil/trace.go:171","msg":"trace[836179869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"397.801563ms","start":"2026-02-10T21:28:13.843261Z","end":"2026-02-10T21:28:14.241062Z","steps":["trace[836179869] 'agreement among raft nodes before linearized reading' (duration: 397.6859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:14.241096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:13.843246Z","time spent":"397.842524ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T21:28:14.241145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.188241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-10T21:28:14.241229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.696373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:28:14.241254Z","caller":"traceutil/trace.go:171","msg":"trace[268548535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"213.755785ms","start":"2026-02-10T21:28:14.02749Z","end":"2026-02-10T21:28:14.241246Z","steps":["trace[268548535] 'agreement among raft nodes before linearized reading' (duration: 213.719864ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:14.241241Z","caller":"traceutil/trace.go:171","msg":"trace[1931435730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"441.348804ms","start":"2026-02-10T21:28:13.79987Z","end":"2026-02-10T21:28:14.241219Z","steps":["trace[1931435730] 'agreement among raft nodes before linearized reading' (duration: 440.961497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:14.241325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:13.799845Z","time spent":"441.438866ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T21:28:14.241535Z","caller":"traceutil/trace.go:171","msg":"trace[1744849036] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"488.694176ms","start":"2026-02-10T21:28:13.752832Z","end":"2026-02-10T21:28:14.241526Z","steps":["trace[1744849036] 'process raft request' (duration: 487.749079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:14.241612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:13.752815Z","time spent":"488.742477ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:28:14.504501Z","caller":"traceutil/trace.go:171","msg":"trace[757797199] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"253.861982ms","start":"2026-02-10T21:28:14.250609Z","end":"2026-02-10T21:28:14.504471Z","steps":["trace[757797199] 'process raft request' (duration: 234.467085ms)","trace[757797199] 'compare' (duration: 19.267995ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:28:36.73849Z","caller":"traceutil/trace.go:171","msg":"trace[985999360] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"114.769172ms","start":"2026-02-10T21:28:36.623697Z","end":"2026-02-10T21:28:36.738466Z","steps":["trace[985999360] 'process raft request' (duration: 114.62186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:36.999883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.63585ms","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-10T21:28:36.999954Z","caller":"traceutil/trace.go:171","msg":"trace[1217472362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"164.774402ms","start":"2026-02-10T21:28:36.835163Z","end":"2026-02-10T21:28:36.999938Z","steps":["trace[1217472362] 'range keys from in-memory index tree' (duration: 164.459547ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:37.590481Z","caller":"traceutil/trace.go:171","msg":"trace[159590433] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"238.862687ms","start":"2026-02-10T21:28:37.351597Z","end":"2026-02-10T21:28:37.590459Z","steps":["trace[159590433] 'process raft request' (duration: 238.418169ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:38.956469Z","caller":"traceutil/trace.go:171","msg":"trace[400160436] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"210.077127ms","start":"2026-02-10T21:28:38.746366Z","end":"2026-02-10T21:28:38.956443Z","steps":["trace[400160436] 'process raft request' (duration: 209.899704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:39.211245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.378232ms","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-10T21:28:39.211924Z","caller":"traceutil/trace.go:171","msg":"trace[212219461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"155.061424ms","start":"2026-02-10T21:28:39.056792Z","end":"2026-02-10T21:28:39.211854Z","steps":["trace[212219461] 'range keys from in-memory index tree' (duration: 154.127818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:39.21169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.09656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:28:39.213596Z","caller":"traceutil/trace.go:171","msg":"trace[1674614086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"186.041405ms","start":"2026-02-10T21:28:39.027525Z","end":"2026-02-10T21:28:39.213566Z","steps":["trace[1674614086] 'range keys from in-memory index tree' (duration: 183.975877ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:54.137011Z","caller":"traceutil/trace.go:171","msg":"trace[1565760144] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"111.768626ms","start":"2026-02-10T21:28:54.025219Z","end":"2026-02-10T21:28:54.136988Z","steps":["trace[1565760144] 'read index received' (duration: 111.570562ms)","trace[1565760144] 'applied index is now lower than readState.Index' (duration: 197.403µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:28:54.137126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.895858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:28:54.137149Z","caller":"traceutil/trace.go:171","msg":"trace[402481563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"111.97351ms","start":"2026-02-10T21:28:54.025165Z","end":"2026-02-10T21:28:54.137138Z","steps":["trace[402481563] 'agreement among raft nodes before linearized reading' (duration: 111.913108ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:28:54.137335Z","caller":"traceutil/trace.go:171","msg":"trace[539457043] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"345.025134ms","start":"2026-02-10T21:28:53.792263Z","end":"2026-02-10T21:28:54.137288Z","steps":["trace[539457043] 'process raft request' (duration: 344.583525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:28:54.137526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:28:53.79225Z","time spent":"345.174387ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:29:03.54122Z","caller":"traceutil/trace.go:171","msg":"trace[1680512427] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"121.107336ms","start":"2026-02-10T21:29:03.420089Z","end":"2026-02-10T21:29:03.541197Z","steps":["trace[1680512427] 'process raft request' (duration: 120.968353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:10.471001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.643018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:29:10.471106Z","caller":"traceutil/trace.go:171","msg":"trace[1274269592] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"259.238933ms","start":"2026-02-10T21:29:10.211853Z","end":"2026-02-10T21:29:10.471092Z","steps":["trace[1274269592] 'read index received' (duration: 23.390439ms)","trace[1274269592] 'applied index is now lower than readState.Index' (duration: 235.847504ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:10.471249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.393286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T21:29:10.471267Z","caller":"traceutil/trace.go:171","msg":"trace[1504882901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"259.432787ms","start":"2026-02-10T21:29:10.211828Z","end":"2026-02-10T21:29:10.471261Z","steps":["trace[1504882901] 'agreement among raft nodes before linearized reading' (duration: 259.298194ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:10.471431Z","caller":"traceutil/trace.go:171","msg":"trace[891109770] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"306.745978ms","start":"2026-02-10T21:29:10.164673Z","end":"2026-02-10T21:29:10.471419Z","steps":["trace[891109770] 'process raft request' (duration: 70.596639ms)","trace[891109770] 'compare' (duration: 235.501295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:10.471473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:10.164656Z","time spent":"306.7918ms","remote":"127.0.0.1:43592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:29:11.37763Z","caller":"traceutil/trace.go:171","msg":"trace[1460331306] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"191.828248ms","start":"2026-02-10T21:29:11.185777Z","end":"2026-02-10T21:29:11.377606Z","steps":["trace[1460331306] 'process raft request' (duration: 126.844954ms)","trace[1460331306] 'compare' (duration: 64.76669ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:11.378684Z","caller":"traceutil/trace.go:171","msg":"trace[2015106305] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"161.726351ms","start":"2026-02-10T21:29:11.215821Z","end":"2026-02-10T21:29:11.377548Z","steps":["trace[2015106305] 'read index received' (duration: 96.791488ms)","trace[2015106305] 'applied index is now lower than readState.Index' (duration: 64.933673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:11.379072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.224072ms","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-10T21:29:11.379108Z","caller":"traceutil/trace.go:171","msg":"trace[1268676377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"163.320324ms","start":"2026-02-10T21:29:11.215777Z","end":"2026-02-10T21:29:11.379098Z","steps":["trace[1268676377] 'agreement among raft nodes before linearized reading' (duration: 163.158381ms)"],"step_count":1}