{"level":"warn","ts":"2026-05-09T05:07:19.934232Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-09T05:07:19.934315Z","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-05-09T05:07:19.934376Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-09T05:07:19.93441Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]}
{"level":"info","ts":"2026-05-09T05:07:19.934434Z","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-05-09T05:07:19.935121Z","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-05-09T05:07:19.935275Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://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-05-09T05:07:19.948051Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.510338ms"}
{"level":"info","ts":"2026-05-09T05:07:20.023949Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"}
{"level":"info","ts":"2026-05-09T05:07:20.024032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"}
{"level":"info","ts":"2026-05-09T05:07:20.02406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"}
{"level":"info","ts":"2026-05-09T05:07:20.024075Z","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-05-09T05:07:20.024092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"}
{"level":"info","ts":"2026-05-09T05:07:20.024136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"}
{"level":"warn","ts":"2026-05-09T05:07:20.03107Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-05-09T05:07:20.032725Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-05-09T05:07:20.03382Z","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-05-09T05:07:20.036197Z","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-05-09T05:07:20.039405Z","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-05-09T05:07:20.03949Z","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-05-09T05:07:20.039507Z","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-05-09T05:07:20.039467Z","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-05-09T05:07:20.040818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"}
{"level":"info","ts":"2026-05-09T05:07:20.040929Z","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-05-09T05:07:20.04141Z","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-05-09T05:07:20.041527Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"}
{"level":"info","ts":"2026-05-09T05:07:20.041595Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"}
{"level":"info","ts":"2026-05-09T05:07:20.041708Z","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-05-09T05:07:20.04174Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-05-09T05:07:20.424988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"}
{"level":"info","ts":"2026-05-09T05:07:20.425116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"}
{"level":"info","ts":"2026-05-09T05:07:20.425224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"}
{"level":"info","ts":"2026-05-09T05:07:20.425268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"}
{"level":"info","ts":"2026-05-09T05:07:20.425304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"}
{"level":"info","ts":"2026-05-09T05:07:20.425341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"}
{"level":"info","ts":"2026-05-09T05:07:20.42537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"}
{"level":"info","ts":"2026-05-09T05:07:20.426597Z","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-05-09T05:07:20.426788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-09T05:07:20.426883Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-09T05:07:20.427028Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-09T05:07:20.427219Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-05-09T05:07:20.427245Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-05-09T05:07:20.42777Z","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-05-09T05:07:20.427853Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-09T05:07:20.427874Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-09T05:07:20.42991Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"}
{"level":"info","ts":"2026-05-09T05:07:20.430164Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-05-09T05:07:54.750007Z","caller":"traceutil/trace.go:171","msg":"trace[1610048429] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"227.502832ms","start":"2026-05-09T05:07:54.522476Z","end":"2026-05-09T05:07:54.749978Z","steps":["trace[1610048429] 'read index received'  (duration: 225.003846ms)","trace[1610048429] 'applied index is now lower than readState.Index'  (duration: 2.497535ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:07:54.750076Z","caller":"traceutil/trace.go:171","msg":"trace[254532959] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"463.722637ms","start":"2026-05-09T05:07:54.286328Z","end":"2026-05-09T05:07:54.750051Z","steps":["trace[254532959] 'process raft request'  (duration: 461.81353ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:07:54.750226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.730515ms","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-05-09T05:07:54.750298Z","caller":"traceutil/trace.go:171","msg":"trace[1488172385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"227.861457ms","start":"2026-05-09T05:07:54.522424Z","end":"2026-05-09T05:07:54.750285Z","steps":["trace[1488172385] 'agreement among raft nodes before linearized reading'  (duration: 227.676094ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:07:54.750739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:07:54.286255Z","time spent":"463.880219ms","remote":"127.0.0.1:39472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:555 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036021 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"warn","ts":"2026-05-09T05:07:54.980947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.05798ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13322102943391374546 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:548 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-09T05:07:54.981186Z","caller":"traceutil/trace.go:171","msg":"trace[1048685577] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"179.612023ms","start":"2026-05-09T05:07:54.801538Z","end":"2026-05-09T05:07:54.98115Z","steps":["trace[1048685577] 'process raft request'  (duration: 69.088197ms)","trace[1048685577] 'compare'  (duration: 109.894598ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-09T05:07:54.98133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.614886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-09T05:07:54.981208Z","caller":"traceutil/trace.go:171","msg":"trace[1433669375] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:581; }","duration":"172.471223ms","start":"2026-05-09T05:07:54.808718Z","end":"2026-05-09T05:07:54.981189Z","steps":["trace[1433669375] 'read index received'  (duration: 61.927727ms)","trace[1433669375] 'applied index is now lower than readState.Index'  (duration: 110.542446ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:07:54.981393Z","caller":"traceutil/trace.go:171","msg":"trace[1117953604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"172.708037ms","start":"2026-05-09T05:07:54.808673Z","end":"2026-05-09T05:07:54.981381Z","steps":["trace[1117953604] 'agreement among raft nodes before linearized reading'  (duration: 172.564765ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:07:54.98156Z","caller":"traceutil/trace.go:171","msg":"trace[396203453] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"173.591589ms","start":"2026-05-09T05:07:54.807949Z","end":"2026-05-09T05:07:54.981541Z","steps":["trace[396203453] 'process raft request'  (duration: 173.142373ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:07:55.425956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.759594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-05-09T05:07:55.426021Z","caller":"traceutil/trace.go:171","msg":"trace[1048175695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"279.891236ms","start":"2026-05-09T05:07:55.146113Z","end":"2026-05-09T05:07:55.426004Z","steps":["trace[1048175695] 'range keys from in-memory index tree'  (duration: 279.582772ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:08:21.809841Z","caller":"traceutil/trace.go:171","msg":"trace[687373575] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"206.292162ms","start":"2026-05-09T05:08:21.603524Z","end":"2026-05-09T05:08:21.809816Z","steps":["trace[687373575] 'process raft request'  (duration: 164.902868ms)","trace[687373575] 'compare'  (duration: 41.203512ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:08:50.72883Z","caller":"traceutil/trace.go:171","msg":"trace[639380513] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"166.45232ms","start":"2026-05-09T05:08:50.562355Z","end":"2026-05-09T05:08:50.728807Z","steps":["trace[639380513] 'process raft request'  (duration: 166.322438ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:09:36.524582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.406669ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13322102943391377086 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1758 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-09T05:09:36.524704Z","caller":"traceutil/trace.go:171","msg":"trace[2108443048] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"250.362456ms","start":"2026-05-09T05:09:36.274325Z","end":"2026-05-09T05:09:36.524687Z","steps":["trace[2108443048] 'read index received'  (duration: 29.58µs)","trace[2108443048] 'applied index is now lower than readState.Index'  (duration: 250.331526ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:09:36.524814Z","caller":"traceutil/trace.go:171","msg":"trace[852748669] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"337.648924ms","start":"2026-05-09T05:09:36.187143Z","end":"2026-05-09T05:09:36.524792Z","steps":["trace[852748669] 'process raft request'  (duration: 77.945291ms)","trace[852748669] 'compare'  (duration: 259.094095ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-09T05:09:36.52493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:09:36.187116Z","time spent":"337.770107ms","remote":"127.0.0.1:39622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1758 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-05-09T05:09:36.524843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.519889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-474fe91a-7ff9-4906-8819-df492e38bfc8\" ","response":"range_response_count:1 size:3626"}
{"level":"info","ts":"2026-05-09T05:09:36.525665Z","caller":"traceutil/trace.go:171","msg":"trace[611709655] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-474fe91a-7ff9-4906-8819-df492e38bfc8; range_end:; response_count:1; response_revision:1827; }","duration":"250.859843ms","start":"2026-05-09T05:09:36.274283Z","end":"2026-05-09T05:09:36.525143Z","steps":["trace[611709655] 'agreement among raft nodes before linearized reading'  (duration: 250.449027ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:09:36.937245Z","caller":"traceutil/trace.go:171","msg":"trace[1171683465] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"181.893065ms","start":"2026-05-09T05:09:36.755329Z","end":"2026-05-09T05:09:36.937222Z","steps":["trace[1171683465] 'process raft request'  (duration: 181.743282ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:10:25.785138Z","caller":"traceutil/trace.go:171","msg":"trace[1188210749] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"251.009739ms","start":"2026-05-09T05:10:25.534102Z","end":"2026-05-09T05:10:25.785112Z","steps":["trace[1188210749] 'read index received'  (duration: 250.831546ms)","trace[1188210749] 'applied index is now lower than readState.Index'  (duration: 176.733µs)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:10:25.785252Z","caller":"traceutil/trace.go:171","msg":"trace[876064340] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"276.944715ms","start":"2026-05-09T05:10:25.508296Z","end":"2026-05-09T05:10:25.785241Z","steps":["trace[876064340] 'process raft request'  (duration: 276.590241ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:10:25.78566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.535516ms","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-05-09T05:10:25.785823Z","caller":"traceutil/trace.go:171","msg":"trace[738556314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2139; }","duration":"251.770029ms","start":"2026-05-09T05:10:25.534025Z","end":"2026-05-09T05:10:25.785795Z","steps":["trace[738556314] 'agreement among raft nodes before linearized reading'  (duration: 251.425165ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:10:26.136902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.536633ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13322102943391377827 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2130 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-09T05:10:26.137049Z","caller":"traceutil/trace.go:171","msg":"trace[430277001] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"345.52258ms","start":"2026-05-09T05:10:25.791513Z","end":"2026-05-09T05:10:26.137035Z","steps":["trace[430277001] 'process raft request'  (duration: 345.481839ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:10:26.13706Z","caller":"traceutil/trace.go:171","msg":"trace[563872422] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"351.831108ms","start":"2026-05-09T05:10:25.785214Z","end":"2026-05-09T05:10:26.137045Z","steps":["trace[563872422] 'read index received'  (duration: 95.029952ms)","trace[563872422] 'applied index is now lower than readState.Index'  (duration: 256.799846ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-09T05:10:26.137094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:25.791479Z","time spent":"345.593711ms","remote":"127.0.0.1:39622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:2128 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-05-09T05:10:26.137085Z","caller":"traceutil/trace.go:171","msg":"trace[856860127] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"514.407616ms","start":"2026-05-09T05:10:25.622628Z","end":"2026-05-09T05:10:26.137036Z","steps":["trace[856860127] 'process raft request'  (duration: 257.66112ms)","trace[856860127] 'compare'  (duration: 256.427052ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-09T05:10:26.137226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.286599ms","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-05-09T05:10:26.137255Z","caller":"traceutil/trace.go:171","msg":"trace[528242172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2141; }","duration":"413.360579ms","start":"2026-05-09T05:10:25.723886Z","end":"2026-05-09T05:10:26.137246Z","steps":["trace[528242172] 'agreement among raft nodes before linearized reading'  (duration: 413.228118ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:10:26.137276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:25.72386Z","time spent":"413.410191ms","remote":"127.0.0.1:39622","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-05-09T05:10:26.137324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:25.622598Z","time spent":"514.590919ms","remote":"127.0.0.1:39622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2130 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-05-09T05:10:26.137425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.848831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-05-09T05:10:26.137461Z","caller":"traceutil/trace.go:171","msg":"trace[19002527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2141; }","duration":"595.936583ms","start":"2026-05-09T05:10:25.541515Z","end":"2026-05-09T05:10:26.137451Z","steps":["trace[19002527] 'agreement among raft nodes before linearized reading'  (duration: 595.841972ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:10:26.137491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:25.541487Z","time spent":"595.997663ms","remote":"127.0.0.1:39622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-05-09T05:10:26.148135Z","caller":"traceutil/trace.go:171","msg":"trace[293933328] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"224.682822ms","start":"2026-05-09T05:10:25.923432Z","end":"2026-05-09T05:10:26.148115Z","steps":["trace[293933328] 'process raft request'  (duration: 224.51385ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:10:26.148327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.067876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"}
{"level":"info","ts":"2026-05-09T05:10:26.148367Z","caller":"traceutil/trace.go:171","msg":"trace[1261318854] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2142; }","duration":"112.146748ms","start":"2026-05-09T05:10:26.036209Z","end":"2026-05-09T05:10:26.148355Z","steps":["trace[1261318854] 'agreement among raft nodes before linearized reading'  (duration: 112.062937ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:10:26.148475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.588207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-09T05:10:26.148535Z","caller":"traceutil/trace.go:171","msg":"trace[1291532882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2142; }","duration":"339.711079ms","start":"2026-05-09T05:10:25.808808Z","end":"2026-05-09T05:10:26.148519Z","steps":["trace[1291532882] 'agreement among raft nodes before linearized reading'  (duration: 339.259263ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:10:26.148564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:25.808782Z","time spent":"339.774029ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-05-09T05:11:39.253171Z","caller":"traceutil/trace.go:171","msg":"trace[923277299] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"133.822695ms","start":"2026-05-09T05:11:39.119322Z","end":"2026-05-09T05:11:39.253144Z","steps":["trace[923277299] 'process raft request'  (duration: 133.551002ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:11:39.446811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.984799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9280"}
{"level":"info","ts":"2026-05-09T05:11:39.447191Z","caller":"traceutil/trace.go:171","msg":"trace[2007647803] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2649; }","duration":"121.394854ms","start":"2026-05-09T05:11:39.325776Z","end":"2026-05-09T05:11:39.44717Z","steps":["trace[2007647803] 'range keys from in-memory index tree'  (duration: 120.864567ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:11:39.589062Z","caller":"traceutil/trace.go:171","msg":"trace[2000002860] linearizableReadLoop","detail":"{readStateIndex:2749; appliedIndex:2748; }","duration":"185.328908ms","start":"2026-05-09T05:11:39.403702Z","end":"2026-05-09T05:11:39.589031Z","steps":["trace[2000002860] 'read index received'  (duration: 185.057425ms)","trace[2000002860] 'applied index is now lower than readState.Index'  (duration: 270.323µs)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:11:39.589434Z","caller":"traceutil/trace.go:171","msg":"trace[898365953] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"195.562017ms","start":"2026-05-09T05:11:39.393859Z","end":"2026-05-09T05:11:39.589421Z","steps":["trace[898365953] 'process raft request'  (duration: 194.990911ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:11:39.589806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.065136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-05-09T05:11:39.589857Z","caller":"traceutil/trace.go:171","msg":"trace[2005617360] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2650; }","duration":"186.156197ms","start":"2026-05-09T05:11:39.403686Z","end":"2026-05-09T05:11:39.589843Z","steps":["trace[2005617360] 'agreement among raft nodes before linearized reading'  (duration: 185.995286ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:11:39.780017Z","caller":"traceutil/trace.go:171","msg":"trace[433936837] linearizableReadLoop","detail":"{readStateIndex:2751; appliedIndex:2749; }","duration":"190.834459ms","start":"2026-05-09T05:11:39.589159Z","end":"2026-05-09T05:11:39.779993Z","steps":["trace[433936837] 'read index received'  (duration: 167.577489ms)","trace[433936837] 'applied index is now lower than readState.Index'  (duration: 23.25564ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:11:39.780136Z","caller":"traceutil/trace.go:171","msg":"trace[761707605] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"328.097811ms","start":"2026-05-09T05:11:39.452013Z","end":"2026-05-09T05:11:39.780111Z","steps":["trace[761707605] 'process raft request'  (duration: 304.80471ms)","trace[761707605] 'compare'  (duration: 22.984457ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:11:39.780173Z","caller":"traceutil/trace.go:171","msg":"trace[521759484] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"202.279141ms","start":"2026-05-09T05:11:39.577779Z","end":"2026-05-09T05:11:39.780058Z","steps":["trace[521759484] 'process raft request'  (duration: 202.153809ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:11:39.780305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:39.451871Z","time spent":"328.337554ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/events/auth-system/keycloak-0.18adcd97b6bde178\" mod_revision:2622 > success:<request_put:<key:\"/registry/events/auth-system/keycloak-0.18adcd97b6bde178\" value_size:690 lease:4098730906536603220 >> failure:<request_range:<key:\"/registry/events/auth-system/keycloak-0.18adcd97b6bde178\" > >"}
{"level":"warn","ts":"2026-05-09T05:11:39.780228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.123274ms","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-05-09T05:11:39.780477Z","caller":"traceutil/trace.go:171","msg":"trace[1427776627] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2652; }","duration":"318.355286ms","start":"2026-05-09T05:11:39.462046Z","end":"2026-05-09T05:11:39.780401Z","steps":["trace[1427776627] 'agreement among raft nodes before linearized reading'  (duration: 318.047003ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-09T05:11:39.780588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:39.462023Z","time spent":"318.537978ms","remote":"127.0.0.1:40106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-05-09T05:14:37.556746Z","caller":"traceutil/trace.go:171","msg":"trace[286303312] transaction","detail":"{read_only:false; response_revision:3745; number_of_response:1; }","duration":"116.784816ms","start":"2026-05-09T05:14:37.439931Z","end":"2026-05-09T05:14:37.556716Z","steps":["trace[286303312] 'process raft request'  (duration: 116.734994ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:14:37.556954Z","caller":"traceutil/trace.go:171","msg":"trace[1667326348] transaction","detail":"{read_only:false; response_revision:3744; number_of_response:1; }","duration":"118.048655ms","start":"2026-05-09T05:14:37.438873Z","end":"2026-05-09T05:14:37.556922Z","steps":["trace[1667326348] 'process raft request'  (duration: 54.774131ms)","trace[1667326348] 'compare'  (duration: 62.602039ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:15:28.634517Z","caller":"traceutil/trace.go:171","msg":"trace[970204421] transaction","detail":"{read_only:false; response_revision:4501; number_of_response:1; }","duration":"116.359262ms","start":"2026-05-09T05:15:28.51813Z","end":"2026-05-09T05:15:28.63449Z","steps":["trace[970204421] 'process raft request'  (duration: 50.259882ms)","trace[970204421] 'compare'  (duration: 65.761174ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:15:36.337669Z","caller":"traceutil/trace.go:171","msg":"trace[1807752528] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"131.923063ms","start":"2026-05-09T05:15:36.20571Z","end":"2026-05-09T05:15:36.337633Z","steps":["trace[1807752528] 'process raft request'  (duration: 131.071327ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:15:48.11265Z","caller":"traceutil/trace.go:171","msg":"trace[1104036542] linearizableReadLoop","detail":"{readStateIndex:5030; appliedIndex:5029; }","duration":"126.876525ms","start":"2026-05-09T05:15:47.985745Z","end":"2026-05-09T05:15:48.112621Z","steps":["trace[1104036542] 'read index received'  (duration: 35.870106ms)","trace[1104036542] 'applied index is now lower than readState.Index'  (duration: 91.005279ms)"],"step_count":2}
{"level":"info","ts":"2026-05-09T05:15:48.112852Z","caller":"traceutil/trace.go:171","msg":"trace[2104681234] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"143.321263ms","start":"2026-05-09T05:15:47.969497Z","end":"2026-05-09T05:15:48.112819Z","steps":["trace[2104681234] 'process raft request'  (duration: 52.197801ms)","trace[2104681234] 'compare'  (duration: 90.798245ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-09T05:15:48.112897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.11661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-09T05:15:48.112964Z","caller":"traceutil/trace.go:171","msg":"trace[471576740] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4849; }","duration":"127.222543ms","start":"2026-05-09T05:15:47.985717Z","end":"2026-05-09T05:15:48.112939Z","steps":["trace[471576740] 'agreement among raft nodes before linearized reading'  (duration: 127.107611ms)"],"step_count":1}
{"level":"info","ts":"2026-05-09T05:17:20.479712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2864}
{"level":"info","ts":"2026-05-09T05:17:20.559613Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2864,"took":"77.918667ms","hash":2330905206,"current-db-size-bytes":53329920,"current-db-size":"53 MB","current-db-size-in-use-bytes":29429760,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-05-09T05:17:20.559741Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2330905206,"revision":2864,"compact-revision":-1}
{"level":"info","ts":"2026-05-09T05:22:20.484959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5617}
{"level":"info","ts":"2026-05-09T05:22:20.559782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5617,"took":"73.289517ms","hash":98202283,"current-db-size-bytes":53329920,"current-db-size":"53 MB","current-db-size-in-use-bytes":18403328,"current-db-size-in-use":"18 MB"}
{"level":"info","ts":"2026-05-09T05:22:20.559864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":98202283,"revision":5617,"compact-revision":2864}