{"level":"warn","ts":"2026-04-02T01:05:20.92327Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T01:05:20.923363Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-04-02T01:05:20.923439Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T01:05:20.923451Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-02T01:05:20.923473Z","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-04-02T01:05:20.924173Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-02T01:05:20.924248Z","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":16,"max-cpu-available":16,"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.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-04-02T01:05:20.927682Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.202266ms"} {"level":"info","ts":"2026-04-02T01:05:20.934455Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-02T01:05:20.934536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T01:05:20.934562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-02T01:05:20.934579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T01:05:20.934591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-02T01:05:20.934629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-02T01:05:20.940949Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T01:05:20.943221Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T01:05:20.944512Z","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-04-02T01:05:20.947209Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T01:05:20.947377Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T01:05:20.947507Z","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-04-02T01:05:20.947648Z","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-04-02T01:05:20.947688Z","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-04-02T01:05:20.949707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-02T01:05:20.949879Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-02T01:05:20.951913Z","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-04-02T01:05:20.952015Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-02T01:05:20.952045Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-02T01:05:20.952481Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T01:05:20.952565Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T01:05:21.835099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T01:05:21.835197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T01:05:21.835233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-02T01:05:21.835251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-02T01:05:21.835259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-02T01:05:21.835272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-02T01:05:21.835284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-02T01:05:21.836044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T01:05:21.836089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T01:05:21.836187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T01:05:21.836199Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T01:05:21.836429Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T01:05:21.83646Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T01:05:21.837142Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T01:05:21.837283Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T01:05:21.837322Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T01:05:21.838807Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-04-02T01:05:21.839913Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T01:05:51.279477Z","caller":"traceutil/trace.go:171","msg":"trace[2141067717] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"193.669561ms","start":"2026-04-02T01:05:51.085777Z","end":"2026-04-02T01:05:51.279447Z","steps":["trace[2141067717] 'process raft request' (duration: 109.147162ms)","trace[2141067717] 'compare' (duration: 84.380886ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:05:58.743588Z","caller":"traceutil/trace.go:171","msg":"trace[1783129633] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"136.216592ms","start":"2026-04-02T01:05:58.607232Z","end":"2026-04-02T01:05:58.743449Z","steps":["trace[1783129633] 'process raft request' (duration: 110.630319ms)","trace[1783129633] 'compare' (duration: 25.100162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:05:59.221114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.012073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xl5wj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-02T01:05:59.221292Z","caller":"traceutil/trace.go:171","msg":"trace[294286820] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xl5wj; range_end:; response_count:1; response_revision:619; }","duration":"235.459533ms","start":"2026-04-02T01:05:58.985764Z","end":"2026-04-02T01:05:59.221223Z","steps":["trace[294286820] 'range keys from in-memory index tree' (duration: 234.583484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:05:59.22109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.182523ms","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-04-02T01:05:59.221595Z","caller":"traceutil/trace.go:171","msg":"trace[832474942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"203.995521ms","start":"2026-04-02T01:05:59.017569Z","end":"2026-04-02T01:05:59.221564Z","steps":["trace[832474942] 'range keys from in-memory index tree' (duration: 202.61149ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:06:53.19985Z","caller":"traceutil/trace.go:171","msg":"trace[617221316] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"167.799017ms","start":"2026-04-02T01:06:53.032015Z","end":"2026-04-02T01:06:53.199814Z","steps":["trace[617221316] 'process raft request' (duration: 167.628693ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:07:15.758269Z","caller":"traceutil/trace.go:171","msg":"trace[1934002242] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1516; }","duration":"102.724378ms","start":"2026-04-02T01:07:15.655509Z","end":"2026-04-02T01:07:15.758233Z","steps":["trace[1934002242] 'read index received' (duration: 102.511983ms)","trace[1934002242] 'applied index is now lower than readState.Index' (duration: 210.755µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:07:15.758401Z","caller":"traceutil/trace.go:171","msg":"trace[1609546592] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"138.398486ms","start":"2026-04-02T01:07:15.619963Z","end":"2026-04-02T01:07:15.758362Z","steps":["trace[1609546592] 'process raft request' (duration: 138.082389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:07:15.758598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.657595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T01:07:15.758567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.037395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T01:07:15.758655Z","caller":"traceutil/trace.go:171","msg":"trace[1269129190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1472; }","duration":"102.776488ms","start":"2026-04-02T01:07:15.655865Z","end":"2026-04-02T01:07:15.758642Z","steps":["trace[1269129190] 'agreement among raft nodes before linearized reading' (duration: 102.562823ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:07:15.758684Z","caller":"traceutil/trace.go:171","msg":"trace[1480595749] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1472; }","duration":"103.226579ms","start":"2026-04-02T01:07:15.655432Z","end":"2026-04-02T01:07:15.758658Z","steps":["trace[1480595749] 'agreement among raft nodes before linearized reading' (duration: 102.994084ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:08:25.45956Z","caller":"traceutil/trace.go:171","msg":"trace[1500184303] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"104.437235ms","start":"2026-04-02T01:08:25.355063Z","end":"2026-04-02T01:08:25.4595Z","steps":["trace[1500184303] 'process raft request' (duration: 104.2219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:09:53.010953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.455461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T01:09:53.011081Z","caller":"traceutil/trace.go:171","msg":"trace[1493787618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2840; }","duration":"242.664325ms","start":"2026-04-02T01:09:52.768383Z","end":"2026-04-02T01:09:53.011047Z","steps":["trace[1493787618] 'range keys from in-memory index tree' (duration: 242.245326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:09:53.01176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.899367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:09:53.011896Z","caller":"traceutil/trace.go:171","msg":"trace[688986424] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"232.69727ms","start":"2026-04-02T01:09:52.779176Z","end":"2026-04-02T01:09:53.011874Z","steps":["trace[688986424] 'process raft request' (duration: 48.042344ms)","trace[688986424] 'compare' (duration: 183.461297ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:12:28.455198Z","caller":"traceutil/trace.go:171","msg":"trace[1532715924] linearizableReadLoop","detail":"{readStateIndex:4008; appliedIndex:4007; }","duration":"146.212742ms","start":"2026-04-02T01:12:28.308964Z","end":"2026-04-02T01:12:28.455176Z","steps":["trace[1532715924] 'read index received' (duration: 146.042208ms)","trace[1532715924] 'applied index is now lower than readState.Index' (duration: 169.434µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:12:28.455267Z","caller":"traceutil/trace.go:171","msg":"trace[716847161] transaction","detail":"{read_only:false; response_revision:3862; number_of_response:1; }","duration":"147.461392ms","start":"2026-04-02T01:12:28.30776Z","end":"2026-04-02T01:12:28.455221Z","steps":["trace[716847161] 'process raft request' (duration: 147.268497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:12:28.455951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.960439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:12:28.455996Z","caller":"traceutil/trace.go:171","msg":"trace[1932931582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3862; }","duration":"147.072331ms","start":"2026-04-02T01:12:28.308914Z","end":"2026-04-02T01:12:28.455987Z","steps":["trace[1932931582] 'agreement among raft nodes before linearized reading' (duration: 146.395756ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:13:52.592176Z","caller":"traceutil/trace.go:171","msg":"trace[1091005437] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"102.1049ms","start":"2026-04-02T01:13:52.490022Z","end":"2026-04-02T01:13:52.592127Z","steps":["trace[1091005437] 'process raft request' (duration: 101.785863ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:13:55.85645Z","caller":"traceutil/trace.go:171","msg":"trace[43290368] linearizableReadLoop","detail":"{readStateIndex:5253; appliedIndex:5252; }","duration":"120.029241ms","start":"2026-04-02T01:13:55.736385Z","end":"2026-04-02T01:13:55.856415Z","steps":["trace[43290368] 'read index received' (duration: 119.780726ms)","trace[43290368] 'applied index is now lower than readState.Index' (duration: 247.045µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:13:55.856502Z","caller":"traceutil/trace.go:171","msg":"trace[1038642313] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"178.217648ms","start":"2026-04-02T01:13:55.678237Z","end":"2026-04-02T01:13:55.856454Z","steps":["trace[1038642313] 'process raft request' (duration: 177.999613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:13:55.85666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.68003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90f9f064-0a4a-4b87-95e5-46dec74743e6\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-04-02T01:13:55.856709Z","caller":"traceutil/trace.go:171","msg":"trace[1311121327] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90f9f064-0a4a-4b87-95e5-46dec74743e6; range_end:; response_count:1; response_revision:5061; }","duration":"107.803603ms","start":"2026-04-02T01:13:55.748894Z","end":"2026-04-02T01:13:55.856697Z","steps":["trace[1311121327] 'agreement among raft nodes before linearized reading' (duration: 107.700561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:13:55.85688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.485921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-04-02T01:13:55.856967Z","caller":"traceutil/trace.go:171","msg":"trace[468532956] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5061; }","duration":"120.622975ms","start":"2026-04-02T01:13:55.736325Z","end":"2026-04-02T01:13:55.856948Z","steps":["trace[468532956] 'agreement among raft nodes before linearized reading' (duration: 120.255716ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:13:58.050948Z","caller":"traceutil/trace.go:171","msg":"trace[146832739] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"129.406092ms","start":"2026-04-02T01:13:57.921511Z","end":"2026-04-02T01:13:58.050917Z","steps":["trace[146832739] 'process raft request' (duration: 129.243927ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:13:58.058645Z","caller":"traceutil/trace.go:171","msg":"trace[1296851867] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"128.072909ms","start":"2026-04-02T01:13:57.930545Z","end":"2026-04-02T01:13:58.058618Z","steps":["trace[1296851867] 'process raft request' (duration: 127.796763ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:13:58.058858Z","caller":"traceutil/trace.go:171","msg":"trace[721760416] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"114.403429ms","start":"2026-04-02T01:13:57.944412Z","end":"2026-04-02T01:13:58.058816Z","steps":["trace[721760416] 'process raft request' (duration: 114.140983ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:14:05.390536Z","caller":"traceutil/trace.go:171","msg":"trace[519842114] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"123.215526ms","start":"2026-04-02T01:14:05.267295Z","end":"2026-04-02T01:14:05.39051Z","steps":["trace[519842114] 'process raft request' (duration: 56.537748ms)","trace[519842114] 'compare' (duration: 66.452191ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:14:13.922295Z","caller":"traceutil/trace.go:171","msg":"trace[468433290] linearizableReadLoop","detail":"{readStateIndex:5622; appliedIndex:5621; }","duration":"134.412229ms","start":"2026-04-02T01:14:13.787848Z","end":"2026-04-02T01:14:13.92226Z","steps":["trace[468433290] 'read index received' (duration: 134.186233ms)","trace[468433290] 'applied index is now lower than readState.Index' (duration: 224.846µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:14:13.922418Z","caller":"traceutil/trace.go:171","msg":"trace[88316658] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"134.91008ms","start":"2026-04-02T01:14:13.787473Z","end":"2026-04-02T01:14:13.922383Z","steps":["trace[88316658] 'process raft request' (duration: 134.543472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:14:13.92275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.813658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-04-02T01:14:13.923045Z","caller":"traceutil/trace.go:171","msg":"trace[1565873098] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5421; }","duration":"135.200577ms","start":"2026-04-02T01:14:13.787817Z","end":"2026-04-02T01:14:13.923017Z","steps":["trace[1565873098] 'agreement among raft nodes before linearized reading' (duration: 134.582193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:14:14.109434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.981047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7613"} {"level":"info","ts":"2026-04-02T01:14:14.109515Z","caller":"traceutil/trace.go:171","msg":"trace[178126564] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5421; }","duration":"136.112229ms","start":"2026-04-02T01:14:13.973381Z","end":"2026-04-02T01:14:14.109494Z","steps":["trace[178126564] 'range keys from in-memory index tree' (duration: 135.71508ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:14:14.279417Z","caller":"traceutil/trace.go:171","msg":"trace[659243781] linearizableReadLoop","detail":"{readStateIndex:5624; appliedIndex:5622; }","duration":"122.155951ms","start":"2026-04-02T01:14:14.157235Z","end":"2026-04-02T01:14:14.279391Z","steps":["trace[659243781] 'read index received' (duration: 62.652812ms)","trace[659243781] 'applied index is now lower than readState.Index' (duration: 59.501299ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:14:14.27951Z","caller":"traceutil/trace.go:171","msg":"trace[1697896145] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"127.063937ms","start":"2026-04-02T01:14:14.152416Z","end":"2026-04-02T01:14:14.27948Z","steps":["trace[1697896145] 'process raft request' (duration: 126.858183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:14:14.279792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.492919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T01:14:14.279935Z","caller":"traceutil/trace.go:171","msg":"trace[695861421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5423; }","duration":"122.692884ms","start":"2026-04-02T01:14:14.157204Z","end":"2026-04-02T01:14:14.279897Z","steps":["trace[695861421] 'agreement among raft nodes before linearized reading' (duration: 122.310225ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:14:26.055744Z","caller":"traceutil/trace.go:171","msg":"trace[253455549] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"115.663959ms","start":"2026-04-02T01:14:25.940037Z","end":"2026-04-02T01:14:26.055701Z","steps":["trace[253455549] 'process raft request' (duration: 114.950912ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:15:21.863158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-04-02T01:15:21.948422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"83.561251ms","hash":1332883506,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":31432704,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-02T01:15:21.948605Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1332883506,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-04-02T01:20:06.745703Z","caller":"traceutil/trace.go:171","msg":"trace[1055733006] linearizableReadLoop","detail":"{readStateIndex:8479; appliedIndex:8478; }","duration":"116.932456ms","start":"2026-04-02T01:20:06.628746Z","end":"2026-04-02T01:20:06.745678Z","steps":["trace[1055733006] 'read index received' (duration: 116.73413ms)","trace[1055733006] 'applied index is now lower than readState.Index' (duration: 196.766µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:20:06.745819Z","caller":"traceutil/trace.go:171","msg":"trace[1981677769] transaction","detail":"{read_only:false; response_revision:8194; number_of_response:1; }","duration":"164.905115ms","start":"2026-04-02T01:20:06.580852Z","end":"2026-04-02T01:20:06.745757Z","steps":["trace[1981677769] 'process raft request' (duration: 164.666209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:20:06.745901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.13272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:20:06.745945Z","caller":"traceutil/trace.go:171","msg":"trace[815152135] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8194; }","duration":"117.232283ms","start":"2026-04-02T01:20:06.628701Z","end":"2026-04-02T01:20:06.745933Z","steps":["trace[815152135] 'agreement among raft nodes before linearized reading' (duration: 117.146041ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:20:06.881364Z","caller":"traceutil/trace.go:171","msg":"trace[2052277462] linearizableReadLoop","detail":"{readStateIndex:8480; appliedIndex:8479; }","duration":"128.612174ms","start":"2026-04-02T01:20:06.752731Z","end":"2026-04-02T01:20:06.881343Z","steps":["trace[2052277462] 'read index received' (duration: 120.155063ms)","trace[2052277462] 'applied index is now lower than readState.Index' (duration: 8.455611ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:20:06.88145Z","caller":"traceutil/trace.go:171","msg":"trace[1905749589] transaction","detail":"{read_only:false; response_revision:8195; number_of_response:1; }","duration":"130.744514ms","start":"2026-04-02T01:20:06.750665Z","end":"2026-04-02T01:20:06.881409Z","steps":["trace[1905749589] 'process raft request' (duration: 122.053008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:20:06.881552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.817248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-02T01:20:06.881593Z","caller":"traceutil/trace.go:171","msg":"trace[1650070254] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8195; }","duration":"128.886659ms","start":"2026-04-02T01:20:06.752695Z","end":"2026-04-02T01:20:06.881582Z","steps":["trace[1650070254] 'agreement among raft nodes before linearized reading' (duration: 128.720836ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:20:21.870769Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6078} {"level":"info","ts":"2026-04-02T01:20:21.975223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6078,"took":"101.159581ms","hash":3612628093,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":23195648,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-02T01:20:21.975314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3612628093,"revision":6078,"compact-revision":3001} {"level":"info","ts":"2026-04-02T01:20:59.124663Z","caller":"traceutil/trace.go:171","msg":"trace[1878828861] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"176.806116ms","start":"2026-04-02T01:20:58.947798Z","end":"2026-04-02T01:20:59.124604Z","steps":["trace[1878828861] 'process raft request' (duration: 176.469279ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:20:59.263346Z","caller":"traceutil/trace.go:171","msg":"trace[1466204380] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8566; }","duration":"124.676471ms","start":"2026-04-02T01:20:59.138636Z","end":"2026-04-02T01:20:59.263313Z","steps":["trace[1466204380] 'process raft request' (duration: 110.915764ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:20:59.281404Z","caller":"traceutil/trace.go:171","msg":"trace[168457995] linearizableReadLoop","detail":"{readStateIndex:8870; appliedIndex:8867; }","duration":"130.17517ms","start":"2026-04-02T01:20:59.151188Z","end":"2026-04-02T01:20:59.281363Z","steps":["trace[168457995] 'read index received' (duration: 98.382825ms)","trace[168457995] 'applied index is now lower than readState.Index' (duration: 31.790645ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:20:59.28184Z","caller":"traceutil/trace.go:171","msg":"trace[969532115] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"142.887102ms","start":"2026-04-02T01:20:59.138937Z","end":"2026-04-02T01:20:59.281824Z","steps":["trace[969532115] 'process raft request' (duration: 142.165315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:20:59.288325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.046023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T01:20:59.288399Z","caller":"traceutil/trace.go:171","msg":"trace[1633654201] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8568; }","duration":"137.231208ms","start":"2026-04-02T01:20:59.15115Z","end":"2026-04-02T01:20:59.288381Z","steps":["trace[1633654201] 'agreement among raft nodes before linearized reading' (duration: 137.038673ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:20:59.290256Z","caller":"traceutil/trace.go:171","msg":"trace[557662774] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"150.750809ms","start":"2026-04-02T01:20:59.139494Z","end":"2026-04-02T01:20:59.290245Z","steps":["trace[557662774] 'process raft request' (duration: 141.803327ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:21:00.137523Z","caller":"traceutil/trace.go:171","msg":"trace[839621396] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"194.57953ms","start":"2026-04-02T01:20:59.942902Z","end":"2026-04-02T01:21:00.137482Z","steps":["trace[839621396] 'process raft request' (duration: 194.323524ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:21:53.534495Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d6.wal"} {"level":"info","ts":"2026-04-02T01:23:00.764489Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T01:23:00.774763Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T01:23:00.774903Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T01:25:21.876684Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8283} {"level":"info","ts":"2026-04-02T01:25:21.959769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8283,"took":"79.967328ms","hash":1642359679,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":25174016,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-02T01:25:21.95985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1642359679,"revision":8283,"compact-revision":6078} {"level":"info","ts":"2026-04-02T01:30:21.891518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10706} {"level":"info","ts":"2026-04-02T01:30:21.974093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10706,"took":"80.669904ms","hash":572577618,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":20811776,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-02T01:30:21.974173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":572577618,"revision":10706,"compact-revision":8283}