{"level":"warn","ts":"2026-10-07T03:51:40.332054Z","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-07T03:51:40.332193Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27: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.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27: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-07T03:51:40.332355Z","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-07T03:51:40.332377Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-10-07T03:51:40.332422Z","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-07T03:51:40.333433Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-10-07T03:51:40.334922Z","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.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27: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-07T03:51:40.342073Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.654682ms"} {"level":"info","ts":"2026-10-07T03:51:40.350494Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-10-07T03:51:40.350632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-10-07T03:51:40.350677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-10-07T03:51:40.350705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-07T03:51:40.350727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-10-07T03:51:40.350796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-10-07T03:51:40.356866Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-07T03:51:40.358542Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-07T03:51:40.360141Z","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-07T03:51:40.362374Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-07T03:51:40.362651Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-07T03:51:40.363336Z","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-07T03:51:40.3637Z","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-07T03:51:40.363908Z","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-07T03:51:40.36478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-10-07T03:51:40.365363Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-10-07T03:51:40.368586Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-10-07T03:51:40.368625Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-10-07T03:51:40.368425Z","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-07T03:51:40.370264Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-07T03:51:40.37021Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-07T03:51:40.55167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-10-07T03:51:40.551767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-10-07T03:51:40.551806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-10-07T03:51:40.55183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-10-07T03:51:40.551848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-10-07T03:51:40.551867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-10-07T03:51:40.551887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-10-07T03:51:40.55334Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-10-07T03:51:40.55339Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T03:51:40.55341Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T03:51:40.553668Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:51:40.554939Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:51:40.555145Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:51:40.555186Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:51:40.563877Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-07T03:51:40.564243Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-10-07T03:51:40.565665Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-07T03:51:40.565706Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-10-07T03:52:27.445424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.598814ms","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-10-07T03:52:27.445654Z","caller":"traceutil/trace.go:171","msg":"trace[934235670] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:646; }","duration":"170.778318ms","start":"2026-10-07T03:52:27.274742Z","end":"2026-10-07T03:52:27.445521Z","steps":["trace[934235670] 'range keys from in-memory index tree' (duration: 170.339517ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:27.445772Z","caller":"traceutil/trace.go:171","msg":"trace[57199808] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"139.665856ms","start":"2026-10-07T03:52:27.306042Z","end":"2026-10-07T03:52:27.445708Z","steps":["trace[57199808] 'process raft request' (duration: 139.356068ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:27.454172Z","caller":"traceutil/trace.go:171","msg":"trace[1120111796] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"132.737376ms","start":"2026-10-07T03:52:27.321397Z","end":"2026-10-07T03:52:27.454135Z","steps":["trace[1120111796] 'process raft request' (duration: 132.450688ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:27.464067Z","caller":"traceutil/trace.go:171","msg":"trace[958973213] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"132.535002ms","start":"2026-10-07T03:52:27.331444Z","end":"2026-10-07T03:52:27.463979Z","steps":["trace[958973213] 'read index received' (duration: 114.079245ms)","trace[958973213] 'applied index is now lower than readState.Index' (duration: 18.454287ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:52:27.46486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.386914ms","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-07T03:52:27.465688Z","caller":"traceutil/trace.go:171","msg":"trace[1953266918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"134.238385ms","start":"2026-10-07T03:52:27.331418Z","end":"2026-10-07T03:52:27.465656Z","steps":["trace[1953266918] 'agreement among raft nodes before linearized reading' (duration: 132.761577ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:28.237749Z","caller":"traceutil/trace.go:171","msg":"trace[484642949] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"114.521429ms","start":"2026-10-07T03:52:28.123208Z","end":"2026-10-07T03:52:28.23773Z","steps":["trace[484642949] 'read index received' (duration: 114.325694ms)","trace[484642949] 'applied index is now lower than readState.Index' (duration: 195.025µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:52:28.237899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.653102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:52:28.237936Z","caller":"traceutil/trace.go:171","msg":"trace[1088097771] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:661; }","duration":"114.762685ms","start":"2026-10-07T03:52:28.123162Z","end":"2026-10-07T03:52:28.237924Z","steps":["trace[1088097771] 'agreement among raft nodes before linearized reading' (duration: 114.650002ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:28.238194Z","caller":"traceutil/trace.go:171","msg":"trace[1399586655] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"117.108866ms","start":"2026-10-07T03:52:28.121074Z","end":"2026-10-07T03:52:28.238183Z","steps":["trace[1399586655] 'process raft request' (duration: 116.535561ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:33.492565Z","caller":"traceutil/trace.go:171","msg":"trace[673016745] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"240.815377ms","start":"2026-10-07T03:52:33.251711Z","end":"2026-10-07T03:52:33.492527Z","steps":["trace[673016745] 'process raft request' (duration: 240.031436ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:33.501605Z","caller":"traceutil/trace.go:171","msg":"trace[1121341311] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"144.17085ms","start":"2026-10-07T03:52:33.357372Z","end":"2026-10-07T03:52:33.501543Z","steps":["trace[1121341311] 'process raft request' (duration: 144.072028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:52:33.722742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.685483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:52:33.722828Z","caller":"traceutil/trace.go:171","msg":"trace[777036893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"144.792896ms","start":"2026-10-07T03:52:33.578015Z","end":"2026-10-07T03:52:33.722808Z","steps":["trace[777036893] 'range keys from in-memory index tree' (duration: 144.606061ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:33.740044Z","caller":"traceutil/trace.go:171","msg":"trace[1647193476] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"160.223625ms","start":"2026-10-07T03:52:33.579798Z","end":"2026-10-07T03:52:33.740021Z","steps":["trace[1647193476] 'process raft request' (duration: 160.02887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:52:34.238678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.565221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-07T03:52:34.238845Z","caller":"traceutil/trace.go:171","msg":"trace[482555744] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"448.202051ms","start":"2026-10-07T03:52:33.79062Z","end":"2026-10-07T03:52:34.238822Z","steps":["trace[482555744] 'process raft request' (duration: 299.618544ms)","trace[482555744] 'compare' (duration: 147.264643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:52:34.238959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:52:33.790582Z","time spent":"448.309854ms","remote":"127.0.0.1:48934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T03:52:34.239118Z","caller":"traceutil/trace.go:171","msg":"trace[1121820449] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"443.890609ms","start":"2026-10-07T03:52:33.795209Z","end":"2026-10-07T03:52:34.239099Z","steps":["trace[1121820449] 'process raft request' (duration: 443.844118ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:34.239212Z","caller":"traceutil/trace.go:171","msg":"trace[1551654084] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"447.074111ms","start":"2026-10-07T03:52:33.792098Z","end":"2026-10-07T03:52:34.239172Z","steps":["trace[1551654084] 'process raft request' (duration: 446.918987ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:52:34.239388Z","caller":"traceutil/trace.go:171","msg":"trace[1194564272] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"448.725235ms","start":"2026-10-07T03:52:33.790646Z","end":"2026-10-07T03:52:34.239372Z","steps":["trace[1194564272] 'process raft request' (duration: 448.297224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:52:34.239241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:52:33.795191Z","time spent":"444.001942ms","remote":"127.0.0.1:48906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T03:52:34.239476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:52:33.790629Z","time spent":"448.808067ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-07T03:52:34.239398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:52:33.792079Z","time spent":"447.217135ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-07T03:54:10.228723Z","caller":"traceutil/trace.go:171","msg":"trace[616377424] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"153.31589ms","start":"2026-10-07T03:54:10.075382Z","end":"2026-10-07T03:54:10.228698Z","steps":["trace[616377424] 'process raft request' (duration: 153.137366ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:54:42.529086Z","caller":"traceutil/trace.go:171","msg":"trace[584492912] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"108.538755ms","start":"2026-10-07T03:54:42.420526Z","end":"2026-10-07T03:54:42.529064Z","steps":["trace[584492912] 'process raft request' (duration: 108.135444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:54:42.700517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.197092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:54:42.700609Z","caller":"traceutil/trace.go:171","msg":"trace[1100402040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2165; }","duration":"123.351147ms","start":"2026-10-07T03:54:42.577238Z","end":"2026-10-07T03:54:42.700589Z","steps":["trace[1100402040] 'range keys from in-memory index tree' (duration: 123.138281ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:31.266924Z","caller":"traceutil/trace.go:171","msg":"trace[624596895] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"116.6725ms","start":"2026-10-07T03:58:31.150227Z","end":"2026-10-07T03:58:31.266899Z","steps":["trace[624596895] 'process raft request' (duration: 116.519696ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:37.138025Z","caller":"traceutil/trace.go:171","msg":"trace[984062822] linearizableReadLoop","detail":"{readStateIndex:4818; appliedIndex:4817; }","duration":"126.178071ms","start":"2026-10-07T03:58:37.011821Z","end":"2026-10-07T03:58:37.137999Z","steps":["trace[984062822] 'read index received' (duration: 37.897432ms)","trace[984062822] 'applied index is now lower than readState.Index' (duration: 88.279679ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:58:37.138138Z","caller":"traceutil/trace.go:171","msg":"trace[1834625558] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"195.619649ms","start":"2026-10-07T03:58:36.942454Z","end":"2026-10-07T03:58:37.138074Z","steps":["trace[1834625558] 'process raft request' (duration: 107.35216ms)","trace[1834625558] 'compare' (duration: 88.050345ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:58:37.138318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.488147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-07T03:58:37.138358Z","caller":"traceutil/trace.go:171","msg":"trace[628420165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4620; }","duration":"126.547288ms","start":"2026-10-07T03:58:37.011799Z","end":"2026-10-07T03:58:37.138346Z","steps":["trace[628420165] 'agreement among raft nodes before linearized reading' (duration: 126.343274ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:37.508729Z","caller":"traceutil/trace.go:171","msg":"trace[1926227258] linearizableReadLoop","detail":"{readStateIndex:4828; appliedIndex:4827; }","duration":"221.963157ms","start":"2026-10-07T03:58:37.286719Z","end":"2026-10-07T03:58:37.508682Z","steps":["trace[1926227258] 'read index received' (duration: 152.971167ms)","trace[1926227258] 'applied index is now lower than readState.Index' (duration: 68.99004ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:58:37.508784Z","caller":"traceutil/trace.go:171","msg":"trace[1950765700] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"224.620274ms","start":"2026-10-07T03:58:37.284115Z","end":"2026-10-07T03:58:37.508736Z","steps":["trace[1950765700] 'process raft request' (duration: 155.609063ms)","trace[1950765700] 'compare' (duration: 67.99708ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:58:37.50902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.279625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3da16169-6ed8-40a5-9b8b-e852d647ee2e\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-10-07T03:58:37.509118Z","caller":"traceutil/trace.go:171","msg":"trace[1860229329] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3da16169-6ed8-40a5-9b8b-e852d647ee2e; range_end:; response_count:1; response_revision:4630; }","duration":"222.427908ms","start":"2026-10-07T03:58:37.286665Z","end":"2026-10-07T03:58:37.509093Z","steps":["trace[1860229329] 'agreement among raft nodes before linearized reading' (duration: 222.149292ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:37.729002Z","caller":"traceutil/trace.go:171","msg":"trace[1510617115] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"193.077796ms","start":"2026-10-07T03:58:37.535852Z","end":"2026-10-07T03:58:37.72893Z","steps":["trace[1510617115] 'process raft request' (duration: 170.10141ms)","trace[1510617115] 'compare' (duration: 16.162842ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:58:37.729682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.863154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:58:37.729788Z","caller":"traceutil/trace.go:171","msg":"trace[881032050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4631; }","duration":"152.036088ms","start":"2026-10-07T03:58:37.577732Z","end":"2026-10-07T03:58:37.729768Z","steps":["trace[881032050] 'agreement among raft nodes before linearized reading' (duration: 151.853154ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:37.730188Z","caller":"traceutil/trace.go:171","msg":"trace[708459129] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4828; }","duration":"151.056197ms","start":"2026-10-07T03:58:37.577799Z","end":"2026-10-07T03:58:37.728855Z","steps":["trace[708459129] 'read index received' (duration: 128.049609ms)","trace[708459129] 'applied index is now lower than readState.Index' (duration: 23.003308ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:58:40.497276Z","caller":"traceutil/trace.go:171","msg":"trace[1033666079] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"111.713734ms","start":"2026-10-07T03:58:40.385531Z","end":"2026-10-07T03:58:40.497244Z","steps":["trace[1033666079] 'process raft request' (duration: 111.450718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:58:49.367504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.136572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:58:49.367617Z","caller":"traceutil/trace.go:171","msg":"trace[1064285221] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:4798; }","duration":"108.290746ms","start":"2026-10-07T03:58:49.259302Z","end":"2026-10-07T03:58:49.367593Z","steps":["trace[1064285221] 'count revisions from in-memory index tree' (duration: 107.937378ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:49.500224Z","caller":"traceutil/trace.go:171","msg":"trace[194838968] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"126.040088ms","start":"2026-10-07T03:58:49.374124Z","end":"2026-10-07T03:58:49.500164Z","steps":["trace[194838968] 'process raft request' (duration: 125.806593ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:49.51616Z","caller":"traceutil/trace.go:171","msg":"trace[1148580135] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"133.61195ms","start":"2026-10-07T03:58:49.382521Z","end":"2026-10-07T03:58:49.516133Z","steps":["trace[1148580135] 'process raft request' (duration: 133.348974ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:49.516711Z","caller":"traceutil/trace.go:171","msg":"trace[764142270] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"141.685644ms","start":"2026-10-07T03:58:49.374986Z","end":"2026-10-07T03:58:49.516672Z","steps":["trace[764142270] 'process raft request' (duration: 140.749014ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:58:49.531995Z","caller":"traceutil/trace.go:171","msg":"trace[1443909478] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5003; }","duration":"135.49094ms","start":"2026-10-07T03:58:49.396479Z","end":"2026-10-07T03:58:49.53197Z","steps":["trace[1443909478] 'read index received' (duration: 103.437902ms)","trace[1443909478] 'applied index is now lower than readState.Index' (duration: 32.051538ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:58:49.532551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.044742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-07T03:58:49.532634Z","caller":"traceutil/trace.go:171","msg":"trace[1581255823] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4801; }","duration":"136.144044ms","start":"2026-10-07T03:58:49.396453Z","end":"2026-10-07T03:58:49.532597Z","steps":["trace[1581255823] 'agreement among raft nodes before linearized reading' (duration: 135.627023ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:01:40.923297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3152} {"level":"info","ts":"2026-10-07T04:01:41.048076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3152,"took":"122.88815ms","hash":3762400981,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":31047680,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-07T04:01:41.048177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3762400981,"revision":3152,"compact-revision":-1} {"level":"info","ts":"2026-10-07T04:06:40.933964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6204} {"level":"info","ts":"2026-10-07T04:06:41.044509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6204,"took":"108.212567ms","hash":3190266380,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":18620416,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-07T04:06:41.044658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3190266380,"revision":6204,"compact-revision":3152}