{"level":"warn","ts":"2026-03-09T01:34:15.916872Z","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-09T01:34:15.916965Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99: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-09T01:34:15.917027Z","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-09T01:34:15.917038Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-03-09T01:34:15.917056Z","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-09T01:34:15.91828Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-03-09T01:34:15.918385Z","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://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99: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-09T01:34:15.934313Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"15.740721ms"} {"level":"info","ts":"2026-03-09T01:34:15.965172Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-03-09T01:34:15.965289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T01:34:15.965313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-03-09T01:34:15.965328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T01:34:15.965337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-03-09T01:34:15.965379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-03-09T01:34:15.990386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T01:34:15.993136Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T01:34:15.995775Z","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-09T01:34:16.013786Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T01:34:16.014173Z","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-09T01:34:16.014338Z","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-09T01:34:16.014384Z","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-09T01:34:16.014449Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T01:34:16.020304Z","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-09T01:34:16.02049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-03-09T01:34:16.020518Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-03-09T01:34:16.021617Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T01:34:16.021811Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T01:34:16.023235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-03-09T01:34:16.023394Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-03-09T01:34:16.26623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T01:34:16.266371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T01:34:16.266399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-03-09T01:34:16.26645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-03-09T01:34:16.266478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-03-09T01:34:16.266616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-03-09T01:34:16.266656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-03-09T01:34:16.267665Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:34:16.26958Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T01:34:16.269669Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:34:16.269685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:34:16.269956Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T01:34:16.270064Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T01:34:16.270575Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:34:16.270921Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:34:16.271001Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:34:16.272875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-03-09T01:34:16.273444Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T01:34:20.66951Z","caller":"traceutil/trace.go:171","msg":"trace[42201853] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"109.38105ms","start":"2026-03-09T01:34:20.560105Z","end":"2026-03-09T01:34:20.669486Z","steps":["trace[42201853] 'process raft request' (duration: 109.321629ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:34:20.669582Z","caller":"traceutil/trace.go:171","msg":"trace[1975027592] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"127.821996ms","start":"2026-03-09T01:34:20.541704Z","end":"2026-03-09T01:34:20.669526Z","steps":["trace[1975027592] 'process raft request' (duration: 73.842698ms)","trace[1975027592] 'compare' (duration: 53.709285ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:34:23.191769Z","caller":"traceutil/trace.go:171","msg":"trace[40671105] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"150.733802ms","start":"2026-03-09T01:34:23.041012Z","end":"2026-03-09T01:34:23.191746Z","steps":["trace[40671105] 'read index received' (duration: 87.036224ms)","trace[40671105] 'applied index is now lower than readState.Index' (duration: 63.696718ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:34:23.191902Z","caller":"traceutil/trace.go:171","msg":"trace[355501055] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"195.145393ms","start":"2026-03-09T01:34:22.996711Z","end":"2026-03-09T01:34:23.191856Z","steps":["trace[355501055] 'process raft request' (duration: 131.343784ms)","trace[355501055] 'compare' (duration: 63.583387ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:34:23.192019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.992955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-09T01:34:23.192096Z","caller":"traceutil/trace.go:171","msg":"trace[1778591217] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"151.111266ms","start":"2026-03-09T01:34:23.040975Z","end":"2026-03-09T01:34:23.192086Z","steps":["trace[1778591217] 'agreement among raft nodes before linearized reading' (duration: 150.887694ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:34:26.172807Z","caller":"traceutil/trace.go:171","msg":"trace[981304883] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"112.934637ms","start":"2026-03-09T01:34:26.059836Z","end":"2026-03-09T01:34:26.172771Z","steps":["trace[981304883] 'process raft request' (duration: 112.718205ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:34:26.494135Z","caller":"traceutil/trace.go:171","msg":"trace[1968661363] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"192.650517ms","start":"2026-03-09T01:34:26.301444Z","end":"2026-03-09T01:34:26.494095Z","steps":["trace[1968661363] 'read index received' (duration: 192.421245ms)","trace[1968661363] 'applied index is now lower than readState.Index' (duration: 227.442µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:34:26.49424Z","caller":"traceutil/trace.go:171","msg":"trace[1976707674] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"254.360767ms","start":"2026-03-09T01:34:26.239831Z","end":"2026-03-09T01:34:26.494192Z","steps":["trace[1976707674] 'process raft request' (duration: 254.039834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:26.494331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.847849ms","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-03-09T01:34:26.494377Z","caller":"traceutil/trace.go:171","msg":"trace[2013396646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:385; }","duration":"192.996481ms","start":"2026-03-09T01:34:26.301367Z","end":"2026-03-09T01:34:26.494363Z","steps":["trace[2013396646] 'agreement among raft nodes before linearized reading' (duration: 192.865749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:29.692093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.152422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:34:29.692234Z","caller":"traceutil/trace.go:171","msg":"trace[570839264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"173.358054ms","start":"2026-03-09T01:34:29.518842Z","end":"2026-03-09T01:34:29.6922Z","steps":["trace[570839264] 'range keys from in-memory index tree' (duration: 173.009501ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:34:30.041187Z","caller":"traceutil/trace.go:171","msg":"trace[33337804] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"322.225563ms","start":"2026-03-09T01:34:29.718931Z","end":"2026-03-09T01:34:30.041157Z","steps":["trace[33337804] 'read index received' (duration: 321.80238ms)","trace[33337804] 'applied index is now lower than readState.Index' (duration: 421.553µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:34:30.041344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.392625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:34:30.041391Z","caller":"traceutil/trace.go:171","msg":"trace[931738646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"322.492315ms","start":"2026-03-09T01:34:29.718883Z","end":"2026-03-09T01:34:30.041375Z","steps":["trace[931738646] 'agreement among raft nodes before linearized reading' (duration: 322.402025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:30.041443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:29.718862Z","time spent":"322.570397ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T01:34:30.041485Z","caller":"traceutil/trace.go:171","msg":"trace[1296336626] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"334.086466ms","start":"2026-03-09T01:34:29.707148Z","end":"2026-03-09T01:34:30.041235Z","steps":["trace[1296336626] 'process raft request' (duration: 333.756583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:30.042403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:29.707135Z","time spent":"334.54187ms","remote":"127.0.0.1:40026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:34:35.634741Z","caller":"traceutil/trace.go:171","msg":"trace[1597108703] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"352.482287ms","start":"2026-03-09T01:34:35.282235Z","end":"2026-03-09T01:34:35.634717Z","steps":["trace[1597108703] 'process raft request' (duration: 352.354056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:35.634887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:35.282221Z","time spent":"352.594089ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:34:36.662247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.525288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-09T01:34:36.662236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.356736ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T01:34:36.662312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.707306ms","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-09T01:34:36.662364Z","caller":"traceutil/trace.go:171","msg":"trace[1764760333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:451; }","duration":"105.728109ms","start":"2026-03-09T01:34:36.556611Z","end":"2026-03-09T01:34:36.662339Z","steps":["trace[1764760333] 'range keys from in-memory index tree' (duration: 105.370506ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:34:36.662403Z","caller":"traceutil/trace.go:171","msg":"trace[1371670079] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:451; }","duration":"435.544597ms","start":"2026-03-09T01:34:36.22683Z","end":"2026-03-09T01:34:36.662375Z","steps":["trace[1371670079] 'range keys from in-memory index tree' (duration: 435.282805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:36.662384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.542758ms","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-03-09T01:34:36.6625Z","caller":"traceutil/trace.go:171","msg":"trace[1870846909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:451; }","duration":"125.72987ms","start":"2026-03-09T01:34:36.536745Z","end":"2026-03-09T01:34:36.662474Z","steps":["trace[1870846909] 'range keys from in-memory index tree' (duration: 125.413457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:36.662389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.798169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:34:36.662709Z","caller":"traceutil/trace.go:171","msg":"trace[244529195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:451; }","duration":"760.202343ms","start":"2026-03-09T01:34:35.90249Z","end":"2026-03-09T01:34:36.662693Z","steps":["trace[244529195] 'range keys from in-memory index tree' (duration: 759.685138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:36.662774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:35.902465Z","time spent":"760.289993ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T01:34:36.662419Z","caller":"traceutil/trace.go:171","msg":"trace[1304408163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:451; }","duration":"426.883338ms","start":"2026-03-09T01:34:36.23551Z","end":"2026-03-09T01:34:36.662393Z","steps":["trace[1304408163] 'range keys from in-memory index tree' (duration: 426.568565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:36.662838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:36.235455Z","time spent":"427.367702ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-09T01:34:54.884521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.044941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tbjvk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-09T01:34:54.884619Z","caller":"traceutil/trace.go:171","msg":"trace[1416596403] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tbjvk; range_end:; response_count:1; response_revision:553; }","duration":"100.213972ms","start":"2026-03-09T01:34:54.784387Z","end":"2026-03-09T01:34:54.884601Z","steps":["trace[1416596403] 'agreement among raft nodes before linearized reading' (duration: 99.94804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:55.414799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485115300629553587,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-09T01:34:55.471967Z","caller":"traceutil/trace.go:171","msg":"trace[1413558911] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"558.068147ms","start":"2026-03-09T01:34:54.91387Z","end":"2026-03-09T01:34:55.471938Z","steps":["trace[1413558911] 'read index received' (duration: 557.768975ms)","trace[1413558911] 'applied index is now lower than readState.Index' (duration: 298.462µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:34:55.472101Z","caller":"traceutil/trace.go:171","msg":"trace[1551730765] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"562.457468ms","start":"2026-03-09T01:34:54.909597Z","end":"2026-03-09T01:34:55.472054Z","steps":["trace[1551730765] 'process raft request' (duration: 562.118986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:55.472209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.314849ms","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-03-09T01:34:55.47224Z","caller":"traceutil/trace.go:171","msg":"trace[1782541516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:554; }","duration":"558.394049ms","start":"2026-03-09T01:34:54.913837Z","end":"2026-03-09T01:34:55.472231Z","steps":["trace[1782541516] 'agreement among raft nodes before linearized reading' (duration: 558.237538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:55.47227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:54.913818Z","time spent":"558.44487ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T01:34:55.472306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:54.909553Z","time spent":"562.644259ms","remote":"127.0.0.1:40026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:34:55.472504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.021942ms","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-09T01:34:55.472644Z","caller":"traceutil/trace.go:171","msg":"trace[1233434456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"483.214553ms","start":"2026-03-09T01:34:54.989398Z","end":"2026-03-09T01:34:55.472612Z","steps":["trace[1233434456] 'agreement among raft nodes before linearized reading' (duration: 482.82722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:55.472721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:54.989376Z","time spent":"483.329094ms","remote":"127.0.0.1:40098","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-09T01:34:55.473318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.52255ms","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-03-09T01:34:55.473436Z","caller":"traceutil/trace.go:171","msg":"trace[1653337384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"488.61941ms","start":"2026-03-09T01:34:54.98474Z","end":"2026-03-09T01:34:55.47336Z","steps":["trace[1653337384] 'agreement among raft nodes before linearized reading' (duration: 487.454802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:55.473479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:54.984719Z","time spent":"488.750652ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-09T01:34:56.391157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.75442ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:34:56.391305Z","caller":"traceutil/trace.go:171","msg":"trace[1196436028] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:557; }","duration":"164.917051ms","start":"2026-03-09T01:34:56.226369Z","end":"2026-03-09T01:34:56.391286Z","steps":["trace[1196436028] 'range keys from in-memory index tree' (duration: 164.73708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:56.391327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.910107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T01:34:56.391221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.499339ms","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-09T01:34:56.391423Z","caller":"traceutil/trace.go:171","msg":"trace[439753421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"492.056239ms","start":"2026-03-09T01:34:55.899345Z","end":"2026-03-09T01:34:56.391402Z","steps":["trace[439753421] 'range keys from in-memory index tree' (duration: 491.761566ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:34:56.391465Z","caller":"traceutil/trace.go:171","msg":"trace[1415088977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"575.777431ms","start":"2026-03-09T01:34:55.815678Z","end":"2026-03-09T01:34:56.391455Z","steps":["trace[1415088977] 'range keys from in-memory index tree' (duration: 575.287468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:56.391523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:55.815661Z","time spent":"575.853602ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T01:34:56.391517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:55.89933Z","time spent":"492.17718ms","remote":"127.0.0.1:39878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T01:34:57.039291Z","caller":"traceutil/trace.go:171","msg":"trace[2003428499] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"134.075806ms","start":"2026-03-09T01:34:56.905198Z","end":"2026-03-09T01:34:57.039274Z","steps":["trace[2003428499] 'read index received' (duration: 133.920105ms)","trace[2003428499] 'applied index is now lower than readState.Index' (duration: 152.911µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:34:57.039377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.165186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:34:57.039401Z","caller":"traceutil/trace.go:171","msg":"trace[471820025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"134.222217ms","start":"2026-03-09T01:34:56.905171Z","end":"2026-03-09T01:34:57.039393Z","steps":["trace[471820025] 'agreement among raft nodes before linearized reading' (duration: 134.171246ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:34:57.039514Z","caller":"traceutil/trace.go:171","msg":"trace[1380067934] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"643.125946ms","start":"2026-03-09T01:34:56.396347Z","end":"2026-03-09T01:34:57.039473Z","steps":["trace[1380067934] 'process raft request' (duration: 642.814254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:57.039644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:56.396322Z","time spent":"643.253917ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:34:59.185334Z","caller":"traceutil/trace.go:171","msg":"trace[313776747] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"264.686424ms","start":"2026-03-09T01:34:58.920626Z","end":"2026-03-09T01:34:59.185312Z","steps":["trace[313776747] 'process raft request' (duration: 264.558173ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:34:59.185404Z","caller":"traceutil/trace.go:171","msg":"trace[740554949] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:610; }","duration":"141.731924ms","start":"2026-03-09T01:34:59.043659Z","end":"2026-03-09T01:34:59.185391Z","steps":["trace[740554949] 'read index received' (duration: 141.722614ms)","trace[740554949] 'applied index is now lower than readState.Index' (duration: 5.29µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:34:59.185533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.868595ms","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-09T01:34:59.185554Z","caller":"traceutil/trace.go:171","msg":"trace[1699665231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"141.907876ms","start":"2026-03-09T01:34:59.043641Z","end":"2026-03-09T01:34:59.185549Z","steps":["trace[1699665231] 'agreement among raft nodes before linearized reading' (duration: 141.786135ms)"],"step_count":1}