{"level":"warn","ts":"2026-09-19T06:07:29.411909Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T06:07:29.412003Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.138: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.138:2380","--initial-cluster=instance=https://162.253.55.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.138: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-09-19T06:07:29.412075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T06:07:29.41209Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-09-19T06:07:29.412114Z","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-09-19T06:07:29.412921Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"]} {"level":"info","ts":"2026-09-19T06:07:29.413017Z","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.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.138: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-09-19T06:07:29.420457Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.18969ms"} {"level":"info","ts":"2026-09-19T06:07:29.42636Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ee8e5a779fc25cea","cluster-id":"8f8e3b77da0cbcef"} {"level":"info","ts":"2026-09-19T06:07:29.426421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T06:07:29.426442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 0"} {"level":"info","ts":"2026-09-19T06:07:29.426454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ee8e5a779fc25cea [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T06:07:29.426464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 1"} {"level":"info","ts":"2026-09-19T06:07:29.426501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"warn","ts":"2026-09-19T06:07:29.433665Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T06:07:29.434839Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T06:07:29.435881Z","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-09-19T06:07:29.437598Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ee8e5a779fc25cea","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T06:07:29.437784Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ee8e5a779fc25cea","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T06:07:29.437937Z","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-09-19T06:07:29.437979Z","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-09-19T06:07:29.438043Z","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-09-19T06:07:29.439377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"info","ts":"2026-09-19T06:07:29.439546Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","added-peer-id":"ee8e5a779fc25cea","added-peer-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-09-19T06:07:29.440813Z","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-09-19T06:07:29.441057Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ee8e5a779fc25cea","initial-advertise-peer-urls":["https://162.253.55.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T06:07:29.441092Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T06:07:29.440951Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-09-19T06:07:29.44144Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-09-19T06:07:29.72722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T06:07:29.727283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T06:07:29.727302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgPreVoteResp from ee8e5a779fc25cea at term 1"} {"level":"info","ts":"2026-09-19T06:07:29.727318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became candidate at term 2"} {"level":"info","ts":"2026-09-19T06:07:29.727325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgVoteResp from ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-09-19T06:07:29.727335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became leader at term 2"} {"level":"info","ts":"2026-09-19T06:07:29.727345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ee8e5a779fc25cea elected leader ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-09-19T06:07:29.751605Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:07:29.75486Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ee8e5a779fc25cea","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.138:2379]}","request-path":"/0/members/ee8e5a779fc25cea/attributes","cluster-id":"8f8e3b77da0cbcef","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T06:07:29.755059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:07:29.755124Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:07:29.755208Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T06:07:29.755278Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T06:07:29.75667Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T06:07:29.757255Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.138:2379"} {"level":"info","ts":"2026-09-19T06:07:29.773028Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:07:29.773121Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:07:29.773146Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:07:47.970524Z","caller":"traceutil/trace.go:171","msg":"trace[415728604] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"183.241869ms","start":"2026-09-19T06:07:47.787261Z","end":"2026-09-19T06:07:47.970503Z","steps":["trace[415728604] 'process raft request' (duration: 183.137096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:48.693358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.509248ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-19T06:07:48.85254Z","caller":"traceutil/trace.go:171","msg":"trace[1828826788] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"139.56882ms","start":"2026-09-19T06:07:48.712946Z","end":"2026-09-19T06:07:48.852515Z","steps":["trace[1828826788] 'process raft request' (duration: 139.436286ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:05.067902Z","caller":"traceutil/trace.go:171","msg":"trace[335122152] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"134.813466ms","start":"2026-09-19T06:08:04.933045Z","end":"2026-09-19T06:08:05.067858Z","steps":["trace[335122152] 'process raft request' (duration: 134.622681ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:16.118477Z","caller":"traceutil/trace.go:171","msg":"trace[266037198] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"166.291383ms","start":"2026-09-19T06:08:15.952166Z","end":"2026-09-19T06:08:16.118458Z","steps":["trace[266037198] 'process raft request' (duration: 149.016657ms)","trace[266037198] 'compare' (duration: 17.157223ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:08:16.118533Z","caller":"traceutil/trace.go:171","msg":"trace[738732622] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"128.43458ms","start":"2026-09-19T06:08:15.99009Z","end":"2026-09-19T06:08:16.118525Z","steps":["trace[738732622] 'process raft request' (duration: 128.370789ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:16.135539Z","caller":"traceutil/trace.go:171","msg":"trace[764816321] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"134.824377ms","start":"2026-09-19T06:08:16.000695Z","end":"2026-09-19T06:08:16.135519Z","steps":["trace[764816321] 'process raft request' (duration: 134.543639ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:16.280394Z","caller":"traceutil/trace.go:171","msg":"trace[803032239] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"111.789142ms","start":"2026-09-19T06:08:16.168557Z","end":"2026-09-19T06:08:16.280346Z","steps":["trace[803032239] 'process raft request' (duration: 74.755711ms)","trace[803032239] 'compare' (duration: 36.63254ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:08:16.280911Z","caller":"traceutil/trace.go:171","msg":"trace[1058219207] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"100.404208ms","start":"2026-09-19T06:08:16.180461Z","end":"2026-09-19T06:08:16.280865Z","steps":["trace[1058219207] 'process raft request' (duration: 100.247924ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:16.622564Z","caller":"traceutil/trace.go:171","msg":"trace[2003355194] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"107.796162ms","start":"2026-09-19T06:08:16.514714Z","end":"2026-09-19T06:08:16.622511Z","steps":["trace[2003355194] 'process raft request' (duration: 106.995821ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:17.689154Z","caller":"traceutil/trace.go:171","msg":"trace[943589911] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"309.933973ms","start":"2026-09-19T06:08:17.379193Z","end":"2026-09-19T06:08:17.689127Z","steps":["trace[943589911] 'read index received' (duration: 309.481161ms)","trace[943589911] 'applied index is now lower than readState.Index' (duration: 451.632µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:08:17.689257Z","caller":"traceutil/trace.go:171","msg":"trace[82986265] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"318.681934ms","start":"2026-09-19T06:08:17.370511Z","end":"2026-09-19T06:08:17.689193Z","steps":["trace[82986265] 'process raft request' (duration: 318.15398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:17.689352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.141639ms","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-09-19T06:08:17.689391Z","caller":"traceutil/trace.go:171","msg":"trace[1284003951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"310.223031ms","start":"2026-09-19T06:08:17.379156Z","end":"2026-09-19T06:08:17.689379Z","steps":["trace[1284003951] 'agreement among raft nodes before linearized reading' (duration: 310.125049ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:17.689434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:17.379141Z","time spent":"310.284633ms","remote":"127.0.0.1:56440","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-09-19T06:08:17.690723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:17.370466Z","time spent":"318.939771ms","remote":"127.0.0.1:56238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:08:17.698147Z","caller":"traceutil/trace.go:171","msg":"trace[1817343395] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"303.392153ms","start":"2026-09-19T06:08:17.394726Z","end":"2026-09-19T06:08:17.698118Z","steps":["trace[1817343395] 'process raft request' (duration: 302.819808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:17.698376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:17.39468Z","time spent":"303.592419ms","remote":"127.0.0.1:56238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:08:17.698451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.796384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-09-19T06:08:17.698536Z","caller":"traceutil/trace.go:171","msg":"trace[1269616470] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:653; }","duration":"299.902587ms","start":"2026-09-19T06:08:17.398611Z","end":"2026-09-19T06:08:17.698514Z","steps":["trace[1269616470] 'agreement among raft nodes before linearized reading' (duration: 299.693001ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:17.698676Z","caller":"traceutil/trace.go:171","msg":"trace[941622884] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"181.229316ms","start":"2026-09-19T06:08:17.517436Z","end":"2026-09-19T06:08:17.698666Z","steps":["trace[941622884] 'process raft request' (duration: 180.64023ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:19.81632Z","caller":"traceutil/trace.go:171","msg":"trace[1642445403] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"102.945648ms","start":"2026-09-19T06:08:19.713345Z","end":"2026-09-19T06:08:19.816291Z","steps":["trace[1642445403] 'read index received' (duration: 11.340423ms)","trace[1642445403] 'applied index is now lower than readState.Index' (duration: 91.604085ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:08:19.816406Z","caller":"traceutil/trace.go:171","msg":"trace[1224634710] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"105.158349ms","start":"2026-09-19T06:08:19.711201Z","end":"2026-09-19T06:08:19.81636Z","steps":["trace[1224634710] 'process raft request' (duration: 13.474311ms)","trace[1224634710] 'compare' (duration: 91.486482ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:08:19.816581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.211005ms","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-09-19T06:08:19.816654Z","caller":"traceutil/trace.go:171","msg":"trace[1513694704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:673; }","duration":"103.312338ms","start":"2026-09-19T06:08:19.713322Z","end":"2026-09-19T06:08:19.816634Z","steps":["trace[1513694704] 'agreement among raft nodes before linearized reading' (duration: 103.079012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:19.816743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.431803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-09-19T06:08:19.816803Z","caller":"traceutil/trace.go:171","msg":"trace[2027245723] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:673; }","duration":"102.527315ms","start":"2026-09-19T06:08:19.71426Z","end":"2026-09-19T06:08:19.816787Z","steps":["trace[2027245723] 'agreement among raft nodes before linearized reading' (duration: 102.32724ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:24.559845Z","caller":"traceutil/trace.go:171","msg":"trace[209870124] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"339.001614ms","start":"2026-09-19T06:08:24.220803Z","end":"2026-09-19T06:08:24.559804Z","steps":["trace[209870124] 'process raft request' (duration: 338.801108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:24.560104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:24.220773Z","time spent":"339.21315ms","remote":"127.0.0.1:56440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:08:25.569182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.615228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:08:25.569267Z","caller":"traceutil/trace.go:171","msg":"trace[1964910438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"254.722482ms","start":"2026-09-19T06:08:25.314527Z","end":"2026-09-19T06:08:25.569249Z","steps":["trace[1964910438] 'range keys from in-memory index tree' (duration: 254.473984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:25.569707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.132715ms","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-09-19T06:08:25.569775Z","caller":"traceutil/trace.go:171","msg":"trace[710179547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"402.231448ms","start":"2026-09-19T06:08:25.167526Z","end":"2026-09-19T06:08:25.569757Z","steps":["trace[710179547] 'range keys from in-memory index tree' (duration: 401.904678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:25.569819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:25.16751Z","time spent":"402.2954ms","remote":"127.0.0.1:56440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-19T06:09:23.569776Z","caller":"traceutil/trace.go:171","msg":"trace[1928074840] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"258.076625ms","start":"2026-09-19T06:09:23.311674Z","end":"2026-09-19T06:09:23.569751Z","steps":["trace[1928074840] 'read index received' (duration: 257.939682ms)","trace[1928074840] 'applied index is now lower than readState.Index' (duration: 135.993µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:23.569807Z","caller":"traceutil/trace.go:171","msg":"trace[1223750245] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"384.022953ms","start":"2026-09-19T06:09:23.185755Z","end":"2026-09-19T06:09:23.569778Z","steps":["trace[1223750245] 'process raft request' (duration: 383.820188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:23.569931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.236869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:09:23.569964Z","caller":"traceutil/trace.go:171","msg":"trace[1594457267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"258.314712ms","start":"2026-09-19T06:09:23.311637Z","end":"2026-09-19T06:09:23.569952Z","steps":["trace[1594457267] 'agreement among raft nodes before linearized reading' (duration: 258.215519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:23.569963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:23.185743Z","time spent":"384.148236ms","remote":"127.0.0.1:56440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:09:23.941639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.23402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:09:23.941718Z","caller":"traceutil/trace.go:171","msg":"trace[1643224717] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1124; }","duration":"211.374604ms","start":"2026-09-19T06:09:23.730327Z","end":"2026-09-19T06:09:23.941702Z","steps":["trace[1643224717] 'count revisions from in-memory index tree' (duration: 211.162468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:38.710659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.143069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:09:38.710727Z","caller":"traceutil/trace.go:171","msg":"trace[1169834856] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"230.024956ms","start":"2026-09-19T06:09:38.48069Z","end":"2026-09-19T06:09:38.710715Z","steps":["trace[1169834856] 'process raft request' (duration: 62.771414ms)","trace[1169834856] 'compare' (duration: 167.076757ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:44.239465Z","caller":"traceutil/trace.go:171","msg":"trace[2113114491] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"162.638295ms","start":"2026-09-19T06:09:44.076806Z","end":"2026-09-19T06:09:44.239444Z","steps":["trace[2113114491] 'process raft request' (duration: 162.497682ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:44.275555Z","caller":"traceutil/trace.go:171","msg":"trace[1959786971] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"184.556817ms","start":"2026-09-19T06:09:44.090961Z","end":"2026-09-19T06:09:44.275518Z","steps":["trace[1959786971] 'process raft request' (duration: 184.358151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:44.653719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.043779ms","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-09-19T06:09:44.653808Z","caller":"traceutil/trace.go:171","msg":"trace[653962332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"205.173323ms","start":"2026-09-19T06:09:44.448609Z","end":"2026-09-19T06:09:44.653782Z","steps":["trace[653962332] 'range keys from in-memory index tree' (duration: 204.849684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:44.653876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.520122ms","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-09-19T06:09:44.654016Z","caller":"traceutil/trace.go:171","msg":"trace[1490368618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"229.762718ms","start":"2026-09-19T06:09:44.424206Z","end":"2026-09-19T06:09:44.653969Z","steps":["trace[1490368618] 'range keys from in-memory index tree' (duration: 229.204622ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:44.985637Z","caller":"traceutil/trace.go:171","msg":"trace[1273901209] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"103.837271ms","start":"2026-09-19T06:09:44.881759Z","end":"2026-09-19T06:09:44.985597Z","steps":["trace[1273901209] 'process raft request' (duration: 103.77783ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:44.986082Z","caller":"traceutil/trace.go:171","msg":"trace[810684579] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"105.403464ms","start":"2026-09-19T06:09:44.880664Z","end":"2026-09-19T06:09:44.986067Z","steps":["trace[810684579] 'process raft request' (duration: 104.664743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:45.315664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.325002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:09:45.315965Z","caller":"traceutil/trace.go:171","msg":"trace[34377505] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"204.244426ms","start":"2026-09-19T06:09:45.111684Z","end":"2026-09-19T06:09:45.315928Z","steps":["trace[34377505] 'read index received' (duration: 67.447681ms)","trace[34377505] 'applied index is now lower than readState.Index' (duration: 136.794195ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:45.316195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.488374ms","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-09-19T06:09:45.316226Z","caller":"traceutil/trace.go:171","msg":"trace[1690237459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1236; }","duration":"204.577666ms","start":"2026-09-19T06:09:45.111643Z","end":"2026-09-19T06:09:45.31622Z","steps":["trace[1690237459] 'agreement among raft nodes before linearized reading' (duration: 204.404101ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:45.31598Z","caller":"traceutil/trace.go:171","msg":"trace[1104570247] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"324.498988ms","start":"2026-09-19T06:09:44.991439Z","end":"2026-09-19T06:09:45.315938Z","steps":["trace[1104570247] 'process raft request' (duration: 187.776246ms)","trace[1104570247] 'compare' (duration: 136.035344ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:45.316393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.012245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:09:45.316502Z","caller":"traceutil/trace.go:171","msg":"trace[1598283959] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1236; }","duration":"175.19205ms","start":"2026-09-19T06:09:45.141288Z","end":"2026-09-19T06:09:45.31648Z","steps":["trace[1598283959] 'agreement among raft nodes before linearized reading' (duration: 174.997255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:45.317475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:44.991409Z","time spent":"325.002492ms","remote":"127.0.0.1:56266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T06:09:45.503712Z","caller":"traceutil/trace.go:171","msg":"trace[1337739759] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"112.353736ms","start":"2026-09-19T06:09:45.391331Z","end":"2026-09-19T06:09:45.503685Z","steps":["trace[1337739759] 'process raft request' (duration: 42.092206ms)","trace[1337739759] 'compare' (duration: 70.110655ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:54.492959Z","caller":"traceutil/trace.go:171","msg":"trace[2118467543] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"209.955454ms","start":"2026-09-19T06:09:54.282966Z","end":"2026-09-19T06:09:54.492922Z","steps":["trace[2118467543] 'process raft request' (duration: 209.701267ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:54.530177Z","caller":"traceutil/trace.go:171","msg":"trace[1698763604] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"214.89136ms","start":"2026-09-19T06:09:54.31525Z","end":"2026-09-19T06:09:54.530141Z","steps":["trace[1698763604] 'read index received' (duration: 177.323478ms)","trace[1698763604] 'applied index is now lower than readState.Index' (duration: 37.566812ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:54.530215Z","caller":"traceutil/trace.go:171","msg":"trace[1491363165] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"228.011081ms","start":"2026-09-19T06:09:54.302173Z","end":"2026-09-19T06:09:54.530184Z","steps":["trace[1491363165] 'process raft request' (duration: 227.839196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:54.530362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.079525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:09:54.530434Z","caller":"traceutil/trace.go:171","msg":"trace[1089972462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"215.196628ms","start":"2026-09-19T06:09:54.315218Z","end":"2026-09-19T06:09:54.530414Z","steps":["trace[1089972462] 'agreement among raft nodes before linearized reading' (duration: 215.066005ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:55.189977Z","caller":"traceutil/trace.go:171","msg":"trace[398525106] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"414.619463ms","start":"2026-09-19T06:09:54.775334Z","end":"2026-09-19T06:09:55.189953Z","steps":["trace[398525106] 'read index received' (duration: 414.579582ms)","trace[398525106] 'applied index is now lower than readState.Index' (duration: 38.721µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:55.190086Z","caller":"traceutil/trace.go:171","msg":"trace[1461988064] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"441.696616ms","start":"2026-09-19T06:09:54.748362Z","end":"2026-09-19T06:09:55.190058Z","steps":["trace[1461988064] 'process raft request' (duration: 441.441999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.190193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.834169ms","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-09-19T06:09:55.19023Z","caller":"traceutil/trace.go:171","msg":"trace[1767362638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"414.912941ms","start":"2026-09-19T06:09:54.775305Z","end":"2026-09-19T06:09:55.190218Z","steps":["trace[1767362638] 'agreement among raft nodes before linearized reading' (duration: 414.767897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.190295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:54.775292Z","time spent":"414.994163ms","remote":"127.0.0.1:56440","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-09-19T06:09:55.190283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:54.748278Z","time spent":"441.87788ms","remote":"127.0.0.1:56440","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-09-19T06:09:55.299134Z","caller":"traceutil/trace.go:171","msg":"trace[1532459325] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"449.432339ms","start":"2026-09-19T06:09:54.849673Z","end":"2026-09-19T06:09:55.299105Z","steps":["trace[1532459325] 'process raft request' (duration: 440.874164ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:55.299159Z","caller":"traceutil/trace.go:171","msg":"trace[1517268815] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"109.089544ms","start":"2026-09-19T06:09:55.19005Z","end":"2026-09-19T06:09:55.29914Z","steps":["trace[1517268815] 'read index received' (duration: 100.499439ms)","trace[1517268815] 'applied index is now lower than readState.Index' (duration: 8.589065ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:55.299286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:54.849647Z","time spent":"449.555061ms","remote":"127.0.0.1:56440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:09:55.299407Z","caller":"traceutil/trace.go:171","msg":"trace[751712183] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"104.706224ms","start":"2026-09-19T06:09:55.19468Z","end":"2026-09-19T06:09:55.299386Z","steps":["trace[751712183] 'process raft request' (duration: 104.394896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.299476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.503148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-09-19T06:09:55.299753Z","caller":"traceutil/trace.go:171","msg":"trace[1067008776] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1297; }","duration":"379.820097ms","start":"2026-09-19T06:09:54.919897Z","end":"2026-09-19T06:09:55.299717Z","steps":["trace[1067008776] 'agreement among raft nodes before linearized reading' (duration: 379.352284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.299927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:54.91988Z","time spent":"379.94237ms","remote":"127.0.0.1:56440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "}