{"level":"warn","ts":"2026-03-06T18:27:42.322057Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T18:27:42.322138Z","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-03-06T18:27:42.322205Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T18:27:42.322225Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-03-06T18:27:42.322242Z","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-03-06T18:27:42.322756Z","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-03-06T18:27:42.322829Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://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-03-06T18:27:42.326778Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.691494ms"} {"level":"info","ts":"2026-03-06T18:27:42.338371Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-03-06T18:27:42.338455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T18:27:42.33848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-03-06T18:27:42.3385Z","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-03-06T18:27:42.338506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-03-06T18:27:42.338535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-03-06T18:27:42.354776Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T18:27:42.356182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T18:27:42.35737Z","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-03-06T18:27:42.359776Z","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-03-06T18:27:42.359983Z","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-03-06T18:27:42.360113Z","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-03-06T18:27:42.360192Z","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-03-06T18:27:42.3602Z","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-03-06T18:27:42.363374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-03-06T18:27:42.363534Z","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-03-06T18:27:42.364525Z","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-03-06T18:27:42.364799Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-03-06T18:27:42.364838Z","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-03-06T18:27:42.364878Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T18:27:42.364862Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-03-06T18:27:42.839148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T18:27:42.839234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T18:27:42.839257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-03-06T18:27:42.83928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-03-06T18:27:42.839292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-03-06T18:27:42.839307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-03-06T18:27:42.839321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-03-06T18:27:42.885563Z","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-03-06T18:27:42.88586Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T18:27:42.886103Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T18:27:42.886198Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T18:27:42.88626Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T18:27:42.885876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T18:27:42.887356Z","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-03-06T18:27:42.887621Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T18:27:42.887689Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T18:27:42.892874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-03-06T18:27:42.893124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T18:28:06.143202Z","caller":"traceutil/trace.go:171","msg":"trace[1227149446] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"111.949763ms","start":"2026-03-06T18:28:06.03122Z","end":"2026-03-06T18:28:06.14317Z","steps":["trace[1227149446] 'read index received' (duration: 111.785582ms)","trace[1227149446] 'applied index is now lower than readState.Index' (duration: 163.051µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:28:06.14337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.110904ms","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-03-06T18:28:06.143437Z","caller":"traceutil/trace.go:171","msg":"trace[644147638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"112.240465ms","start":"2026-03-06T18:28:06.031184Z","end":"2026-03-06T18:28:06.143425Z","steps":["trace[644147638] 'agreement among raft nodes before linearized reading' (duration: 112.108684ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:06.143543Z","caller":"traceutil/trace.go:171","msg":"trace[1165424314] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"153.35219ms","start":"2026-03-06T18:28:05.990161Z","end":"2026-03-06T18:28:06.143513Z","steps":["trace[1165424314] 'process raft request' (duration: 152.867696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:09.813339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.276201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-06T18:28:09.813455Z","caller":"traceutil/trace.go:171","msg":"trace[1497925135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:461; }","duration":"152.407887ms","start":"2026-03-06T18:28:09.66102Z","end":"2026-03-06T18:28:09.813428Z","steps":["trace[1497925135] 'range keys from in-memory index tree' (duration: 152.143386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:09.813567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.064712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-03-06T18:28:09.813693Z","caller":"traceutil/trace.go:171","msg":"trace[1567907130] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:461; }","duration":"208.205468ms","start":"2026-03-06T18:28:09.605464Z","end":"2026-03-06T18:28:09.81367Z","steps":["trace[1567907130] 'range keys from in-memory index tree' (duration: 207.741619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:12.505515Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541103913698834,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T18:28:12.515888Z","caller":"traceutil/trace.go:171","msg":"trace[362221318] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"510.999684ms","start":"2026-03-06T18:28:12.004864Z","end":"2026-03-06T18:28:12.515863Z","steps":["trace[362221318] 'read index received' (duration: 510.822586ms)","trace[362221318] 'applied index is now lower than readState.Index' (duration: 176.338µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:28:12.515955Z","caller":"traceutil/trace.go:171","msg":"trace[1173702801] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"688.227953ms","start":"2026-03-06T18:28:11.827695Z","end":"2026-03-06T18:28:12.515923Z","steps":["trace[1173702801] 'process raft request' (duration: 688.045396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:12.516013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.13259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:28:12.516044Z","caller":"traceutil/trace.go:171","msg":"trace[560242320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"511.226754ms","start":"2026-03-06T18:28:12.004809Z","end":"2026-03-06T18:28:12.516035Z","steps":["trace[560242320] 'agreement among raft nodes before linearized reading' (duration: 511.151191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:12.516073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:12.004786Z","time spent":"511.280736ms","remote":"127.0.0.1:46122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:28:12.516177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.076056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-06T18:28:12.516216Z","caller":"traceutil/trace.go:171","msg":"trace[924832567] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:468; }","duration":"347.16093ms","start":"2026-03-06T18:28:12.169041Z","end":"2026-03-06T18:28:12.516202Z","steps":["trace[924832567] 'agreement among raft nodes before linearized reading' (duration: 347.077916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:12.516294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:12.169017Z","time spent":"347.223132ms","remote":"127.0.0.1:46320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4399,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-06T18:28:12.51624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.024927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-06T18:28:12.516378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.766605ms","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-03-06T18:28:12.516423Z","caller":"traceutil/trace.go:171","msg":"trace[1982198573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"350.296029ms","start":"2026-03-06T18:28:12.166099Z","end":"2026-03-06T18:28:12.516395Z","steps":["trace[1982198573] 'agreement among raft nodes before linearized reading' (duration: 349.954565ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:12.516494Z","caller":"traceutil/trace.go:171","msg":"trace[2126967281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"349.000705ms","start":"2026-03-06T18:28:12.167469Z","end":"2026-03-06T18:28:12.51647Z","steps":["trace[2126967281] 'agreement among raft nodes before linearized reading' (duration: 348.678631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:12.516506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:12.166045Z","time spent":"350.450225ms","remote":"127.0.0.1:46420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:28:12.516547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:12.167392Z","time spent":"349.145201ms","remote":"127.0.0.1:46420","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-03-06T18:28:12.51751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:11.827672Z","time spent":"688.331207ms","remote":"127.0.0.1:46420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:28:14.39355Z","caller":"traceutil/trace.go:171","msg":"trace[1626865892] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"841.768015ms","start":"2026-03-06T18:28:13.551757Z","end":"2026-03-06T18:28:14.393525Z","steps":["trace[1626865892] 'process raft request' (duration: 841.598438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:14.39372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:13.551725Z","time spent":"841.910301ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T18:28:14.398914Z","caller":"traceutil/trace.go:171","msg":"trace[25242907] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:513; }","duration":"448.502465ms","start":"2026-03-06T18:28:13.950399Z","end":"2026-03-06T18:28:14.398901Z","steps":["trace[25242907] 'read index received' (duration: 443.012235ms)","trace[25242907] 'applied index is now lower than readState.Index' (duration: 5.48937ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:28:14.399041Z","caller":"traceutil/trace.go:171","msg":"trace[11157219] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"648.109145ms","start":"2026-03-06T18:28:13.750925Z","end":"2026-03-06T18:28:14.399034Z","steps":["trace[11157219] 'process raft request' (duration: 647.897126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:14.399089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:13.750898Z","time spent":"648.164397ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T18:28:14.399176Z","caller":"traceutil/trace.go:171","msg":"trace[463564472] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"243.649143ms","start":"2026-03-06T18:28:14.15552Z","end":"2026-03-06T18:28:14.39917Z","steps":["trace[463564472] 'process raft request' (duration: 243.356831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:14.399409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.437452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:28:14.399443Z","caller":"traceutil/trace.go:171","msg":"trace[1610221043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"394.503325ms","start":"2026-03-06T18:28:14.004931Z","end":"2026-03-06T18:28:14.399434Z","steps":["trace[1610221043] 'agreement among raft nodes before linearized reading' (duration: 394.457363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:14.399462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:14.004909Z","time spent":"394.549717ms","remote":"127.0.0.1:46134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T18:28:14.399289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.892691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T18:28:14.400781Z","caller":"traceutil/trace.go:171","msg":"trace[745484739] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:501; }","duration":"450.424303ms","start":"2026-03-06T18:28:13.950344Z","end":"2026-03-06T18:28:14.400768Z","steps":["trace[745484739] 'agreement among raft nodes before linearized reading' (duration: 448.898522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:14.400821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:13.950324Z","time spent":"450.484595ms","remote":"127.0.0.1:46208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-03-06T18:28:14.862404Z","caller":"traceutil/trace.go:171","msg":"trace[1640424909] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"112.453551ms","start":"2026-03-06T18:28:14.749928Z","end":"2026-03-06T18:28:14.862382Z","steps":["trace[1640424909] 'read index received' (duration: 112.289814ms)","trace[1640424909] 'applied index is now lower than readState.Index' (duration: 162.917µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:28:14.862506Z","caller":"traceutil/trace.go:171","msg":"trace[57813179] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"116.560795ms","start":"2026-03-06T18:28:14.745936Z","end":"2026-03-06T18:28:14.862497Z","steps":["trace[57813179] 'process raft request' (duration: 116.310735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:14.862602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.115657ms","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-03-06T18:28:14.862625Z","caller":"traceutil/trace.go:171","msg":"trace[1894338096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:511; }","duration":"107.163088ms","start":"2026-03-06T18:28:14.755456Z","end":"2026-03-06T18:28:14.862619Z","steps":["trace[1894338096] 'agreement among raft nodes before linearized reading' (duration: 107.070564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:14.862722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.787714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-06T18:28:14.862754Z","caller":"traceutil/trace.go:171","msg":"trace[1756320272] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:511; }","duration":"112.852537ms","start":"2026-03-06T18:28:14.749891Z","end":"2026-03-06T18:28:14.862743Z","steps":["trace[1756320272] 'agreement among raft nodes before linearized reading' (duration: 112.779554ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:20.716068Z","caller":"traceutil/trace.go:171","msg":"trace[1039785413] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"155.118065ms","start":"2026-03-06T18:28:20.560924Z","end":"2026-03-06T18:28:20.716043Z","steps":["trace[1039785413] 'read index received' (duration: 154.896857ms)","trace[1039785413] 'applied index is now lower than readState.Index' (duration: 220.048µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:28:20.716154Z","caller":"traceutil/trace.go:171","msg":"trace[429530598] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"168.613741ms","start":"2026-03-06T18:28:20.547503Z","end":"2026-03-06T18:28:20.716117Z","steps":["trace[429530598] 'process raft request' (duration: 168.397083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:20.716312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.366934ms","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-03-06T18:28:20.716347Z","caller":"traceutil/trace.go:171","msg":"trace[606302649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"155.454857ms","start":"2026-03-06T18:28:20.560882Z","end":"2026-03-06T18:28:20.716337Z","steps":["trace[606302649] 'agreement among raft nodes before linearized reading' (duration: 155.293781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:20.716508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.410784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T18:28:20.716615Z","caller":"traceutil/trace.go:171","msg":"trace[147160585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"144.594031ms","start":"2026-03-06T18:28:20.571998Z","end":"2026-03-06T18:28:20.716592Z","steps":["trace[147160585] 'agreement among raft nodes before linearized reading' (duration: 144.228827ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:20.902896Z","caller":"traceutil/trace.go:171","msg":"trace[1693834343] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"182.98036ms","start":"2026-03-06T18:28:20.719889Z","end":"2026-03-06T18:28:20.902869Z","steps":["trace[1693834343] 'process raft request' (duration: 174.609006ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:20.943875Z","caller":"traceutil/trace.go:171","msg":"trace[462736162] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"223.269219ms","start":"2026-03-06T18:28:20.720574Z","end":"2026-03-06T18:28:20.943843Z","steps":["trace[462736162] 'process raft request' (duration: 223.115694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:21.079431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.941275ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-06T18:28:21.079527Z","caller":"traceutil/trace.go:171","msg":"trace[1283052766] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"135.718497ms","start":"2026-03-06T18:28:20.943793Z","end":"2026-03-06T18:28:21.079511Z","steps":["trace[1283052766] 'read index received' (duration: 19.855975ms)","trace[1283052766] 'applied index is now lower than readState.Index' (duration: 115.861372ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:28:21.079714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.317318ms","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-03-06T18:28:21.07974Z","caller":"traceutil/trace.go:171","msg":"trace[257261075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:564; }","duration":"192.37731ms","start":"2026-03-06T18:28:20.887356Z","end":"2026-03-06T18:28:21.079733Z","steps":["trace[257261075] 'agreement among raft nodes before linearized reading' (duration: 192.195433ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:21.280489Z","caller":"traceutil/trace.go:171","msg":"trace[430657721] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"323.362757ms","start":"2026-03-06T18:28:20.95709Z","end":"2026-03-06T18:28:21.280452Z","steps":["trace[430657721] 'process raft request' (duration: 322.965612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:21.280726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:20.957062Z","time spent":"323.535653ms","remote":"127.0.0.1:46336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T18:28:21.369955Z","caller":"traceutil/trace.go:171","msg":"trace[1551869218] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"284.424224ms","start":"2026-03-06T18:28:21.085504Z","end":"2026-03-06T18:28:21.369928Z","steps":["trace[1551869218] 'process raft request' (duration: 284.228667ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:22.881402Z","caller":"traceutil/trace.go:171","msg":"trace[1170421276] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"157.38625ms","start":"2026-03-06T18:28:22.723987Z","end":"2026-03-06T18:28:22.881373Z","steps":["trace[1170421276] 'process raft request' (duration: 157.183013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:23.449207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541103913699058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T18:28:23.949552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541103913699058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T18:28:24.156309Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.246091413s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-06T18:28:24.279826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.255821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:28:24.27996Z","caller":"traceutil/trace.go:171","msg":"trace[1484929111] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"1.331236396s","start":"2026-03-06T18:28:22.948705Z","end":"2026-03-06T18:28:24.279941Z","steps":["trace[1484929111] 'read index received' (duration: 1.207716506s)","trace[1484929111] 'applied index is now lower than readState.Index' (duration: 123.51815ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:28:24.280053Z","caller":"traceutil/trace.go:171","msg":"trace[2006839986] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.369929735s","start":"2026-03-06T18:28:22.910113Z","end":"2026-03-06T18:28:24.280042Z","steps":["trace[2006839986] 'process raft request' (duration: 1.246370834s)","trace[2006839986] 'compare' (duration: 123.072484ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T18:28:24.280115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:22.91009Z","time spent":"1.369994048s","remote":"127.0.0.1:46420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T18:28:24.280332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.689781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T18:28:24.280377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.620176ms","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-03-06T18:28:24.280413Z","caller":"traceutil/trace.go:171","msg":"trace[899037316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"274.815437ms","start":"2026-03-06T18:28:24.005577Z","end":"2026-03-06T18:28:24.280393Z","steps":["trace[899037316] 'agreement among raft nodes before linearized reading' (duration: 274.655091ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:24.280424Z","caller":"traceutil/trace.go:171","msg":"trace[1033761703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:569; }","duration":"906.709239ms","start":"2026-03-06T18:28:23.373702Z","end":"2026-03-06T18:28:24.280411Z","steps":["trace[1033761703] 'agreement among raft nodes before linearized reading' (duration: 906.417749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:24.280459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:23.373679Z","time spent":"906.772222ms","remote":"127.0.0.1:46420","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-03-06T18:28:24.280492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331767106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T18:28:24.280573Z","caller":"traceutil/trace.go:171","msg":"trace[1050975425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"1.33190099s","start":"2026-03-06T18:28:22.948647Z","end":"2026-03-06T18:28:24.280548Z","steps":["trace[1050975425] 'agreement among raft nodes before linearized reading' (duration: 1.33162868s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:24.280628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:22.948619Z","time spent":"1.332000354s","remote":"127.0.0.1:46420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T18:28:24.280641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.587387ms","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-03-06T18:28:24.280784Z","caller":"traceutil/trace.go:171","msg":"trace[67269546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"900.848037ms","start":"2026-03-06T18:28:23.379912Z","end":"2026-03-06T18:28:24.28076Z","steps":["trace[67269546] 'agreement among raft nodes before linearized reading' (duration: 900.326208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:28:24.280859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T18:28:23.379902Z","time spent":"900.94449ms","remote":"127.0.0.1:46420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T18:28:24.507481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.264161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T18:28:24.5076Z","caller":"traceutil/trace.go:171","msg":"trace[1933659698] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"222.712198ms","start":"2026-03-06T18:28:24.284866Z","end":"2026-03-06T18:28:24.507579Z","steps":["trace[1933659698] 'process raft request' (duration: 116.232879ms)","trace[1933659698] 'compare' (duration: 106.141557ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T18:28:24.526223Z","caller":"traceutil/trace.go:171","msg":"trace[1207896706] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"240.263098ms","start":"2026-03-06T18:28:24.285932Z","end":"2026-03-06T18:28:24.526195Z","steps":["trace[1207896706] 'process raft request' (duration: 240.216756ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:28:24.526402Z","caller":"traceutil/trace.go:171","msg":"trace[1857277911] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"241.258853ms","start":"2026-03-06T18:28:24.285133Z","end":"2026-03-06T18:28:24.526392Z","steps":["trace[1857277911] 'process raft request' (duration: 240.89419ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:29:57.24049Z","caller":"traceutil/trace.go:171","msg":"trace[932793570] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"216.147966ms","start":"2026-03-06T18:29:57.024318Z","end":"2026-03-06T18:29:57.240466Z","steps":["trace[932793570] 'process raft request' (duration: 216.095055ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:29:57.240899Z","caller":"traceutil/trace.go:171","msg":"trace[763096908] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"217.484179ms","start":"2026-03-06T18:29:57.023405Z","end":"2026-03-06T18:29:57.240889Z","steps":["trace[763096908] 'process raft request' (duration: 209.300692ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:29:57.252056Z","caller":"traceutil/trace.go:171","msg":"trace[1074191162] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"183.084513ms","start":"2026-03-06T18:29:57.068942Z","end":"2026-03-06T18:29:57.252027Z","steps":["trace[1074191162] 'process raft request' (duration: 182.92069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T18:29:57.537432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.120782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-06T18:29:57.537501Z","caller":"traceutil/trace.go:171","msg":"trace[2100174048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"162.260904ms","start":"2026-03-06T18:29:57.375223Z","end":"2026-03-06T18:29:57.537484Z","steps":["trace[2100174048] 'range keys from in-memory index tree' (duration: 161.954709ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T18:29:57.789617Z","caller":"traceutil/trace.go:171","msg":"trace[1614171184] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"247.945008ms","start":"2026-03-06T18:29:57.54165Z","end":"2026-03-06T18:29:57.789595Z","steps":["trace[1614171184] 'process raft request' (duration: 247.826466ms)"],"step_count":1}