{"level":"warn","ts":"2026-07-01T09:51:56.523157Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T09:51:56.523258Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-07-01T09:51:56.523326Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T09:51:56.523341Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-01T09:51:56.523362Z","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-07-01T09:51:56.524041Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-07-01T09:51:56.524113Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-07-01T09:51:56.538732Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.398172ms"} {"level":"info","ts":"2026-07-01T09:51:56.610202Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-07-01T09:51:56.610297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T09:51:56.610318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-07-01T09:51:56.610328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T09:51:56.610338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-07-01T09:51:56.610379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-07-01T09:51:56.644211Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T09:51:56.656733Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T09:51:56.675962Z","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-07-01T09:51:56.686679Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T09:51:56.686947Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T09:51:56.687203Z","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-07-01T09:51:56.687297Z","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-07-01T09:51:56.687317Z","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-07-01T09:51:56.692119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-07-01T09:51:56.692292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-01T09:51:56.693558Z","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-07-01T09:51:56.693691Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-01T09:51:56.69379Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-01T09:51:56.694915Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T09:51:56.694998Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T09:51:57.511272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T09:51:57.511335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T09:51:57.511363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-07-01T09:51:57.511384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-07-01T09:51:57.511396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-01T09:51:57.511414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-07-01T09:51:57.51143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-01T09:51:57.512516Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T09:51:57.513457Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T09:51:57.513576Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T09:51:57.513463Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T09:51:57.513715Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T09:51:57.51373Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T09:51:57.515008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T09:51:57.515114Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T09:51:57.515145Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T09:51:57.5154Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T09:51:57.517193Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-07-01T09:51:59.251303Z","caller":"traceutil/trace.go:171","msg":"trace[1592437601] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"112.823023ms","start":"2026-07-01T09:51:59.138444Z","end":"2026-07-01T09:51:59.251268Z","steps":["trace[1592437601] 'process raft request' (duration: 24.174503ms)","trace[1592437601] 'compare' (duration: 88.485538ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:51:59.28124Z","caller":"traceutil/trace.go:171","msg":"trace[1894102786] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"142.12445ms","start":"2026-07-01T09:51:59.139088Z","end":"2026-07-01T09:51:59.281212Z","steps":["trace[1894102786] 'process raft request' (duration: 141.913377ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:51:59.28149Z","caller":"traceutil/trace.go:171","msg":"trace[1471063547] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"142.321771ms","start":"2026-07-01T09:51:59.139107Z","end":"2026-07-01T09:51:59.281429Z","steps":["trace[1471063547] 'process raft request' (duration: 142.061818ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:01.411951Z","caller":"traceutil/trace.go:171","msg":"trace[59847533] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"102.98396ms","start":"2026-07-01T09:52:01.308936Z","end":"2026-07-01T09:52:01.41192Z","steps":["trace[59847533] 'process raft request' (duration: 27.497392ms)","trace[59847533] 'compare' (duration: 75.328656ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:52:04.026605Z","caller":"traceutil/trace.go:171","msg":"trace[781206194] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"111.574436ms","start":"2026-07-01T09:52:03.91499Z","end":"2026-07-01T09:52:04.026565Z","steps":["trace[781206194] 'process raft request' (duration: 63.917869ms)","trace[781206194] 'compare' (duration: 47.589586ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:52:04.029373Z","caller":"traceutil/trace.go:171","msg":"trace[364974921] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"109.667592ms","start":"2026-07-01T09:52:03.919688Z","end":"2026-07-01T09:52:04.029355Z","steps":["trace[364974921] 'process raft request' (duration: 109.47521ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:04.034104Z","caller":"traceutil/trace.go:171","msg":"trace[1907160815] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"102.731476ms","start":"2026-07-01T09:52:03.931324Z","end":"2026-07-01T09:52:04.034055Z","steps":["trace[1907160815] 'process raft request' (duration: 98.012268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:37.068338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.502914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T09:52:37.068478Z","caller":"traceutil/trace.go:171","msg":"trace[1349568828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"177.67866ms","start":"2026-07-01T09:52:36.890781Z","end":"2026-07-01T09:52:37.068459Z","steps":["trace[1349568828] 'range keys from in-memory index tree' (duration: 177.429761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:37.068657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.268995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-07-01T09:52:37.068767Z","caller":"traceutil/trace.go:171","msg":"trace[1147727085] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:602; }","duration":"172.390178ms","start":"2026-07-01T09:52:36.896312Z","end":"2026-07-01T09:52:37.068702Z","steps":["trace[1147727085] 'range keys from in-memory index tree' (duration: 171.89429ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:37.254844Z","caller":"traceutil/trace.go:171","msg":"trace[1794050127] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"184.631309ms","start":"2026-07-01T09:52:37.070191Z","end":"2026-07-01T09:52:37.254823Z","steps":["trace[1794050127] 'read index received' (duration: 182.962389ms)","trace[1794050127] 'applied index is now lower than readState.Index' (duration: 1.66803ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:52:37.254919Z","caller":"traceutil/trace.go:171","msg":"trace[1405631402] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"224.512213ms","start":"2026-07-01T09:52:37.030376Z","end":"2026-07-01T09:52:37.254888Z","steps":["trace[1405631402] 'process raft request' (duration: 223.216086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:37.254975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.763684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T09:52:37.25531Z","caller":"traceutil/trace.go:171","msg":"trace[293398512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"184.848378ms","start":"2026-07-01T09:52:37.070151Z","end":"2026-07-01T09:52:37.254999Z","steps":["trace[293398512] 'agreement among raft nodes before linearized reading' (duration: 184.778365ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:37.259987Z","caller":"traceutil/trace.go:171","msg":"trace[1797732122] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"130.807007ms","start":"2026-07-01T09:52:37.129163Z","end":"2026-07-01T09:52:37.25997Z","steps":["trace[1797732122] 'process raft request' (duration: 130.472425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:37.272138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.268904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-07-01T09:52:37.272185Z","caller":"traceutil/trace.go:171","msg":"trace[1004740147] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"192.343956ms","start":"2026-07-01T09:52:37.079831Z","end":"2026-07-01T09:52:37.272175Z","steps":["trace[1004740147] 'agreement among raft nodes before linearized reading' (duration: 192.241823ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:41.689197Z","caller":"traceutil/trace.go:171","msg":"trace[936402119] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"155.366554ms","start":"2026-07-01T09:52:41.533809Z","end":"2026-07-01T09:52:41.689176Z","steps":["trace[936402119] 'process raft request' (duration: 155.109175ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:43.20105Z","caller":"traceutil/trace.go:171","msg":"trace[1594055449] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"239.773088ms","start":"2026-07-01T09:52:42.961216Z","end":"2026-07-01T09:52:43.200989Z","steps":["trace[1594055449] 'process raft request' (duration: 239.595161ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:43.445599Z","caller":"traceutil/trace.go:171","msg":"trace[869341416] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"318.443254ms","start":"2026-07-01T09:52:43.127111Z","end":"2026-07-01T09:52:43.445554Z","steps":["trace[869341416] 'process raft request' (duration: 311.24246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:43.44697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:43.127084Z","time spent":"318.59449ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T09:52:43.789535Z","caller":"traceutil/trace.go:171","msg":"trace[1564031792] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"297.800324ms","start":"2026-07-01T09:52:43.491707Z","end":"2026-07-01T09:52:43.789508Z","steps":["trace[1564031792] 'process raft request' (duration: 297.630798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:44.929901Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341748874332053,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T09:52:45.108153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194376392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-01T09:52:45.108201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.87782ms","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-07-01T09:52:45.108217Z","caller":"traceutil/trace.go:171","msg":"trace[588583587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"1.194472255s","start":"2026-07-01T09:52:43.913727Z","end":"2026-07-01T09:52:45.108199Z","steps":["trace[588583587] 'range keys from in-memory index tree' (duration: 1.194288809s)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:45.108243Z","caller":"traceutil/trace.go:171","msg":"trace[1826664979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"994.946472ms","start":"2026-07-01T09:52:44.113287Z","end":"2026-07-01T09:52:45.108234Z","steps":["trace[1826664979] 'range keys from in-memory index tree' (duration: 994.770176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:45.108251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:43.913709Z","time spent":"1.194535408s","remote":"127.0.0.1:48104","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-07-01T09:52:45.108264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:44.113273Z","time spent":"994.985873ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T09:52:45.178978Z","caller":"traceutil/trace.go:171","msg":"trace[117654674] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"964.238527ms","start":"2026-07-01T09:52:44.214661Z","end":"2026-07-01T09:52:45.1789Z","steps":["trace[117654674] 'process raft request' (duration: 963.605815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:45.179151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:44.214631Z","time spent":"964.432673ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T09:52:45.182447Z","caller":"traceutil/trace.go:171","msg":"trace[874255699] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"752.993957ms","start":"2026-07-01T09:52:44.429426Z","end":"2026-07-01T09:52:45.18242Z","steps":["trace[874255699] 'read index received' (duration: 748.802547ms)","trace[874255699] 'applied index is now lower than readState.Index' (duration: 4.19023ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:52:45.182769Z","caller":"traceutil/trace.go:171","msg":"trace[1322582659] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"500.355896ms","start":"2026-07-01T09:52:44.682399Z","end":"2026-07-01T09:52:45.182755Z","steps":["trace[1322582659] 'process raft request' (duration: 499.842199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:45.182883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:44.682375Z","time spent":"500.436789ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T09:52:45.182993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.547235ms","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-07-01T09:52:45.183103Z","caller":"traceutil/trace.go:171","msg":"trace[22438510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"753.665009ms","start":"2026-07-01T09:52:44.429389Z","end":"2026-07-01T09:52:45.183055Z","steps":["trace[22438510] 'agreement among raft nodes before linearized reading' (duration: 753.372639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:45.183144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:44.429368Z","time spent":"753.764283ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T09:52:45.183208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.842556ms","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-07-01T09:52:45.183255Z","caller":"traceutil/trace.go:171","msg":"trace[1495334938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"638.964599ms","start":"2026-07-01T09:52:44.544277Z","end":"2026-07-01T09:52:45.183241Z","steps":["trace[1495334938] 'agreement among raft nodes before linearized reading' (duration: 638.799164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:52:45.18329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:44.544257Z","time spent":"639.025912ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T09:52:45.183395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.106733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T09:52:45.183425Z","caller":"traceutil/trace.go:171","msg":"trace[19640037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"299.152984ms","start":"2026-07-01T09:52:44.884264Z","end":"2026-07-01T09:52:45.183417Z","steps":["trace[19640037] 'agreement among raft nodes before linearized reading' (duration: 299.109673ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:46.296993Z","caller":"traceutil/trace.go:171","msg":"trace[1470213299] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"115.669753ms","start":"2026-07-01T09:52:46.181287Z","end":"2026-07-01T09:52:46.296957Z","steps":["trace[1470213299] 'process raft request' (duration: 115.081913ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:52:57.182688Z","caller":"traceutil/trace.go:171","msg":"trace[718473123] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"142.569768ms","start":"2026-07-01T09:52:57.040091Z","end":"2026-07-01T09:52:57.182661Z","steps":["trace[718473123] 'process raft request' (duration: 142.336541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:53:00.070103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.261587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T09:53:00.070224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.49936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T09:53:00.070261Z","caller":"traceutil/trace.go:171","msg":"trace[1069434593] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:803; }","duration":"432.573522ms","start":"2026-07-01T09:52:59.637677Z","end":"2026-07-01T09:53:00.07025Z","steps":["trace[1069434593] 'count revisions from in-memory index tree' (duration: 432.440108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:53:00.070158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.190568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T09:53:00.07022Z","caller":"traceutil/trace.go:171","msg":"trace[504407965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"183.414291ms","start":"2026-07-01T09:52:59.886784Z","end":"2026-07-01T09:53:00.070198Z","steps":["trace[504407965] 'range keys from in-memory index tree' (duration: 183.188364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:53:00.0703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:59.637651Z","time spent":"432.638724ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-07-01T09:53:00.070305Z","caller":"traceutil/trace.go:171","msg":"trace[1000541280] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:803; }","duration":"584.383593ms","start":"2026-07-01T09:52:59.485909Z","end":"2026-07-01T09:53:00.070292Z","steps":["trace[1000541280] 'count revisions from in-memory index tree' (duration: 584.114375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:53:00.070344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:59.485889Z","time spent":"584.441195ms","remote":"127.0.0.1:41478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-07-01T09:53:00.268325Z","caller":"traceutil/trace.go:171","msg":"trace[678633550] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"330.707232ms","start":"2026-07-01T09:52:59.937595Z","end":"2026-07-01T09:53:00.268302Z","steps":["trace[678633550] 'process raft request' (duration: 330.541487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:53:00.268554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:52:59.93757Z","time spent":"330.829835ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T09:53:00.285206Z","caller":"traceutil/trace.go:171","msg":"trace[818967939] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:833; }","duration":"213.610499ms","start":"2026-07-01T09:53:00.071582Z","end":"2026-07-01T09:53:00.285193Z","steps":["trace[818967939] 'read index received' (duration: 196.565528ms)","trace[818967939] 'applied index is now lower than readState.Index' (duration: 17.044211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T09:53:00.285277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.683801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T09:53:00.285306Z","caller":"traceutil/trace.go:171","msg":"trace[1476778020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"213.735923ms","start":"2026-07-01T09:53:00.07156Z","end":"2026-07-01T09:53:00.285296Z","steps":["trace[1476778020] 'agreement among raft nodes before linearized reading' (duration: 213.683661ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:53:00.285448Z","caller":"traceutil/trace.go:171","msg":"trace[368975887] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"224.55086ms","start":"2026-07-01T09:53:00.060846Z","end":"2026-07-01T09:53:00.285397Z","steps":["trace[368975887] 'process raft request' (duration: 212.893837ms)","trace[368975887] 'compare' (duration: 11.380255ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:53:12.284178Z","caller":"traceutil/trace.go:171","msg":"trace[1167283481] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"104.005953ms","start":"2026-07-01T09:53:12.180151Z","end":"2026-07-01T09:53:12.284157Z","steps":["trace[1167283481] 'process raft request' (duration: 103.709745ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:53:45.444977Z","caller":"traceutil/trace.go:171","msg":"trace[594204086] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"367.379187ms","start":"2026-07-01T09:53:45.077571Z","end":"2026-07-01T09:53:45.44495Z","steps":["trace[594204086] 'process raft request' (duration: 367.201773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:53:45.445208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:53:45.077554Z","time spent":"367.53097ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T09:53:45.848523Z","caller":"traceutil/trace.go:171","msg":"trace[1184774742] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"140.174843ms","start":"2026-07-01T09:53:45.708322Z","end":"2026-07-01T09:53:45.848497Z","steps":["trace[1184774742] 'process raft request' (duration: 140.03549ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:54:43.474172Z","caller":"traceutil/trace.go:171","msg":"trace[597849732] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"111.809662ms","start":"2026-07-01T09:54:43.362342Z","end":"2026-07-01T09:54:43.474151Z","steps":["trace[597849732] 'process raft request' (duration: 111.69473ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:54:44.240486Z","caller":"traceutil/trace.go:171","msg":"trace[1588410937] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"157.139648ms","start":"2026-07-01T09:54:44.083308Z","end":"2026-07-01T09:54:44.240448Z","steps":["trace[1588410937] 'process raft request' (duration: 156.157321ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:54:44.281691Z","caller":"traceutil/trace.go:171","msg":"trace[1049036717] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1363; }","duration":"138.921394ms","start":"2026-07-01T09:54:44.14274Z","end":"2026-07-01T09:54:44.281662Z","steps":["trace[1049036717] 'read index received' (duration: 96.818989ms)","trace[1049036717] 'applied index is now lower than readState.Index' (duration: 42.100745ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:54:44.28174Z","caller":"traceutil/trace.go:171","msg":"trace[880313631] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"198.249816ms","start":"2026-07-01T09:54:44.083449Z","end":"2026-07-01T09:54:44.281698Z","steps":["trace[880313631] 'process raft request' (duration: 198.043542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:54:44.281911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.153738ms","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-07-01T09:54:44.281974Z","caller":"traceutil/trace.go:171","msg":"trace[325109806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"139.272851ms","start":"2026-07-01T09:54:44.14269Z","end":"2026-07-01T09:54:44.281962Z","steps":["trace[325109806] 'agreement among raft nodes before linearized reading' (duration: 139.084547ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:54:54.764452Z","caller":"traceutil/trace.go:171","msg":"trace[1130192059] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"436.392744ms","start":"2026-07-01T09:54:54.328035Z","end":"2026-07-01T09:54:54.764428Z","steps":["trace[1130192059] 'read index received' (duration: 436.188881ms)","trace[1130192059] 'applied index is now lower than readState.Index' (duration: 202.973µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T09:54:54.764528Z","caller":"traceutil/trace.go:171","msg":"trace[1264010047] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"468.434872ms","start":"2026-07-01T09:54:54.296059Z","end":"2026-07-01T09:54:54.764494Z","steps":["trace[1264010047] 'process raft request' (duration: 468.226869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:54:54.764715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.647158ms","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-07-01T09:54:54.764781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.960293ms","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-07-01T09:54:54.764822Z","caller":"traceutil/trace.go:171","msg":"trace[915582304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1348; }","duration":"436.79052ms","start":"2026-07-01T09:54:54.328004Z","end":"2026-07-01T09:54:54.764794Z","steps":["trace[915582304] 'agreement among raft nodes before linearized reading' (duration: 436.526526ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T09:54:54.76483Z","caller":"traceutil/trace.go:171","msg":"trace[885369756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"431.037544ms","start":"2026-07-01T09:54:54.333781Z","end":"2026-07-01T09:54:54.764818Z","steps":["trace[885369756] 'agreement among raft nodes before linearized reading' (duration: 430.82505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T09:54:54.764892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:54:54.327984Z","time spent":"436.890652ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T09:54:54.764891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:54:54.33377Z","time spent":"431.111556ms","remote":"127.0.0.1:48104","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-07-01T09:54:54.764857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T09:54:54.296035Z","time spent":"468.623125ms","remote":"127.0.0.1:48104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}