{"level":"warn","ts":"2026-04-16T22:48:27.439704Z","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-16T22:48:27.439779Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-16T22:48:27.439876Z","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-16T22:48:27.439891Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-04-16T22:48:27.439911Z","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-16T22:48:27.440495Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-04-16T22:48:27.440564Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-16T22:48:27.444171Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.379694ms"} {"level":"info","ts":"2026-04-16T22:48:27.45548Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-04-16T22:48:27.455612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T22:48:27.455678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-04-16T22:48:27.455702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T22:48:27.45575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-04-16T22:48:27.455856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-04-16T22:48:27.467331Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T22:48:27.468873Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T22:48:27.47024Z","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-16T22:48:27.47264Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T22:48:27.472793Z","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-16T22:48:27.472927Z","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-16T22:48:27.472858Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T22:48:27.472953Z","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-16T22:48:27.474879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-04-16T22:48:27.475115Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-04-16T22:48:27.477367Z","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-16T22:48:27.477513Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-04-16T22:48:27.477582Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-04-16T22:48:27.477772Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T22:48:27.477811Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T22:48:28.156548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T22:48:28.156724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T22:48:28.156751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-04-16T22:48:28.156772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-04-16T22:48:28.156783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-04-16T22:48:28.156799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-04-16T22:48:28.156813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-04-16T22:48:28.157666Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:28.158572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:48:28.158593Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T22:48:28.158933Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T22:48:28.158999Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T22:48:28.158702Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:48:28.159114Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:28.159417Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:28.159446Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:28.160963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-04-16T22:48:28.162512Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T22:48:56.860909Z","caller":"traceutil/trace.go:171","msg":"trace[2124503914] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"166.509743ms","start":"2026-04-16T22:48:56.694373Z","end":"2026-04-16T22:48:56.860883Z","steps":["trace[2124503914] 'process raft request' (duration: 142.595442ms)","trace[2124503914] 'compare' (duration: 23.772247ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:48:57.030506Z","caller":"traceutil/trace.go:171","msg":"trace[714447591] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"107.793576ms","start":"2026-04-16T22:48:56.922691Z","end":"2026-04-16T22:48:57.030485Z","steps":["trace[714447591] 'process raft request' (duration: 98.548854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:03.101546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.131333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mspsc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-16T22:49:03.101663Z","caller":"traceutil/trace.go:171","msg":"trace[1195328437] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mspsc; range_end:; response_count:1; response_revision:622; }","duration":"153.295166ms","start":"2026-04-16T22:49:02.948345Z","end":"2026-04-16T22:49:03.10164Z","steps":["trace[1195328437] 'range keys from in-memory index tree' (duration: 152.950648ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:03.332563Z","caller":"traceutil/trace.go:171","msg":"trace[428981179] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"217.186065ms","start":"2026-04-16T22:49:03.115351Z","end":"2026-04-16T22:49:03.332537Z","steps":["trace[428981179] 'process raft request' (duration: 216.987581ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:03.867804Z","caller":"traceutil/trace.go:171","msg":"trace[85950838] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"114.459401ms","start":"2026-04-16T22:49:03.753314Z","end":"2026-04-16T22:49:03.867774Z","steps":["trace[85950838] 'read index received' (duration: 114.304377ms)","trace[85950838] 'applied index is now lower than readState.Index' (duration: 153.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:03.867899Z","caller":"traceutil/trace.go:171","msg":"trace[1662491799] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"165.542442ms","start":"2026-04-16T22:49:03.702326Z","end":"2026-04-16T22:49:03.867869Z","steps":["trace[1662491799] 'process raft request' (duration: 165.306497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:03.868098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.766407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:49:03.868137Z","caller":"traceutil/trace.go:171","msg":"trace[1637432747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"114.829248ms","start":"2026-04-16T22:49:03.753298Z","end":"2026-04-16T22:49:03.868127Z","steps":["trace[1637432747] 'agreement among raft nodes before linearized reading' (duration: 114.577353ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:26.437468Z","caller":"traceutil/trace.go:171","msg":"trace[1365623654] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"136.760645ms","start":"2026-04-16T22:50:26.300676Z","end":"2026-04-16T22:50:26.437437Z","steps":["trace[1365623654] 'read index received' (duration: 136.462728ms)","trace[1365623654] 'applied index is now lower than readState.Index' (duration: 297.007µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:50:26.437541Z","caller":"traceutil/trace.go:171","msg":"trace[1045738011] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"136.911558ms","start":"2026-04-16T22:50:26.300588Z","end":"2026-04-16T22:50:26.4375Z","steps":["trace[1045738011] 'process raft request' (duration: 136.633872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:50:26.437683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.978239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:50:26.437766Z","caller":"traceutil/trace.go:171","msg":"trace[889547696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1816; }","duration":"137.092092ms","start":"2026-04-16T22:50:26.300653Z","end":"2026-04-16T22:50:26.437745Z","steps":["trace[889547696] 'agreement among raft nodes before linearized reading' (duration: 136.889448ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:26.588616Z","caller":"traceutil/trace.go:171","msg":"trace[994675642] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"142.523029ms","start":"2026-04-16T22:50:26.44607Z","end":"2026-04-16T22:50:26.588593Z","steps":["trace[994675642] 'process raft request' (duration: 142.241953ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:39.336716Z","caller":"traceutil/trace.go:171","msg":"trace[1107038347] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"104.19963ms","start":"2026-04-16T22:52:39.232473Z","end":"2026-04-16T22:52:39.336672Z","steps":["trace[1107038347] 'process raft request' (duration: 103.968685ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:39.346868Z","caller":"traceutil/trace.go:171","msg":"trace[2012611838] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"113.687763ms","start":"2026-04-16T22:52:39.233134Z","end":"2026-04-16T22:52:39.346822Z","steps":["trace[2012611838] 'process raft request' (duration: 113.53344ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:05.245807Z","caller":"traceutil/trace.go:171","msg":"trace[1983207086] transaction","detail":"{read_only:false; response_revision:3753; number_of_response:1; }","duration":"106.5137ms","start":"2026-04-16T22:55:05.139256Z","end":"2026-04-16T22:55:05.24577Z","steps":["trace[1983207086] 'process raft request' (duration: 106.275335ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:25.338937Z","caller":"traceutil/trace.go:171","msg":"trace[280167843] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4754; }","duration":"109.197996ms","start":"2026-04-16T22:56:25.229712Z","end":"2026-04-16T22:56:25.33891Z","steps":["trace[280167843] 'process raft request' (duration: 109.049792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:30.605485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.274932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b3fa9adf-8ae4-420d-943a-6efe461c4e90\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-16T22:56:30.605585Z","caller":"traceutil/trace.go:171","msg":"trace[258365243] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b3fa9adf-8ae4-420d-943a-6efe461c4e90; range_end:; response_count:1; response_revision:4942; }","duration":"120.410914ms","start":"2026-04-16T22:56:30.485155Z","end":"2026-04-16T22:56:30.605565Z","steps":["trace[258365243] 'range keys from in-memory index tree' (duration: 120.117558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:30.605656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.294906ms","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-04-16T22:56:30.605738Z","caller":"traceutil/trace.go:171","msg":"trace[648543868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4942; }","duration":"123.442709ms","start":"2026-04-16T22:56:30.482277Z","end":"2026-04-16T22:56:30.60572Z","steps":["trace[648543868] 'range keys from in-memory index tree' (duration: 123.089132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:30.605753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.398135ms","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-04-16T22:56:30.60585Z","caller":"traceutil/trace.go:171","msg":"trace[226445766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4942; }","duration":"121.545019ms","start":"2026-04-16T22:56:30.484281Z","end":"2026-04-16T22:56:30.605826Z","steps":["trace[226445766] 'range keys from in-memory index tree' (duration: 121.15362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:30.60596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.999924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777442"} {"level":"info","ts":"2026-04-16T22:56:30.606016Z","caller":"traceutil/trace.go:171","msg":"trace[1129467299] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4942; }","duration":"103.078556ms","start":"2026-04-16T22:56:30.502921Z","end":"2026-04-16T22:56:30.605999Z","steps":["trace[1129467299] 'range keys from in-memory index tree' (duration: 102.535863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:30.606326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.064793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-04-16T22:56:30.606892Z","caller":"traceutil/trace.go:171","msg":"trace[843397336] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4942; }","duration":"170.532932ms","start":"2026-04-16T22:56:30.435861Z","end":"2026-04-16T22:56:30.606394Z","steps":["trace[843397336] 'range keys from in-memory index tree' (duration: 169.494759ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:48.351939Z","caller":"traceutil/trace.go:171","msg":"trace[2134388104] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"102.706137ms","start":"2026-04-16T22:56:48.249182Z","end":"2026-04-16T22:56:48.351888Z","steps":["trace[2134388104] 'process raft request' (duration: 102.433721ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:48.489585Z","caller":"traceutil/trace.go:171","msg":"trace[298338260] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"131.137902ms","start":"2026-04-16T22:56:48.35839Z","end":"2026-04-16T22:56:48.489528Z","steps":["trace[298338260] 'process raft request' (duration: 116.829388ms)","trace[298338260] 'compare' (duration: 13.872555ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:48.48981Z","caller":"traceutil/trace.go:171","msg":"trace[1667232396] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"131.195215ms","start":"2026-04-16T22:56:48.358587Z","end":"2026-04-16T22:56:48.489782Z","steps":["trace[1667232396] 'process raft request' (duration: 130.870997ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:48.489992Z","caller":"traceutil/trace.go:171","msg":"trace[1617161986] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"131.195804ms","start":"2026-04-16T22:56:48.358785Z","end":"2026-04-16T22:56:48.48998Z","steps":["trace[1617161986] 'process raft request' (duration: 130.912177ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:48.490157Z","caller":"traceutil/trace.go:171","msg":"trace[1940600112] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"131.337377ms","start":"2026-04-16T22:56:48.358793Z","end":"2026-04-16T22:56:48.490131Z","steps":["trace[1940600112] 'process raft request' (duration: 130.974269ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:58.982849Z","caller":"traceutil/trace.go:171","msg":"trace[1427735905] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"135.276574ms","start":"2026-04-16T22:56:58.847514Z","end":"2026-04-16T22:56:58.98279Z","steps":["trace[1427735905] 'process raft request' (duration: 134.985638ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:59.784703Z","caller":"traceutil/trace.go:171","msg":"trace[1028970299] linearizableReadLoop","detail":"{readStateIndex:5680; appliedIndex:5679; }","duration":"154.873902ms","start":"2026-04-16T22:56:59.629801Z","end":"2026-04-16T22:56:59.784675Z","steps":["trace[1028970299] 'read index received' (duration: 154.615307ms)","trace[1028970299] 'applied index is now lower than readState.Index' (duration: 257.395µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:59.784763Z","caller":"traceutil/trace.go:171","msg":"trace[1171080935] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"173.181442ms","start":"2026-04-16T22:56:59.611551Z","end":"2026-04-16T22:56:59.784732Z","steps":["trace[1171080935] 'process raft request' (duration: 172.925367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:59.78498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.15601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-df08d895-a0f8-4850-805d-e5e167355589\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-16T22:56:59.785057Z","caller":"traceutil/trace.go:171","msg":"trace[1990418022] range","detail":"{range_begin:/registry/persistentvolumes/pvc-df08d895-a0f8-4850-805d-e5e167355589; range_end:; response_count:1; response_revision:5477; }","duration":"155.271652ms","start":"2026-04-16T22:56:59.629765Z","end":"2026-04-16T22:56:59.785037Z","steps":["trace[1990418022] 'agreement among raft nodes before linearized reading' (duration: 155.025925ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:58:28.186762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3052} {"level":"info","ts":"2026-04-16T22:58:28.274457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3052,"took":"85.854801ms","hash":666043614,"current-db-size-bytes":57499648,"current-db-size":"58 MB","current-db-size-in-use-bytes":31928320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-16T22:58:28.27454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":666043614,"revision":3052,"compact-revision":-1} {"level":"warn","ts":"2026-04-16T23:02:42.970231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.024122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T23:02:42.9703Z","caller":"traceutil/trace.go:171","msg":"trace[2089724956] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:8097; }","duration":"269.153585ms","start":"2026-04-16T23:02:42.701132Z","end":"2026-04-16T23:02:42.970285Z","steps":["trace[2089724956] 'count revisions from in-memory index tree' (duration: 268.90814ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:28.19387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6171} {"level":"info","ts":"2026-04-16T23:03:28.302156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6171,"took":"106.15151ms","hash":1930565164,"current-db-size-bytes":57499648,"current-db-size":"58 MB","current-db-size-in-use-bytes":23220224,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-16T23:03:28.302261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1930565164,"revision":6171,"compact-revision":3052} {"level":"info","ts":"2026-04-16T23:03:36.412494Z","caller":"traceutil/trace.go:171","msg":"trace[535995467] linearizableReadLoop","detail":"{readStateIndex:8776; appliedIndex:8775; }","duration":"245.601052ms","start":"2026-04-16T23:03:36.166868Z","end":"2026-04-16T23:03:36.412469Z","steps":["trace[535995467] 'read index received' (duration: 245.390757ms)","trace[535995467] 'applied index is now lower than readState.Index' (duration: 209.425µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:36.412774Z","caller":"traceutil/trace.go:171","msg":"trace[1765115763] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"376.772496ms","start":"2026-04-16T23:03:36.035989Z","end":"2026-04-16T23:03:36.412761Z","steps":["trace[1765115763] 'process raft request' (duration: 376.326476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:36.413347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T23:03:36.035964Z","time spent":"376.875408ms","remote":"127.0.0.1:54940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4634,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T23:03:36.413678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.802278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T23:03:36.413741Z","caller":"traceutil/trace.go:171","msg":"trace[446046791] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8472; }","duration":"246.9378ms","start":"2026-04-16T23:03:36.166787Z","end":"2026-04-16T23:03:36.413725Z","steps":["trace[446046791] 'agreement among raft nodes before linearized reading' (duration: 246.777407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:36.414019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.389776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-c6f4598d8-w2qh8.18a6f8b447c774cb\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-16T23:03:36.414072Z","caller":"traceutil/trace.go:171","msg":"trace[1655705185] range","detail":"{range_begin:/registry/events/openstack/barbican-api-c6f4598d8-w2qh8.18a6f8b447c774cb; range_end:; response_count:1; response_revision:8472; }","duration":"186.469348ms","start":"2026-04-16T23:03:36.227592Z","end":"2026-04-16T23:03:36.414062Z","steps":["trace[1655705185] 'agreement among raft nodes before linearized reading' (duration: 186.334465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:36.68096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.036782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T23:03:36.681333Z","caller":"traceutil/trace.go:171","msg":"trace[582448600] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"261.312988ms","start":"2026-04-16T23:03:36.419984Z","end":"2026-04-16T23:03:36.681297Z","steps":["trace[582448600] 'process raft request' (duration: 129.640602ms)","trace[582448600] 'compare' (duration: 130.613584ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:36.6819Z","caller":"traceutil/trace.go:171","msg":"trace[1851102588] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"169.736889ms","start":"2026-04-16T23:03:36.512151Z","end":"2026-04-16T23:03:36.681888Z","steps":["trace[1851102588] 'process raft request' (duration: 169.687628ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:36.68195Z","caller":"traceutil/trace.go:171","msg":"trace[1564031103] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"260.570882ms","start":"2026-04-16T23:03:36.42135Z","end":"2026-04-16T23:03:36.681921Z","steps":["trace[1564031103] 'process raft request' (duration: 259.898348ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:36.754688Z","caller":"traceutil/trace.go:171","msg":"trace[2146078500] linearizableReadLoop","detail":"{readStateIndex:8780; appliedIndex:8776; }","duration":"149.947755ms","start":"2026-04-16T23:03:36.604715Z","end":"2026-04-16T23:03:36.754663Z","steps":["trace[2146078500] 'read index received' (duration: 8.815468ms)","trace[2146078500] 'applied index is now lower than readState.Index' (duration: 141.130757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T23:03:36.755004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.242292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T23:03:36.755145Z","caller":"traceutil/trace.go:171","msg":"trace[1425771161] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8475; }","duration":"150.435106ms","start":"2026-04-16T23:03:36.604673Z","end":"2026-04-16T23:03:36.755108Z","steps":["trace[1425771161] 'agreement among raft nodes before linearized reading' (duration: 150.16496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:36.796411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.70128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T23:03:36.796498Z","caller":"traceutil/trace.go:171","msg":"trace[1839185598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8477; }","duration":"141.836154ms","start":"2026-04-16T23:03:36.654644Z","end":"2026-04-16T23:03:36.79648Z","steps":["trace[1839185598] 'agreement among raft nodes before linearized reading' (duration: 141.687271ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:36.796499Z","caller":"traceutil/trace.go:171","msg":"trace[1817760870] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"110.651285ms","start":"2026-04-16T23:03:36.685814Z","end":"2026-04-16T23:03:36.796465Z","steps":["trace[1817760870] 'process raft request' (duration: 110.450081ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:36.796644Z","caller":"traceutil/trace.go:171","msg":"trace[1983113371] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"182.564714ms","start":"2026-04-16T23:03:36.614053Z","end":"2026-04-16T23:03:36.796618Z","steps":["trace[1983113371] 'process raft request' (duration: 181.978561ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:37.861248Z","caller":"traceutil/trace.go:171","msg":"trace[1964372950] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"220.892603ms","start":"2026-04-16T23:03:37.640305Z","end":"2026-04-16T23:03:37.861198Z","steps":["trace[1964372950] 'process raft request' (duration: 189.183305ms)","trace[1964372950] 'compare' (duration: 31.516694ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:04:25.10503Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002448.wal"} {"level":"info","ts":"2026-04-16T23:05:47.626312Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T23:05:47.631505Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T23:05:47.631596Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T23:08:28.201924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8389} {"level":"info","ts":"2026-04-16T23:08:28.283493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8389,"took":"78.774504ms","hash":57682052,"current-db-size-bytes":57499648,"current-db-size":"58 MB","current-db-size-in-use-bytes":25362432,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T23:08:28.28358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":57682052,"revision":8389,"compact-revision":6171} {"level":"info","ts":"2026-04-16T23:13:28.209455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10773} {"level":"info","ts":"2026-04-16T23:13:28.290283Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10773,"took":"77.622738ms","hash":3391937006,"current-db-size-bytes":57499648,"current-db-size":"58 MB","current-db-size-in-use-bytes":20447232,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-16T23:13:28.290365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3391937006,"revision":10773,"compact-revision":8389}