{"level":"warn","ts":"2026-04-07T12:57:09.817416Z","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-07T12:57:09.817515Z","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-07T12:57:09.817588Z","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-07T12:57:09.817597Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-07T12:57:09.817615Z","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-07T12:57:09.818251Z","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-07T12:57:09.818327Z","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-07T12:57:09.855295Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"36.7412ms"} {"level":"info","ts":"2026-04-07T12:57:09.920934Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-07T12:57:09.921095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:57:09.921138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-07T12:57:09.92116Z","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-07T12:57:09.921175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-07T12:57:09.921235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-07T12:57:09.927975Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:57:09.92972Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:57:09.931423Z","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-07T12:57:09.934589Z","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-07T12:57:09.934887Z","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-07T12:57:09.934857Z","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-07T12:57:09.935052Z","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-07T12:57:09.935098Z","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-07T12:57:09.936522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-07T12:57:09.937159Z","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-07T12:57:09.940723Z","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-07T12:57:09.940785Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-07T12:57:09.940827Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-07T12:57:09.942303Z","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-07T12:57:09.942358Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:57:10.522219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:57:10.52229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:57:10.522323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-07T12:57:10.522344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:57:10.522356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-07T12:57:10.522374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-07T12:57:10.52239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-07T12:57:10.5237Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:57:10.524535Z","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-07T12:57:10.524597Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:57:10.524755Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:57:10.524996Z","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-07T12:57:10.525174Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:57:10.525225Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:57:10.525279Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:57:10.525349Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:57:10.528229Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:57:10.528368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"warn","ts":"2026-04-07T12:57:26.581403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.242956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:57:26.581539Z","caller":"traceutil/trace.go:171","msg":"trace[1219404042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"293.43942ms","start":"2026-04-07T12:57:26.288079Z","end":"2026-04-07T12:57:26.581519Z","steps":["trace[1219404042] 'range keys from in-memory index tree' (duration: 293.114102ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:27.040777Z","caller":"traceutil/trace.go:171","msg":"trace[244041644] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"406.682363ms","start":"2026-04-07T12:57:26.634069Z","end":"2026-04-07T12:57:27.040751Z","steps":["trace[244041644] 'read index received' (duration: 406.471238ms)","trace[244041644] 'applied index is now lower than readState.Index' (duration: 209.755µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:57:27.040913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.833806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T12:57:27.040833Z","caller":"traceutil/trace.go:171","msg":"trace[548120295] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"440.290643ms","start":"2026-04-07T12:57:26.600519Z","end":"2026-04-07T12:57:27.040809Z","steps":["trace[548120295] 'process raft request' (duration: 440.088378ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:27.040999Z","caller":"traceutil/trace.go:171","msg":"trace[1420997600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"406.927629ms","start":"2026-04-07T12:57:26.634014Z","end":"2026-04-07T12:57:27.040942Z","steps":["trace[1420997600] 'agreement among raft nodes before linearized reading' (duration: 406.837496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:27.0411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:26.633991Z","time spent":"407.096373ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:57:27.04165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:26.600487Z","time spent":"440.56877ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:57:40.182736Z","caller":"traceutil/trace.go:171","msg":"trace[1032501635] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"105.806046ms","start":"2026-04-07T12:57:40.076895Z","end":"2026-04-07T12:57:40.182701Z","steps":["trace[1032501635] 'process raft request' (duration: 105.445187ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:46.266353Z","caller":"traceutil/trace.go:171","msg":"trace[410071491] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"199.31866ms","start":"2026-04-07T12:57:46.066992Z","end":"2026-04-07T12:57:46.266311Z","steps":["trace[410071491] 'process raft request' (duration: 198.551981ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:46.386461Z","caller":"traceutil/trace.go:171","msg":"trace[174855090] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"115.658119ms","start":"2026-04-07T12:57:46.270761Z","end":"2026-04-07T12:57:46.386419Z","steps":["trace[174855090] 'process raft request' (duration: 115.426103ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:46.492581Z","caller":"traceutil/trace.go:171","msg":"trace[746352159] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"101.677231ms","start":"2026-04-07T12:57:46.390872Z","end":"2026-04-07T12:57:46.49255Z","steps":["trace[746352159] 'process raft request' (duration: 101.526837ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:46.492667Z","caller":"traceutil/trace.go:171","msg":"trace[1823014427] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"171.536729ms","start":"2026-04-07T12:57:46.321065Z","end":"2026-04-07T12:57:46.492602Z","steps":["trace[1823014427] 'process raft request' (duration: 103.894046ms)","trace[1823014427] 'compare' (duration: 67.268774ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:46.894311Z","caller":"traceutil/trace.go:171","msg":"trace[1119645201] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"387.993396ms","start":"2026-04-07T12:57:46.50629Z","end":"2026-04-07T12:57:46.894283Z","steps":["trace[1119645201] 'process raft request' (duration: 387.847122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:46.894664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:46.506266Z","time spent":"388.127759ms","remote":"127.0.0.1:51342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:58:01.915469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.019049ms","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-07T12:58:01.915562Z","caller":"traceutil/trace.go:171","msg":"trace[2079025422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"104.170342ms","start":"2026-04-07T12:58:01.811372Z","end":"2026-04-07T12:58:01.915543Z","steps":["trace[2079025422] 'range keys from in-memory index tree' (duration: 103.842625ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:58:02.083133Z","caller":"traceutil/trace.go:171","msg":"trace[66237761] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"163.090279ms","start":"2026-04-07T12:58:01.920024Z","end":"2026-04-07T12:58:02.083114Z","steps":["trace[66237761] 'process raft request' (duration: 162.984207ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:58:27.904881Z","caller":"traceutil/trace.go:171","msg":"trace[2080700468] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"134.312512ms","start":"2026-04-07T12:58:27.770541Z","end":"2026-04-07T12:58:27.904854Z","steps":["trace[2080700468] 'process raft request' (duration: 134.159458ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:13.805609Z","caller":"traceutil/trace.go:171","msg":"trace[988620724] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"106.914384ms","start":"2026-04-07T12:59:13.698652Z","end":"2026-04-07T12:59:13.805566Z","steps":["trace[988620724] 'process raft request' (duration: 61.751779ms)","trace[988620724] 'compare' (duration: 44.972841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:14.092718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.639933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7001d733-644f-4439-92ec-05d7f6ff6e4a\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-07T12:59:14.092757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.064684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:59:14.092833Z","caller":"traceutil/trace.go:171","msg":"trace[1760923835] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1829; }","duration":"102.160436ms","start":"2026-04-07T12:59:13.990654Z","end":"2026-04-07T12:59:14.092815Z","steps":["trace[1760923835] 'count revisions from in-memory index tree' (duration: 101.981191ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.092832Z","caller":"traceutil/trace.go:171","msg":"trace[417754271] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7001d733-644f-4439-92ec-05d7f6ff6e4a; range_end:; response_count:1; response_revision:1829; }","duration":"158.835519ms","start":"2026-04-07T12:59:13.93397Z","end":"2026-04-07T12:59:14.092806Z","steps":["trace[417754271] 'range keys from in-memory index tree' (duration: 158.423719ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:21.271297Z","caller":"traceutil/trace.go:171","msg":"trace[72320665] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"153.317382ms","start":"2026-04-07T12:59:21.117953Z","end":"2026-04-07T12:59:21.27127Z","steps":["trace[72320665] 'process raft request' (duration: 120.011611ms)","trace[72320665] 'compare' (duration: 32.994053ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:59:26.85096Z","caller":"traceutil/trace.go:171","msg":"trace[20675267] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"119.096168ms","start":"2026-04-07T12:59:26.731839Z","end":"2026-04-07T12:59:26.850935Z","steps":["trace[20675267] 'process raft request' (duration: 118.909113ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:59.661518Z","caller":"traceutil/trace.go:171","msg":"trace[1613203206] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"270.799956ms","start":"2026-04-07T12:59:59.390691Z","end":"2026-04-07T12:59:59.661491Z","steps":["trace[1613203206] 'process raft request' (duration: 270.615422ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:34.120405Z","caller":"traceutil/trace.go:171","msg":"trace[1858238071] transaction","detail":"{read_only:false; response_revision:3991; number_of_response:1; }","duration":"116.346614ms","start":"2026-04-07T13:04:34.004029Z","end":"2026-04-07T13:04:34.120376Z","steps":["trace[1858238071] 'process raft request' (duration: 116.180439ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:39.488543Z","caller":"traceutil/trace.go:171","msg":"trace[146263645] transaction","detail":"{read_only:false; response_revision:4023; number_of_response:1; }","duration":"160.859415ms","start":"2026-04-07T13:04:39.327653Z","end":"2026-04-07T13:04:39.488512Z","steps":["trace[146263645] 'process raft request' (duration: 160.679481ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:46.178208Z","caller":"traceutil/trace.go:171","msg":"trace[1092182792] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4925; }","duration":"116.027657ms","start":"2026-04-07T13:05:46.06214Z","end":"2026-04-07T13:05:46.178167Z","steps":["trace[1092182792] 'process raft request' (duration: 67.780009ms)","trace[1092182792] 'compare' (duration: 48.051463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:05:53.473848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.733984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:05:53.474009Z","caller":"traceutil/trace.go:171","msg":"trace[1077989270] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5176; }","duration":"102.963579ms","start":"2026-04-07T13:05:53.371015Z","end":"2026-04-07T13:05:53.473979Z","steps":["trace[1077989270] 'count revisions from in-memory index tree' (duration: 102.630181ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:53.786772Z","caller":"traceutil/trace.go:171","msg":"trace[1407807842] linearizableReadLoop","detail":"{readStateIndex:5372; appliedIndex:5371; }","duration":"190.730398ms","start":"2026-04-07T13:05:53.59601Z","end":"2026-04-07T13:05:53.78674Z","steps":["trace[1407807842] 'read index received' (duration: 190.525043ms)","trace[1407807842] 'applied index is now lower than readState.Index' (duration: 203.955µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:53.786868Z","caller":"traceutil/trace.go:171","msg":"trace[1904247233] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"227.660053ms","start":"2026-04-07T13:05:53.55918Z","end":"2026-04-07T13:05:53.78684Z","steps":["trace[1904247233] 'process raft request' (duration: 227.357975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.787069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.040495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T13:05:53.787127Z","caller":"traceutil/trace.go:171","msg":"trace[1978364616] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:5177; }","duration":"191.170488ms","start":"2026-04-07T13:05:53.595939Z","end":"2026-04-07T13:05:53.787109Z","steps":["trace[1978364616] 'agreement among raft nodes before linearized reading' (duration: 190.935552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:54.099064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.404984ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-07T13:05:54.099267Z","caller":"traceutil/trace.go:171","msg":"trace[1964234567] linearizableReadLoop","detail":"{readStateIndex:5374; appliedIndex:5372; }","duration":"226.501393ms","start":"2026-04-07T13:05:53.872751Z","end":"2026-04-07T13:05:54.099253Z","steps":["trace[1964234567] 'read index received' (duration: 123.851193ms)","trace[1964234567] 'applied index is now lower than readState.Index' (duration: 102.64948ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:54.099341Z","caller":"traceutil/trace.go:171","msg":"trace[1008073598] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"229.21588ms","start":"2026-04-07T13:05:53.870117Z","end":"2026-04-07T13:05:54.099333Z","steps":["trace[1008073598] 'process raft request' (duration: 229.042016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:54.099354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:05:53.788253Z","time spent":"311.096743ms","remote":"127.0.0.1:51206","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T13:05:54.099577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.319159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-231f26f9-8015-4696-8e22-fc3b54560aee\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-04-07T13:05:54.099567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.538139ms","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-07T13:05:54.099622Z","caller":"traceutil/trace.go:171","msg":"trace[630150804] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-231f26f9-8015-4696-8e22-fc3b54560aee; range_end:; response_count:1; response_revision:5178; }","duration":"139.38266ms","start":"2026-04-07T13:05:53.960223Z","end":"2026-04-07T13:05:54.099606Z","steps":["trace[630150804] 'agreement among raft nodes before linearized reading' (duration: 139.281418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:54.099612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.832681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:05:54.099726Z","caller":"traceutil/trace.go:171","msg":"trace[1017644263] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5178; }","duration":"227.000685ms","start":"2026-04-07T13:05:53.872695Z","end":"2026-04-07T13:05:54.099696Z","steps":["trace[1017644263] 'agreement among raft nodes before linearized reading' (duration: 226.79105ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:54.099652Z","caller":"traceutil/trace.go:171","msg":"trace[59780282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5178; }","duration":"215.636941ms","start":"2026-04-07T13:05:53.883979Z","end":"2026-04-07T13:05:54.099615Z","steps":["trace[59780282] 'agreement among raft nodes before linearized reading' (duration: 215.417436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:54.448404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.326302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:05:54.448475Z","caller":"traceutil/trace.go:171","msg":"trace[1179003298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5183; }","duration":"161.455025ms","start":"2026-04-07T13:05:54.287005Z","end":"2026-04-07T13:05:54.44846Z","steps":["trace[1179003298] 'agreement among raft nodes before linearized reading' (duration: 161.309981ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:54.448283Z","caller":"traceutil/trace.go:171","msg":"trace[1501370828] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"165.003113ms","start":"2026-04-07T13:05:54.283257Z","end":"2026-04-07T13:05:54.44826Z","steps":["trace[1501370828] 'process raft request' (duration: 164.814428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:54.449398Z","caller":"traceutil/trace.go:171","msg":"trace[245262634] linearizableReadLoop","detail":"{readStateIndex:5379; appliedIndex:5378; }","duration":"161.159918ms","start":"2026-04-07T13:05:54.287058Z","end":"2026-04-07T13:05:54.448218Z","steps":["trace[245262634] 'read index received' (duration: 160.923662ms)","trace[245262634] 'applied index is now lower than readState.Index' (duration: 235.296µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:56.156943Z","caller":"traceutil/trace.go:171","msg":"trace[1589193135] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"114.713973ms","start":"2026-04-07T13:05:56.042202Z","end":"2026-04-07T13:05:56.156916Z","steps":["trace[1589193135] 'process raft request' (duration: 111.617036ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:56.157148Z","caller":"traceutil/trace.go:171","msg":"trace[1360959823] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"114.756624ms","start":"2026-04-07T13:05:56.042374Z","end":"2026-04-07T13:05:56.157131Z","steps":["trace[1360959823] 'process raft request' (duration: 114.478808ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:56.157165Z","caller":"traceutil/trace.go:171","msg":"trace[451669212] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"114.720474ms","start":"2026-04-07T13:05:56.042426Z","end":"2026-04-07T13:05:56.157147Z","steps":["trace[451669212] 'process raft request' (duration: 114.607141ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:56.157254Z","caller":"traceutil/trace.go:171","msg":"trace[791554623] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"114.817596ms","start":"2026-04-07T13:05:56.042429Z","end":"2026-04-07T13:05:56.157246Z","steps":["trace[791554623] 'process raft request' (duration: 114.688513ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:03.004305Z","caller":"traceutil/trace.go:171","msg":"trace[13624953] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"103.658636ms","start":"2026-04-07T13:06:02.900619Z","end":"2026-04-07T13:06:03.004278Z","steps":["trace[13624953] 'process raft request' (duration: 68.095265ms)","trace[13624953] 'compare' (duration: 34.483784ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:06:10.785391Z","caller":"traceutil/trace.go:171","msg":"trace[369150692] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"140.052719ms","start":"2026-04-07T13:06:10.645312Z","end":"2026-04-07T13:06:10.785365Z","steps":["trace[369150692] 'process raft request' (duration: 139.868974ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:10.97179Z","caller":"traceutil/trace.go:171","msg":"trace[603783011] linearizableReadLoop","detail":"{readStateIndex:5706; appliedIndex:5705; }","duration":"181.356952ms","start":"2026-04-07T13:06:10.79041Z","end":"2026-04-07T13:06:10.971767Z","steps":["trace[603783011] 'read index received' (duration: 171.705281ms)","trace[603783011] 'applied index is now lower than readState.Index' (duration: 9.650571ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:06:10.97191Z","caller":"traceutil/trace.go:171","msg":"trace[51009039] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"181.501695ms","start":"2026-04-07T13:06:10.790396Z","end":"2026-04-07T13:06:10.971897Z","steps":["trace[51009039] 'process raft request' (duration: 171.784533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:10.972022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.233429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17125"} {"level":"info","ts":"2026-04-07T13:06:10.972103Z","caller":"traceutil/trace.go:171","msg":"trace[47956504] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5503; }","duration":"181.321341ms","start":"2026-04-07T13:06:10.790765Z","end":"2026-04-07T13:06:10.972087Z","steps":["trace[47956504] 'agreement among raft nodes before linearized reading' (duration: 181.135307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:10.972252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.819213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17125"} {"level":"info","ts":"2026-04-07T13:06:10.972314Z","caller":"traceutil/trace.go:171","msg":"trace[2050963604] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5503; }","duration":"181.909865ms","start":"2026-04-07T13:06:10.79039Z","end":"2026-04-07T13:06:10.9723Z","steps":["trace[2050963604] 'agreement among raft nodes before linearized reading' (duration: 181.70318ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:11.254501Z","caller":"traceutil/trace.go:171","msg":"trace[1321688240] linearizableReadLoop","detail":"{readStateIndex:5707; appliedIndex:5706; }","duration":"202.564734ms","start":"2026-04-07T13:06:11.051913Z","end":"2026-04-07T13:06:11.254478Z","steps":["trace[1321688240] 'read index received' (duration: 112.147469ms)","trace[1321688240] 'applied index is now lower than readState.Index' (duration: 90.416105ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:06:11.254554Z","caller":"traceutil/trace.go:171","msg":"trace[1089802827] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"207.531758ms","start":"2026-04-07T13:06:11.046977Z","end":"2026-04-07T13:06:11.254509Z","steps":["trace[1089802827] 'process raft request' (duration: 117.045711ms)","trace[1089802827] 'compare' (duration: 90.352794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:06:11.254732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.79825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-07T13:06:11.254777Z","caller":"traceutil/trace.go:171","msg":"trace[1219652461] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5504; }","duration":"202.868621ms","start":"2026-04-07T13:06:11.051896Z","end":"2026-04-07T13:06:11.254764Z","steps":["trace[1219652461] 'agreement among raft nodes before linearized reading' (duration: 202.709787ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:11.320301Z","caller":"traceutil/trace.go:171","msg":"trace[1681854778] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"107.021932ms","start":"2026-04-07T13:06:11.213184Z","end":"2026-04-07T13:06:11.320206Z","steps":["trace[1681854778] 'process raft request' (duration: 106.839717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:11.320498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.113922ms","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-04-07T13:06:11.320582Z","caller":"traceutil/trace.go:171","msg":"trace[1475572754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5505; }","duration":"188.272676ms","start":"2026-04-07T13:06:11.132291Z","end":"2026-04-07T13:06:11.320563Z","steps":["trace[1475572754] 'agreement among raft nodes before linearized reading' (duration: 187.987949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:11.320543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.691371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T13:06:11.320782Z","caller":"traceutil/trace.go:171","msg":"trace[1196769677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5505; }","duration":"187.974639ms","start":"2026-04-07T13:06:11.132785Z","end":"2026-04-07T13:06:11.32076Z","steps":["trace[1196769677] 'agreement among raft nodes before linearized reading' (duration: 187.521377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:11.320977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.151927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-15e31d7b-1c67-4e9b-bd78-ece7b1c1fa8c\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-07T13:06:11.321039Z","caller":"traceutil/trace.go:171","msg":"trace[1179530773] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-15e31d7b-1c67-4e9b-bd78-ece7b1c1fa8c; range_end:; response_count:1; response_revision:5505; }","duration":"181.22825ms","start":"2026-04-07T13:06:11.139795Z","end":"2026-04-07T13:06:11.321023Z","steps":["trace[1179530773] 'agreement among raft nodes before linearized reading' (duration: 180.435051ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:11.464457Z","caller":"traceutil/trace.go:171","msg":"trace[1357109040] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"139.602157ms","start":"2026-04-07T13:06:11.324816Z","end":"2026-04-07T13:06:11.464418Z","steps":["trace[1357109040] 'process raft request' (duration: 78.036594ms)","trace[1357109040] 'compare' (duration: 61.287676ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:06:11.474023Z","caller":"traceutil/trace.go:171","msg":"trace[1616864073] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"147.914935ms","start":"2026-04-07T13:06:11.326084Z","end":"2026-04-07T13:06:11.473999Z","steps":["trace[1616864073] 'process raft request' (duration: 147.607567ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:11.474018Z","caller":"traceutil/trace.go:171","msg":"trace[367696936] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"145.496475ms","start":"2026-04-07T13:06:11.328493Z","end":"2026-04-07T13:06:11.473989Z","steps":["trace[367696936] 'process raft request' (duration: 145.373481ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:07:10.55115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3040} {"level":"info","ts":"2026-04-07T13:07:10.630055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3040,"took":"77.745777ms","hash":2247850881,"current-db-size-bytes":58384384,"current-db-size":"58 MB","current-db-size-in-use-bytes":31440896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T13:07:10.630228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2247850881,"revision":3040,"compact-revision":-1} {"level":"info","ts":"2026-04-07T13:12:10.583189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6113} {"level":"info","ts":"2026-04-07T13:12:10.743443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6113,"took":"158.22673ms","hash":3283405821,"current-db-size-bytes":58384384,"current-db-size":"58 MB","current-db-size-in-use-bytes":21438464,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-07T13:12:10.743526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3283405821,"revision":6113,"compact-revision":3040} {"level":"warn","ts":"2026-04-07T13:12:15.725865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.002669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:12:15.72597Z","caller":"traceutil/trace.go:171","msg":"trace[1081905196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8361; }","duration":"438.157182ms","start":"2026-04-07T13:12:15.287794Z","end":"2026-04-07T13:12:15.725952Z","steps":["trace[1081905196] 'range keys from in-memory index tree' (duration: 437.922546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:15.726015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:15.287773Z","time spent":"438.228474ms","remote":"127.0.0.1:51160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T13:12:15.72587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.733449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:12:15.726083Z","caller":"traceutil/trace.go:171","msg":"trace[1678525802] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8361; }","duration":"178.006515ms","start":"2026-04-07T13:12:15.54806Z","end":"2026-04-07T13:12:15.726067Z","steps":["trace[1678525802] 'count revisions from in-memory index tree' (duration: 177.593005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:15.726186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.859696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T13:12:15.726267Z","caller":"traceutil/trace.go:171","msg":"trace[433161201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8361; }","duration":"461.96068ms","start":"2026-04-07T13:12:15.264283Z","end":"2026-04-07T13:12:15.726244Z","steps":["trace[433161201] 'range keys from in-memory index tree' (duration: 461.718503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:15.7264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.999009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:12:15.726443Z","caller":"traceutil/trace.go:171","msg":"trace[1067470080] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:8361; }","duration":"495.04185ms","start":"2026-04-07T13:12:15.231388Z","end":"2026-04-07T13:12:15.726429Z","steps":["trace[1067470080] 'count revisions from in-memory index tree' (duration: 494.842435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:15.726475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:15.231325Z","time spent":"495.141132ms","remote":"127.0.0.1:50638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-04-07T13:12:15.726625Z","caller":"traceutil/trace.go:171","msg":"trace[2133295738] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"154.897497ms","start":"2026-04-07T13:12:15.571709Z","end":"2026-04-07T13:12:15.726606Z","steps":["trace[2133295738] 'process raft request' (duration: 140.382082ms)","trace[2133295738] 'compare' (duration: 13.250362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:12:15.726321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:15.264274Z","time spent":"462.033831ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T13:12:15.726633Z","caller":"traceutil/trace.go:171","msg":"trace[2076953729] linearizableReadLoop","detail":"{readStateIndex:8657; appliedIndex:8656; }","duration":"149.239635ms","start":"2026-04-07T13:12:15.577341Z","end":"2026-04-07T13:12:15.72658Z","steps":["trace[2076953729] 'read index received' (duration: 134.71474ms)","trace[2076953729] 'applied index is now lower than readState.Index' (duration: 14.521245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:12:15.726831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.484441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bc7fp\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-07T13:12:15.726914Z","caller":"traceutil/trace.go:171","msg":"trace[664514032] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bc7fp; range_end:; response_count:1; response_revision:8362; }","duration":"149.565683ms","start":"2026-04-07T13:12:15.577324Z","end":"2026-04-07T13:12:15.72689Z","steps":["trace[664514032] 'agreement among raft nodes before linearized reading' (duration: 149.352657ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:26.783303Z","caller":"traceutil/trace.go:171","msg":"trace[37982923] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"112.840722ms","start":"2026-04-07T13:12:26.670439Z","end":"2026-04-07T13:12:26.78328Z","steps":["trace[37982923] 'process raft request' (duration: 106.224845ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:27.50403Z","caller":"traceutil/trace.go:171","msg":"trace[755197718] linearizableReadLoop","detail":"{readStateIndex:8721; appliedIndex:8720; }","duration":"271.742368ms","start":"2026-04-07T13:12:27.23225Z","end":"2026-04-07T13:12:27.503992Z","steps":["trace[755197718] 'read index received' (duration: 271.401649ms)","trace[755197718] 'applied index is now lower than readState.Index' (duration: 339.499µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:27.504132Z","caller":"traceutil/trace.go:171","msg":"trace[462414467] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"348.060972ms","start":"2026-04-07T13:12:27.156025Z","end":"2026-04-07T13:12:27.504086Z","steps":["trace[462414467] 'process raft request' (duration: 347.718594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:27.504285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.095352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:12:27.50434Z","caller":"traceutil/trace.go:171","msg":"trace[1972676918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8424; }","duration":"216.212794ms","start":"2026-04-07T13:12:27.288114Z","end":"2026-04-07T13:12:27.504327Z","steps":["trace[1972676918] 'agreement among raft nodes before linearized reading' (duration: 216.084201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:27.504345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:27.156001Z","time spent":"348.219016ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:12:27.50452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.25298ms","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-04-07T13:12:27.504597Z","caller":"traceutil/trace.go:171","msg":"trace[1306803674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8424; }","duration":"272.362843ms","start":"2026-04-07T13:12:27.232216Z","end":"2026-04-07T13:12:27.504579Z","steps":["trace[1306803674] 'agreement among raft nodes before linearized reading' (duration: 272.012085ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:29.031565Z","caller":"traceutil/trace.go:171","msg":"trace[1792097728] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"108.152494ms","start":"2026-04-07T13:12:28.923391Z","end":"2026-04-07T13:12:29.031543Z","steps":["trace[1792097728] 'process raft request' (duration: 108.00211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:29.265024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.220778ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T13:12:29.265113Z","caller":"traceutil/trace.go:171","msg":"trace[1284156064] linearizableReadLoop","detail":"{readStateIndex:8729; appliedIndex:8728; }","duration":"142.32651ms","start":"2026-04-07T13:12:29.122771Z","end":"2026-04-07T13:12:29.265097Z","steps":["trace[1284156064] 'read index received' (duration: 2.01436ms)","trace[1284156064] 'applied index is now lower than readState.Index' (duration: 140.31096ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:12:29.265292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.513305ms","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-07T13:12:29.265317Z","caller":"traceutil/trace.go:171","msg":"trace[1467186177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8431; }","duration":"142.579807ms","start":"2026-04-07T13:12:29.122731Z","end":"2026-04-07T13:12:29.265311Z","steps":["trace[1467186177] 'agreement among raft nodes before linearized reading' (duration: 142.457433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:53.729119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.425072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T13:12:53.729227Z","caller":"traceutil/trace.go:171","msg":"trace[1146064420] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:8616; }","duration":"108.584046ms","start":"2026-04-07T13:12:53.620622Z","end":"2026-04-07T13:12:53.729206Z","steps":["trace[1146064420] 'range keys from in-memory index tree' (duration: 108.215427ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:53.731983Z","caller":"traceutil/trace.go:171","msg":"trace[1369586233] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"105.312404ms","start":"2026-04-07T13:12:53.626651Z","end":"2026-04-07T13:12:53.731963Z","steps":["trace[1369586233] 'process raft request' (duration: 105.17674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:57.020065Z","caller":"traceutil/trace.go:171","msg":"trace[508114397] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"276.482284ms","start":"2026-04-07T13:12:56.743563Z","end":"2026-04-07T13:12:57.020045Z","steps":["trace[508114397] 'process raft request' (duration: 276.364171ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:57.274576Z","caller":"traceutil/trace.go:171","msg":"trace[1915147016] linearizableReadLoop","detail":"{readStateIndex:8941; appliedIndex:8939; }","duration":"335.040125ms","start":"2026-04-07T13:12:56.939519Z","end":"2026-04-07T13:12:57.27456Z","steps":["trace[1915147016] 'read index received' (duration: 80.42655ms)","trace[1915147016] 'applied index is now lower than readState.Index' (duration: 254.612635ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:57.274657Z","caller":"traceutil/trace.go:171","msg":"trace[1690483209] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"429.759282ms","start":"2026-04-07T13:12:56.844856Z","end":"2026-04-07T13:12:57.274615Z","steps":["trace[1690483209] 'process raft request' (duration: 345.096456ms)","trace[1690483209] 'compare' (duration: 84.515572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:12:57.274842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:56.844843Z","time spent":"429.922596ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:12:57.27487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.312596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T13:12:57.274869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.375328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T13:12:57.274913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.373893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T13:12:57.274922Z","caller":"traceutil/trace.go:171","msg":"trace[1601011788] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8636; }","duration":"109.413388ms","start":"2026-04-07T13:12:57.165496Z","end":"2026-04-07T13:12:57.274909Z","steps":["trace[1601011788] 'agreement among raft nodes before linearized reading' (duration: 109.298005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:57.274956Z","caller":"traceutil/trace.go:171","msg":"trace[1872617410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8636; }","duration":"335.461985ms","start":"2026-04-07T13:12:56.939485Z","end":"2026-04-07T13:12:57.274947Z","steps":["trace[1872617410] 'agreement among raft nodes before linearized reading' (duration: 335.129427ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:57.274966Z","caller":"traceutil/trace.go:171","msg":"trace[1077076213] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8636; }","duration":"115.503201ms","start":"2026-04-07T13:12:57.159434Z","end":"2026-04-07T13:12:57.274938Z","steps":["trace[1077076213] 'agreement among raft nodes before linearized reading' (duration: 115.356617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:57.275283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:56.939474Z","time spent":"335.627199ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T13:12:59.136145Z","caller":"traceutil/trace.go:171","msg":"trace[1161609728] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"110.73005ms","start":"2026-04-07T13:12:59.025389Z","end":"2026-04-07T13:12:59.136119Z","steps":["trace[1161609728] 'process raft request' (duration: 110.560086ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:09.119932Z","caller":"traceutil/trace.go:171","msg":"trace[2101203841] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"164.197033ms","start":"2026-04-07T13:13:08.955705Z","end":"2026-04-07T13:13:09.119902Z","steps":["trace[2101203841] 'process raft request' (duration: 155.810953ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:09.123059Z","caller":"traceutil/trace.go:171","msg":"trace[1527714579] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8740; }","duration":"166.926492ms","start":"2026-04-07T13:13:08.956115Z","end":"2026-04-07T13:13:09.123041Z","steps":["trace[1527714579] 'process raft request' (duration: 166.805349ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:09.705606Z","caller":"traceutil/trace.go:171","msg":"trace[752533819] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"196.947936ms","start":"2026-04-07T13:13:09.50863Z","end":"2026-04-07T13:13:09.705578Z","steps":["trace[752533819] 'process raft request' (duration: 196.768011ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:09.866219Z","caller":"traceutil/trace.go:171","msg":"trace[838489255] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"196.496404ms","start":"2026-04-07T13:13:09.669695Z","end":"2026-04-07T13:13:09.866192Z","steps":["trace[838489255] 'process raft request' (duration: 196.121185ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:10.033208Z","caller":"traceutil/trace.go:171","msg":"trace[1291135864] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"110.470854ms","start":"2026-04-07T13:13:09.922695Z","end":"2026-04-07T13:13:10.033166Z","steps":["trace[1291135864] 'process raft request' (duration: 110.274049ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:58.685607Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000253b.wal"} {"level":"info","ts":"2026-04-07T13:14:48.768028Z","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-07T13:14:48.775377Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T13:14:48.775477Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T13:17:10.59037Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8199} {"level":"info","ts":"2026-04-07T13:17:10.652888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8199,"took":"60.405256ms","hash":3424792703,"current-db-size-bytes":58384384,"current-db-size":"58 MB","current-db-size-in-use-bytes":28758016,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T13:17:10.652958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3424792703,"revision":8199,"compact-revision":6113} {"level":"info","ts":"2026-04-07T13:22:10.595551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10725} {"level":"info","ts":"2026-04-07T13:22:10.672208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10725,"took":"74.550654ms","hash":2915712772,"current-db-size-bytes":58384384,"current-db-size":"58 MB","current-db-size-in-use-bytes":20361216,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T13:22:10.672277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2915712772,"revision":10725,"compact-revision":8199}