{"level":"warn","ts":"2026-10-01T19:07:07.011408Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-01T19:07:07.011521Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.54: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.54:2380","--initial-cluster=instance=https://162.253.55.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.54: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-10-01T19:07:07.0116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-01T19:07:07.011613Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.54:2380"]} {"level":"info","ts":"2026-10-01T19:07:07.011636Z","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-10-01T19:07:07.012349Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"]} {"level":"info","ts":"2026-10-01T19:07:07.012424Z","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.54:2380"],"listen-peer-urls":["https://162.253.55.54:2380"],"advertise-client-urls":["https://162.253.55.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.54: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-10-01T19:07:07.01791Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.259583ms"} {"level":"info","ts":"2026-10-01T19:07:07.030075Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"353030364a385dbf","cluster-id":"1e619908a4cffd8f"} {"level":"info","ts":"2026-10-01T19:07:07.030137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=()"} {"level":"info","ts":"2026-10-01T19:07:07.030161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became follower at term 0"} {"level":"info","ts":"2026-10-01T19:07:07.03017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 353030364a385dbf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-01T19:07:07.030176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became follower at term 1"} {"level":"info","ts":"2026-10-01T19:07:07.030215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=(3832616292623867327)"} {"level":"warn","ts":"2026-10-01T19:07:07.037887Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-01T19:07:07.03933Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-01T19:07:07.040571Z","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-10-01T19:07:07.042507Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"353030364a385dbf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-01T19:07:07.043728Z","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-10-01T19:07:07.043847Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"353030364a385dbf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-01T19:07:07.043949Z","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-10-01T19:07:07.043998Z","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-10-01T19:07:07.044196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=(3832616292623867327)"} {"level":"info","ts":"2026-10-01T19:07:07.044597Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e619908a4cffd8f","local-member-id":"353030364a385dbf","added-peer-id":"353030364a385dbf","added-peer-peer-urls":["https://162.253.55.54:2380"]} {"level":"info","ts":"2026-10-01T19:07:07.048566Z","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-10-01T19:07:07.048676Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.54:2380"} {"level":"info","ts":"2026-10-01T19:07:07.048718Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.54:2380"} {"level":"info","ts":"2026-10-01T19:07:07.049293Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"353030364a385dbf","initial-advertise-peer-urls":["https://162.253.55.54:2380"],"listen-peer-urls":["https://162.253.55.54:2380"],"advertise-client-urls":["https://162.253.55.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-01T19:07:07.049418Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-01T19:07:07.430645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf is starting a new election at term 1"} {"level":"info","ts":"2026-10-01T19:07:07.430716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became pre-candidate at term 1"} {"level":"info","ts":"2026-10-01T19:07:07.43075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf received MsgPreVoteResp from 353030364a385dbf at term 1"} {"level":"info","ts":"2026-10-01T19:07:07.43077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became candidate at term 2"} {"level":"info","ts":"2026-10-01T19:07:07.430782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf received MsgVoteResp from 353030364a385dbf at term 2"} {"level":"info","ts":"2026-10-01T19:07:07.430797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became leader at term 2"} {"level":"info","ts":"2026-10-01T19:07:07.430811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 353030364a385dbf elected leader 353030364a385dbf at term 2"} {"level":"info","ts":"2026-10-01T19:07:07.431779Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"353030364a385dbf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.54:2379]}","request-path":"/0/members/353030364a385dbf/attributes","cluster-id":"1e619908a4cffd8f","publish-timeout":"7s"} {"level":"info","ts":"2026-10-01T19:07:07.43183Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-01T19:07:07.431917Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T19:07:07.43188Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-01T19:07:07.432187Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-01T19:07:07.432218Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-01T19:07:07.432669Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e619908a4cffd8f","local-member-id":"353030364a385dbf","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T19:07:07.432767Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T19:07:07.432793Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T19:07:07.435754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.54:2379"} {"level":"info","ts":"2026-10-01T19:07:07.436611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-01T19:07:10.886885Z","caller":"traceutil/trace.go:171","msg":"trace[1375780511] linearizableReadLoop","detail":"{readStateIndex:149; appliedIndex:148; }","duration":"391.872907ms","start":"2026-10-01T19:07:10.494976Z","end":"2026-10-01T19:07:10.886849Z","steps":["trace[1375780511] 'read index received' (duration: 391.693201ms)","trace[1375780511] 'applied index is now lower than readState.Index' (duration: 178.166µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:07:10.886974Z","caller":"traceutil/trace.go:171","msg":"trace[824227900] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"394.718306ms","start":"2026-10-01T19:07:10.492203Z","end":"2026-10-01T19:07:10.886921Z","steps":["trace[824227900] 'process raft request' (duration: 394.463088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:07:10.887128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.079663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T19:07:10.887229Z","caller":"traceutil/trace.go:171","msg":"trace[293590977] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:0; response_revision:144; }","duration":"392.244789ms","start":"2026-10-01T19:07:10.494968Z","end":"2026-10-01T19:07:10.887213Z","steps":["trace[293590977] 'agreement among raft nodes before linearized reading' (duration: 392.019462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:07:10.887276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T19:07:10.494915Z","time spent":"392.350761ms","remote":"127.0.0.1:50512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:kube-scheduler\" "} {"level":"warn","ts":"2026-10-01T19:07:10.887731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T19:07:10.492188Z","time spent":"394.918892ms","remote":"127.0.0.1:50372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-01T19:07:11.156954Z","caller":"traceutil/trace.go:171","msg":"trace[1183527660] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"264.560945ms","start":"2026-10-01T19:07:10.892342Z","end":"2026-10-01T19:07:11.156903Z","steps":["trace[1183527660] 'process raft request' (duration: 229.415037ms)","trace[1183527660] 'compare' (duration: 34.958572ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:07:44.601714Z","caller":"traceutil/trace.go:171","msg":"trace[853448613] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"112.514969ms","start":"2026-10-01T19:07:44.489166Z","end":"2026-10-01T19:07:44.601681Z","steps":["trace[853448613] 'process raft request' (duration: 112.023153ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:07:46.984624Z","caller":"traceutil/trace.go:171","msg":"trace[1387142412] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"100.043795ms","start":"2026-10-01T19:07:46.884548Z","end":"2026-10-01T19:07:46.984592Z","steps":["trace[1387142412] 'process raft request' (duration: 99.805817ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:07:50.14015Z","caller":"traceutil/trace.go:171","msg":"trace[1168383011] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"163.222674ms","start":"2026-10-01T19:07:49.976901Z","end":"2026-10-01T19:07:50.140124Z","steps":["trace[1168383011] 'process raft request' (duration: 133.330898ms)","trace[1168383011] 'compare' (duration: 29.750181ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T19:07:52.077409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.013208ms","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-10-01T19:07:52.077544Z","caller":"traceutil/trace.go:171","msg":"trace[473404128] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"110.121722ms","start":"2026-10-01T19:07:51.967393Z","end":"2026-10-01T19:07:52.077515Z","steps":["trace[473404128] 'process raft request' (duration: 18.093463ms)","trace[473404128] 'compare' (duration: 91.882465ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:07:52.077534Z","caller":"traceutil/trace.go:171","msg":"trace[20957827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"116.182995ms","start":"2026-10-01T19:07:51.961308Z","end":"2026-10-01T19:07:52.077491Z","steps":["trace[20957827] 'range keys from in-memory index tree' (duration: 115.900656ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:07:54.011901Z","caller":"traceutil/trace.go:171","msg":"trace[655220691] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"150.299291ms","start":"2026-10-01T19:07:53.861578Z","end":"2026-10-01T19:07:54.011878Z","steps":["trace[655220691] 'read index received' (duration: 150.077644ms)","trace[655220691] 'applied index is now lower than readState.Index' (duration: 217.627µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:07:54.012092Z","caller":"traceutil/trace.go:171","msg":"trace[2133429425] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"371.873313ms","start":"2026-10-01T19:07:53.640148Z","end":"2026-10-01T19:07:54.012021Z","steps":["trace[2133429425] 'process raft request' (duration: 371.577493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:07:54.012182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.5709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-01T19:07:54.012229Z","caller":"traceutil/trace.go:171","msg":"trace[1776992677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"150.696724ms","start":"2026-10-01T19:07:53.861519Z","end":"2026-10-01T19:07:54.012216Z","steps":["trace[1776992677] 'agreement among raft nodes before linearized reading' (duration: 150.475527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:07:54.012348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T19:07:53.640118Z","time spent":"372.124051ms","remote":"127.0.0.1:50684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-01T19:09:37.129084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.51507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T19:09:37.129379Z","caller":"traceutil/trace.go:171","msg":"trace[991894373] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"408.3522ms","start":"2026-10-01T19:09:36.720978Z","end":"2026-10-01T19:09:37.12933Z","steps":["trace[991894373] 'process raft request' (duration: 299.115717ms)","trace[991894373] 'compare' (duration: 108.232769ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:09:37.129433Z","caller":"traceutil/trace.go:171","msg":"trace[1006058221] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"383.505053ms","start":"2026-10-01T19:09:36.745861Z","end":"2026-10-01T19:09:37.129366Z","steps":["trace[1006058221] 'read index received' (duration: 274.13086ms)","trace[1006058221] 'applied index is now lower than readState.Index' (duration: 109.370883ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T19:09:37.129607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T19:09:36.720939Z","time spent":"408.53063ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T19:09:37.129673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.776934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-01T19:09:37.12972Z","caller":"traceutil/trace.go:171","msg":"trace[1335540262] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1914; }","duration":"383.903754ms","start":"2026-10-01T19:09:36.745805Z","end":"2026-10-01T19:09:37.129708Z","steps":["trace[1335540262] 'agreement among raft nodes before linearized reading' (duration: 383.778644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:09:37.129764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T19:09:36.745775Z","time spent":"383.978015ms","remote":"127.0.0.1:57632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-01T19:09:37.12988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.665505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T19:09:37.130015Z","caller":"traceutil/trace.go:171","msg":"trace[290019477] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1914; }","duration":"238.886036ms","start":"2026-10-01T19:09:36.891099Z","end":"2026-10-01T19:09:37.129985Z","steps":["trace[290019477] 'agreement among raft nodes before linearized reading' (duration: 238.607464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:09:37.130154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.658871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-01T19:09:37.130276Z","caller":"traceutil/trace.go:171","msg":"trace[316667679] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1914; }","duration":"209.866851ms","start":"2026-10-01T19:09:36.920379Z","end":"2026-10-01T19:09:37.130246Z","steps":["trace[316667679] 'agreement among raft nodes before linearized reading' (duration: 209.417129ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:09:37.447554Z","caller":"traceutil/trace.go:171","msg":"trace[265913836] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"231.372369ms","start":"2026-10-01T19:09:37.216136Z","end":"2026-10-01T19:09:37.447508Z","steps":["trace[265913836] 'read index received' (duration: 211.759317ms)","trace[265913836] 'applied index is now lower than readState.Index' (duration: 19.610622ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:09:37.447704Z","caller":"traceutil/trace.go:171","msg":"trace[551678109] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"115.781409ms","start":"2026-10-01T19:09:37.331892Z","end":"2026-10-01T19:09:37.447673Z","steps":["trace[551678109] 'process raft request' (duration: 115.517198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:09:37.448118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.766321ms","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-10-01T19:09:37.448217Z","caller":"traceutil/trace.go:171","msg":"trace[1403557236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1915; }","duration":"232.139482ms","start":"2026-10-01T19:09:37.216056Z","end":"2026-10-01T19:09:37.448196Z","steps":["trace[1403557236] 'agreement among raft nodes before linearized reading' (duration: 231.5451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:09:37.448465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.920435ms","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-10-01T19:09:37.448542Z","caller":"traceutil/trace.go:171","msg":"trace[732784007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1916; }","duration":"187.040175ms","start":"2026-10-01T19:09:37.261489Z","end":"2026-10-01T19:09:37.448529Z","steps":["trace[732784007] 'agreement among raft nodes before linearized reading' (duration: 186.396023ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:09:51.349927Z","caller":"traceutil/trace.go:171","msg":"trace[721736834] linearizableReadLoop","detail":"{readStateIndex:2118; appliedIndex:2117; }","duration":"137.003523ms","start":"2026-10-01T19:09:51.212901Z","end":"2026-10-01T19:09:51.349904Z","steps":["trace[721736834] 'read index received' (duration: 136.825312ms)","trace[721736834] 'applied index is now lower than readState.Index' (duration: 177.351µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:09:51.350027Z","caller":"traceutil/trace.go:171","msg":"trace[960709509] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"170.473048ms","start":"2026-10-01T19:09:51.179512Z","end":"2026-10-01T19:09:51.349985Z","steps":["trace[960709509] 'process raft request' (duration: 170.218637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:09:51.350173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.202734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-01T19:09:51.350222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.275774ms","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-10-01T19:09:51.350299Z","caller":"traceutil/trace.go:171","msg":"trace[465341354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2041; }","duration":"134.359824ms","start":"2026-10-01T19:09:51.215896Z","end":"2026-10-01T19:09:51.350256Z","steps":["trace[465341354] 'agreement among raft nodes before linearized reading' (duration: 134.185683ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:09:51.350329Z","caller":"traceutil/trace.go:171","msg":"trace[2122680498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2041; }","duration":"137.389136ms","start":"2026-10-01T19:09:51.212854Z","end":"2026-10-01T19:09:51.350243Z","steps":["trace[2122680498] 'agreement among raft nodes before linearized reading' (duration: 137.190635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:09:51.350386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.397883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-01T19:09:51.350495Z","caller":"traceutil/trace.go:171","msg":"trace[461159900] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2041; }","duration":"111.536995ms","start":"2026-10-01T19:09:51.238934Z","end":"2026-10-01T19:09:51.350471Z","steps":["trace[461159900] 'agreement among raft nodes before linearized reading' (duration: 111.192062ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:09:51.461868Z","caller":"traceutil/trace.go:171","msg":"trace[1718084414] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"105.842404ms","start":"2026-10-01T19:09:51.355992Z","end":"2026-10-01T19:09:51.461834Z","steps":["trace[1718084414] 'process raft request' (duration: 103.665949ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:10:08.829282Z","caller":"traceutil/trace.go:171","msg":"trace[2109137087] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"134.225551ms","start":"2026-10-01T19:10:08.695025Z","end":"2026-10-01T19:10:08.829251Z","steps":["trace[2109137087] 'process raft request' (duration: 129.833467ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:10:09.690515Z","caller":"traceutil/trace.go:171","msg":"trace[1183386069] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"129.97022ms","start":"2026-10-01T19:10:09.560499Z","end":"2026-10-01T19:10:09.690469Z","steps":["trace[1183386069] 'process raft request' (duration: 82.559131ms)","trace[1183386069] 'compare' (duration: 47.256388ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:10:09.698911Z","caller":"traceutil/trace.go:171","msg":"trace[1852943506] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"109.023023ms","start":"2026-10-01T19:10:09.58987Z","end":"2026-10-01T19:10:09.698893Z","steps":["trace[1852943506] 'process raft request' (duration: 108.652689ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:11:56.860292Z","caller":"traceutil/trace.go:171","msg":"trace[831590298] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"140.778637ms","start":"2026-10-01T19:11:56.719488Z","end":"2026-10-01T19:11:56.860267Z","steps":["trace[831590298] 'process raft request' (duration: 140.381868ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:12:02.459914Z","caller":"traceutil/trace.go:171","msg":"trace[1543683929] linearizableReadLoop","detail":"{readStateIndex:2964; appliedIndex:2963; }","duration":"153.349406ms","start":"2026-10-01T19:12:02.306537Z","end":"2026-10-01T19:12:02.459886Z","steps":["trace[1543683929] 'read index received' (duration: 153.218433ms)","trace[1543683929] 'applied index is now lower than readState.Index' (duration: 129.633µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T19:12:02.460003Z","caller":"traceutil/trace.go:171","msg":"trace[717362842] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"155.106787ms","start":"2026-10-01T19:12:02.304846Z","end":"2026-10-01T19:12:02.459953Z","steps":["trace[717362842] 'process raft request' (duration: 154.869511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:12:02.460143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.584562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-01T19:12:02.460186Z","caller":"traceutil/trace.go:171","msg":"trace[981446365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2857; }","duration":"153.706285ms","start":"2026-10-01T19:12:02.306465Z","end":"2026-10-01T19:12:02.460171Z","steps":["trace[981446365] 'agreement among raft nodes before linearized reading' (duration: 153.537621ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:12:02.472699Z","caller":"traceutil/trace.go:171","msg":"trace[2049629083] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"163.893269ms","start":"2026-10-01T19:12:02.308789Z","end":"2026-10-01T19:12:02.472683Z","steps":["trace[2049629083] 'process raft request' (duration: 163.782027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T19:16:51.568261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.467955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T19:16:51.568359Z","caller":"traceutil/trace.go:171","msg":"trace[96909013] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5136; }","duration":"256.631829ms","start":"2026-10-01T19:16:51.311707Z","end":"2026-10-01T19:16:51.568338Z","steps":["trace[96909013] 'count revisions from in-memory index tree' (duration: 256.378451ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:16:52.112242Z","caller":"traceutil/trace.go:171","msg":"trace[908263202] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"153.232088ms","start":"2026-10-01T19:16:51.958964Z","end":"2026-10-01T19:16:52.112196Z","steps":["trace[908263202] 'process raft request' (duration: 153.011221ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T19:17:07.672209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2887} {"level":"info","ts":"2026-10-01T19:17:07.768893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2887,"took":"95.152888ms","hash":257469261,"current-db-size-bytes":52666368,"current-db-size":"53 MB","current-db-size-in-use-bytes":27557888,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-01T19:17:07.768969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":257469261,"revision":2887,"compact-revision":-1} {"level":"info","ts":"2026-10-01T19:22:07.679661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5355} {"level":"info","ts":"2026-10-01T19:22:07.770218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5355,"took":"88.010401ms","hash":1174268410,"current-db-size-bytes":52666368,"current-db-size":"53 MB","current-db-size-in-use-bytes":18903040,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-01T19:22:07.770293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1174268410,"revision":5355,"compact-revision":2887}