{"level":"warn","ts":"2026-04-13T17:05:49.408167Z","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-13T17:05:49.408309Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.139: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.19.213.139:2380","--initial-cluster=instance=https://199.19.213.139:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.139:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.139: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-13T17:05:49.408405Z","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-13T17:05:49.408416Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.139:2380"]} {"level":"info","ts":"2026-04-13T17:05:49.408436Z","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-13T17:05:49.409245Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"]} {"level":"info","ts":"2026-04-13T17:05:49.409342Z","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.19.213.139:2380"],"listen-peer-urls":["https://199.19.213.139:2380"],"advertise-client-urls":["https://199.19.213.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.139: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-13T17:05:49.414384Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.806916ms"} {"level":"info","ts":"2026-04-13T17:05:49.42275Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb257ce137899862","cluster-id":"9913588efaa998c1"} {"level":"info","ts":"2026-04-13T17:05:49.422847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:05:49.422875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became follower at term 0"} {"level":"info","ts":"2026-04-13T17:05:49.422891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb257ce137899862 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:05:49.422906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became follower at term 1"} {"level":"info","ts":"2026-04-13T17:05:49.42295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=(14638243470578915426)"} {"level":"warn","ts":"2026-04-13T17:05:49.431614Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:05:49.433844Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:05:49.434752Z","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-13T17:05:49.437519Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb257ce137899862","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:05:49.437873Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb257ce137899862","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:05:49.43811Z","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-13T17:05:49.4382Z","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-13T17:05:49.438209Z","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-13T17:05:49.43938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=(14638243470578915426)"} {"level":"info","ts":"2026-04-13T17:05:49.440586Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9913588efaa998c1","local-member-id":"cb257ce137899862","added-peer-id":"cb257ce137899862","added-peer-peer-urls":["https://199.19.213.139:2380"]} {"level":"info","ts":"2026-04-13T17:05:49.443127Z","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-13T17:05:49.443448Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb257ce137899862","initial-advertise-peer-urls":["https://199.19.213.139:2380"],"listen-peer-urls":["https://199.19.213.139:2380"],"advertise-client-urls":["https://199.19.213.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:05:49.443493Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:05:49.443282Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.139:2380"} {"level":"info","ts":"2026-04-13T17:05:49.444077Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.139:2380"} {"level":"info","ts":"2026-04-13T17:05:49.824221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:05:49.824309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:05:49.824344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 received MsgPreVoteResp from cb257ce137899862 at term 1"} {"level":"info","ts":"2026-04-13T17:05:49.824369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:05:49.824383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 received MsgVoteResp from cb257ce137899862 at term 2"} {"level":"info","ts":"2026-04-13T17:05:49.824399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became leader at term 2"} {"level":"info","ts":"2026-04-13T17:05:49.824708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb257ce137899862 elected leader cb257ce137899862 at term 2"} {"level":"info","ts":"2026-04-13T17:05:49.825772Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:05:49.828958Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9913588efaa998c1","local-member-id":"cb257ce137899862","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:05:49.829078Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:05:49.829114Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:05:49.829155Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb257ce137899862","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.139:2379]}","request-path":"/0/members/cb257ce137899862/attributes","cluster-id":"9913588efaa998c1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:05:49.829405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:05:49.829451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:05:49.832051Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:05:49.83208Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:05:49.83317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:05:49.833803Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.139:2379"} {"level":"info","ts":"2026-04-13T17:06:24.884959Z","caller":"traceutil/trace.go:171","msg":"trace[1422844292] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"191.878765ms","start":"2026-04-13T17:06:24.693047Z","end":"2026-04-13T17:06:24.884926Z","steps":["trace[1422844292] 'read index received' (duration: 191.68741ms)","trace[1422844292] 'applied index is now lower than readState.Index' (duration: 189.955µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:06:24.885091Z","caller":"traceutil/trace.go:171","msg":"trace[884214085] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"278.094441ms","start":"2026-04-13T17:06:24.606942Z","end":"2026-04-13T17:06:24.885036Z","steps":["trace[884214085] 'process raft request' (duration: 277.716612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:24.885269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.174865ms","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-13T17:06:24.885359Z","caller":"traceutil/trace.go:171","msg":"trace[1149055979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"192.482096ms","start":"2026-04-13T17:06:24.692863Z","end":"2026-04-13T17:06:24.885345Z","steps":["trace[1149055979] 'agreement among raft nodes before linearized reading' (duration: 192.232283ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:25.084217Z","caller":"traceutil/trace.go:171","msg":"trace[283961869] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"382.395454ms","start":"2026-04-13T17:06:24.701796Z","end":"2026-04-13T17:06:25.084191Z","steps":["trace[283961869] 'process raft request' (duration: 381.976316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:25.084689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:24.701757Z","time spent":"382.53508ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:06:25.095064Z","caller":"traceutil/trace.go:171","msg":"trace[713901713] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"204.317311ms","start":"2026-04-13T17:06:24.890706Z","end":"2026-04-13T17:06:25.095024Z","steps":["trace[713901713] 'process raft request' (duration: 204.082387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:25.529017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.967481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:06:25.529273Z","caller":"traceutil/trace.go:171","msg":"trace[1946491232] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"315.204663ms","start":"2026-04-13T17:06:25.214047Z","end":"2026-04-13T17:06:25.529252Z","steps":["trace[1946491232] 'process raft request' (duration: 315.124025ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:25.529275Z","caller":"traceutil/trace.go:171","msg":"trace[1387518465] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"444.501957ms","start":"2026-04-13T17:06:25.084718Z","end":"2026-04-13T17:06:25.52922Z","steps":["trace[1387518465] 'process raft request' (duration: 268.817346ms)","trace[1387518465] 'compare' (duration: 174.766617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:25.529363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:25.214025Z","time spent":"315.30004ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:06:25.529415Z","caller":"traceutil/trace.go:171","msg":"trace[1228588394] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"379.467826ms","start":"2026-04-13T17:06:25.149903Z","end":"2026-04-13T17:06:25.529371Z","steps":["trace[1228588394] 'read index received' (duration: 203.693888ms)","trace[1228588394] 'applied index is now lower than readState.Index' (duration: 175.770898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:25.529674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.761526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:06:25.529729Z","caller":"traceutil/trace.go:171","msg":"trace[858415375] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:572; }","duration":"379.889443ms","start":"2026-04-13T17:06:25.149822Z","end":"2026-04-13T17:06:25.529711Z","steps":["trace[858415375] 'agreement among raft nodes before linearized reading' (duration: 379.704938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:25.52975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:25.084669Z","time spent":"444.718621ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:06:25.529775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:25.149777Z","time spent":"379.98735ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-13T17:07:09.467409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.633262ms","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-13T17:07:09.467513Z","caller":"traceutil/trace.go:171","msg":"trace[873514198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1146; }","duration":"151.795775ms","start":"2026-04-13T17:07:09.315695Z","end":"2026-04-13T17:07:09.46749Z","steps":["trace[873514198] 'range keys from in-memory index tree' (duration: 151.388547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:57.486093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.27662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-516b361d-c2c9-43a1-a603-dbb1b888c4ab\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-13T17:07:57.486191Z","caller":"traceutil/trace.go:171","msg":"trace[2023225443] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-516b361d-c2c9-43a1-a603-dbb1b888c4ab; range_end:; response_count:1; response_revision:1819; }","duration":"214.432746ms","start":"2026-04-13T17:07:57.271736Z","end":"2026-04-13T17:07:57.486169Z","steps":["trace[2023225443] 'range keys from in-memory index tree' (duration: 214.083501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:57.486246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.231025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:57.486356Z","caller":"traceutil/trace.go:171","msg":"trace[852317903] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1819; }","duration":"133.424052ms","start":"2026-04-13T17:07:57.352912Z","end":"2026-04-13T17:07:57.486336Z","steps":["trace[852317903] 'count revisions from in-memory index tree' (duration: 133.078608ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:57.918893Z","caller":"traceutil/trace.go:171","msg":"trace[308642573] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"195.890813ms","start":"2026-04-13T17:07:57.722965Z","end":"2026-04-13T17:07:57.918856Z","steps":["trace[308642573] 'process raft request' (duration: 195.544288ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:08:13.676234Z","caller":"traceutil/trace.go:171","msg":"trace[1401963892] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2014; }","duration":"111.917259ms","start":"2026-04-13T17:08:13.564293Z","end":"2026-04-13T17:08:13.67621Z","steps":["trace[1401963892] 'read index received' (duration: 111.71941ms)","trace[1401963892] 'applied index is now lower than readState.Index' (duration: 197.139µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:08:13.676408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.084948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:08:13.676453Z","caller":"traceutil/trace.go:171","msg":"trace[1699387716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1943; }","duration":"112.185762ms","start":"2026-04-13T17:08:13.564255Z","end":"2026-04-13T17:08:13.676441Z","steps":["trace[1699387716] 'agreement among raft nodes before linearized reading' (duration: 112.063167ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:08:13.676813Z","caller":"traceutil/trace.go:171","msg":"trace[1755830735] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"140.215844ms","start":"2026-04-13T17:08:13.536586Z","end":"2026-04-13T17:08:13.676802Z","steps":["trace[1755830735] 'process raft request' (duration: 139.487668ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:08:26.332437Z","caller":"traceutil/trace.go:171","msg":"trace[1853041391] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2099; }","duration":"195.617473ms","start":"2026-04-13T17:08:26.136789Z","end":"2026-04-13T17:08:26.332406Z","steps":["trace[1853041391] 'read index received' (duration: 108.630561ms)","trace[1853041391] 'applied index is now lower than readState.Index' (duration: 86.985332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:08:26.33268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.880677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-04-13T17:08:26.332715Z","caller":"traceutil/trace.go:171","msg":"trace[1445759069] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2026; }","duration":"195.975802ms","start":"2026-04-13T17:08:26.136727Z","end":"2026-04-13T17:08:26.332703Z","steps":["trace[1445759069] 'agreement among raft nodes before linearized reading' (duration: 195.785642ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:08:26.333022Z","caller":"traceutil/trace.go:171","msg":"trace[1919208179] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"226.962117ms","start":"2026-04-13T17:08:26.106047Z","end":"2026-04-13T17:08:26.333009Z","steps":["trace[1919208179] 'process raft request' (duration: 139.483278ms)","trace[1919208179] 'compare' (duration: 86.665476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:08:27.473693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.870741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:08:27.473769Z","caller":"traceutil/trace.go:171","msg":"trace[2112793358] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2032; }","duration":"175.000208ms","start":"2026-04-13T17:08:27.298754Z","end":"2026-04-13T17:08:27.473754Z","steps":["trace[2112793358] 'count revisions from in-memory index tree' (duration: 174.742685ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:08:27.905337Z","caller":"traceutil/trace.go:171","msg":"trace[1141247927] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2107; }","duration":"111.654192ms","start":"2026-04-13T17:08:27.793647Z","end":"2026-04-13T17:08:27.905302Z","steps":["trace[1141247927] 'read index received' (duration: 111.390749ms)","trace[1141247927] 'applied index is now lower than readState.Index' (duration: 261.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:08:27.90547Z","caller":"traceutil/trace.go:171","msg":"trace[1930562881] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"145.592479ms","start":"2026-04-13T17:08:27.759825Z","end":"2026-04-13T17:08:27.905417Z","steps":["trace[1930562881] 'process raft request' (duration: 145.24286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:08:27.905533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.850363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:08:27.905604Z","caller":"traceutil/trace.go:171","msg":"trace[641710499] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2034; }","duration":"112.026993ms","start":"2026-04-13T17:08:27.793557Z","end":"2026-04-13T17:08:27.905584Z","steps":["trace[641710499] 'agreement among raft nodes before linearized reading' (duration: 111.876735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:08:28.328542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.410943ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-13T17:08:28.328905Z","caller":"traceutil/trace.go:171","msg":"trace[594725383] linearizableReadLoop","detail":"{readStateIndex:2109; appliedIndex:2108; }","duration":"196.75892ms","start":"2026-04-13T17:08:28.132114Z","end":"2026-04-13T17:08:28.328873Z","steps":["trace[594725383] 'read index received' (duration: 53.945505ms)","trace[594725383] 'applied index is now lower than readState.Index' (duration: 142.811505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:08:28.329433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.042114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:08:28.329505Z","caller":"traceutil/trace.go:171","msg":"trace[309277271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2034; }","duration":"197.458436ms","start":"2026-04-13T17:08:28.132035Z","end":"2026-04-13T17:08:28.329493Z","steps":["trace[309277271] 'agreement among raft nodes before linearized reading' (duration: 196.901167ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:12.580275Z","caller":"traceutil/trace.go:171","msg":"trace[1527859522] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"124.352206ms","start":"2026-04-13T17:10:12.455817Z","end":"2026-04-13T17:10:12.580169Z","steps":["trace[1527859522] 'process raft request' (duration: 124.005544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:31.788737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980512048093039272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:11:32.289954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980512048093039272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:11:32.790488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980512048093039272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:11:33.291661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980512048093039272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:11:33.298047Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.052342314s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T17:11:33.34138Z","caller":"traceutil/trace.go:171","msg":"trace[1698275897] transaction","detail":"{read_only:false; response_revision:3116; number_of_response:1; }","duration":"2.095771445s","start":"2026-04-13T17:11:31.245576Z","end":"2026-04-13T17:11:33.341347Z","steps":["trace[1698275897] 'process raft request' (duration: 2.052826471s)","trace[1698275897] 'compare' (duration: 42.834865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:33.341798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.245552Z","time spent":"2.096147164s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:33.367616Z","caller":"traceutil/trace.go:171","msg":"trace[287246047] transaction","detail":"{read_only:false; response_revision:3117; number_of_response:1; }","duration":"1.833098529s","start":"2026-04-13T17:11:31.534474Z","end":"2026-04-13T17:11:33.367573Z","steps":["trace[287246047] 'process raft request' (duration: 1.83291599s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.367662Z","caller":"traceutil/trace.go:171","msg":"trace[1544076842] transaction","detail":"{read_only:false; response_revision:3118; number_of_response:1; }","duration":"1.423820435s","start":"2026-04-13T17:11:31.943822Z","end":"2026-04-13T17:11:33.367643Z","steps":["trace[1544076842] 'process raft request' (duration: 1.423676076s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.367737Z","caller":"traceutil/trace.go:171","msg":"trace[1443462986] transaction","detail":"{read_only:false; response_revision:3119; number_of_response:1; }","duration":"246.1721ms","start":"2026-04-13T17:11:33.121546Z","end":"2026-04-13T17:11:33.367718Z","steps":["trace[1443462986] 'process raft request' (duration: 246.0001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.367845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.534447Z","time spent":"1.833264839s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:33.367866Z","caller":"traceutil/trace.go:171","msg":"trace[727870285] linearizableReadLoop","detail":"{readStateIndex:3235; appliedIndex:3230; }","duration":"2.082246004s","start":"2026-04-13T17:11:31.285594Z","end":"2026-04-13T17:11:33.36784Z","steps":["trace[727870285] 'read index received' (duration: 2.012803864s)","trace[727870285] 'applied index is now lower than readState.Index' (duration: 69.4406ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:33.367869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.943797Z","time spent":"1.423973865s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:33.36806Z","caller":"traceutil/trace.go:171","msg":"trace[1436448706] transaction","detail":"{read_only:false; response_revision:3120; number_of_response:1; }","duration":"180.248819ms","start":"2026-04-13T17:11:33.187792Z","end":"2026-04-13T17:11:33.368041Z","steps":["trace[1436448706] 'process raft request' (duration: 179.88566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.368144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.082545273s","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-13T17:11:33.368191Z","caller":"traceutil/trace.go:171","msg":"trace[394388381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3120; }","duration":"2.082613813s","start":"2026-04-13T17:11:31.285565Z","end":"2026-04-13T17:11:33.368178Z","steps":["trace[394388381] 'agreement among raft nodes before linearized reading' (duration: 2.082444914s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.36823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.285551Z","time spent":"2.082672412s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:11:33.368255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.078180191s","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-13T17:11:33.368363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605436525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-13T17:11:33.368417Z","caller":"traceutil/trace.go:171","msg":"trace[380882798] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3120; }","duration":"1.605550545s","start":"2026-04-13T17:11:31.762855Z","end":"2026-04-13T17:11:33.368405Z","steps":["trace[380882798] 'agreement among raft nodes before linearized reading' (duration: 1.605318626s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.368453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.650659572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-13T17:11:33.368516Z","caller":"traceutil/trace.go:171","msg":"trace[1185315239] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3120; }","duration":"1.650752932s","start":"2026-04-13T17:11:31.717743Z","end":"2026-04-13T17:11:33.368496Z","steps":["trace[1185315239] 'agreement among raft nodes before linearized reading' (duration: 1.650594312s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.368523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0759227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T17:11:33.368566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.717718Z","time spent":"1.650838012s","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-13T17:11:33.368569Z","caller":"traceutil/trace.go:171","msg":"trace[951608317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3120; }","duration":"2.07598715s","start":"2026-04-13T17:11:31.292569Z","end":"2026-04-13T17:11:33.368557Z","steps":["trace[951608317] 'agreement among raft nodes before linearized reading' (duration: 2.07587175s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.368588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.844143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.139\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T17:11:33.36862Z","caller":"traceutil/trace.go:171","msg":"trace[2043011118] range","detail":"{range_begin:/registry/masterleases/199.19.213.139; range_end:; response_count:1; response_revision:3120; }","duration":"323.903703ms","start":"2026-04-13T17:11:33.044707Z","end":"2026-04-13T17:11:33.36861Z","steps":["trace[2043011118] 'agreement among raft nodes before linearized reading' (duration: 323.775984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.368611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.292563Z","time spent":"2.076040029s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T17:11:33.368653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:33.04469Z","time spent":"323.955953ms","remote":"127.0.0.1:38918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.139\" "} {"level":"warn","ts":"2026-04-13T17:11:33.368733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.075963539s","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-13T17:11:33.368413Z","caller":"traceutil/trace.go:171","msg":"trace[999847470] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3120; }","duration":"2.07837748s","start":"2026-04-13T17:11:31.290002Z","end":"2026-04-13T17:11:33.368379Z","steps":["trace[999847470] 'agreement among raft nodes before linearized reading' (duration: 2.078122071s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.368806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.289948Z","time spent":"2.078848979s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T17:11:33.368836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455316698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:11:33.368861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541817946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:33.368793Z","caller":"traceutil/trace.go:171","msg":"trace[619810146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3120; }","duration":"2.07613464s","start":"2026-04-13T17:11:31.292643Z","end":"2026-04-13T17:11:33.368778Z","steps":["trace[619810146] 'agreement among raft nodes before linearized reading' (duration: 2.0757674s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.368895Z","caller":"traceutil/trace.go:171","msg":"trace[1318609702] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:3120; }","duration":"1.541909546s","start":"2026-04-13T17:11:31.826975Z","end":"2026-04-13T17:11:33.368885Z","steps":["trace[1318609702] 'agreement among raft nodes before linearized reading' (duration: 1.541853156s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.368928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.826948Z","time spent":"1.541972136s","remote":"127.0.0.1:39248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:11:33.36893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.292607Z","time spent":"2.076310989s","remote":"127.0.0.1:39090","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":"warn","ts":"2026-04-13T17:11:33.368458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.762822Z","time spent":"1.605625575s","remote":"127.0.0.1:39358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":276,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:11:33.369001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.296066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-13T17:11:33.369041Z","caller":"traceutil/trace.go:171","msg":"trace[820928877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3120; }","duration":"378.374846ms","start":"2026-04-13T17:11:32.990656Z","end":"2026-04-13T17:11:33.36903Z","steps":["trace[820928877] 'agreement among raft nodes before linearized reading' (duration: 378.255196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.369098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:32.990631Z","time spent":"378.450965ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:11:33.368886Z","caller":"traceutil/trace.go:171","msg":"trace[567293747] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3120; }","duration":"1.455406328s","start":"2026-04-13T17:11:31.913469Z","end":"2026-04-13T17:11:33.368875Z","steps":["trace[567293747] 'agreement among raft nodes before linearized reading' (duration: 1.455309129s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.369209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.913446Z","time spent":"1.455744477s","remote":"127.0.0.1:39186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:11:33.369249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.353365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-13T17:11:33.369282Z","caller":"traceutil/trace.go:171","msg":"trace[1920063384] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3120; }","duration":"892.417305ms","start":"2026-04-13T17:11:32.47685Z","end":"2026-04-13T17:11:33.369268Z","steps":["trace[1920063384] 'agreement among raft nodes before linearized reading' (duration: 892.334455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.369315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:32.476811Z","time spent":"892.498576ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-13T17:11:33.368289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.804577486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:33.369393Z","caller":"traceutil/trace.go:171","msg":"trace[727630033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3120; }","duration":"1.805729151s","start":"2026-04-13T17:11:31.563655Z","end":"2026-04-13T17:11:33.369384Z","steps":["trace[727630033] 'agreement among raft nodes before linearized reading' (duration: 1.804549845s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.369418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.563645Z","time spent":"1.805767161s","remote":"127.0.0.1:38882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:11:33.369413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750508236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-13T17:11:33.369519Z","caller":"traceutil/trace.go:171","msg":"trace[1025762330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3120; }","duration":"1.750668955s","start":"2026-04-13T17:11:31.618818Z","end":"2026-04-13T17:11:33.369487Z","steps":["trace[1025762330] 'agreement among raft nodes before linearized reading' (duration: 1.749625838s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.36963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:31.618793Z","time spent":"1.750794734s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:11:33.576332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.419951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:11:33.576674Z","caller":"traceutil/trace.go:171","msg":"trace[1639440644] transaction","detail":"{read_only:false; response_revision:3121; number_of_response:1; }","duration":"202.403128ms","start":"2026-04-13T17:11:33.374218Z","end":"2026-04-13T17:11:33.576621Z","steps":["trace[1639440644] 'process raft request' (duration: 72.617529ms)","trace[1639440644] 'compare' (duration: 129.303611ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:33.576918Z","caller":"traceutil/trace.go:171","msg":"trace[278109873] transaction","detail":"{read_only:false; response_revision:3122; number_of_response:1; }","duration":"202.560157ms","start":"2026-04-13T17:11:33.374325Z","end":"2026-04-13T17:11:33.576885Z","steps":["trace[278109873] 'process raft request' (duration: 202.104119ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.577006Z","caller":"traceutil/trace.go:171","msg":"trace[2065891078] transaction","detail":"{read_only:false; response_revision:3124; number_of_response:1; }","duration":"201.77269ms","start":"2026-04-13T17:11:33.375206Z","end":"2026-04-13T17:11:33.576979Z","steps":["trace[2065891078] 'process raft request' (duration: 201.68241ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.577141Z","caller":"traceutil/trace.go:171","msg":"trace[1049153044] transaction","detail":"{read_only:false; response_revision:3123; number_of_response:1; }","duration":"202.007799ms","start":"2026-04-13T17:11:33.375111Z","end":"2026-04-13T17:11:33.577119Z","steps":["trace[1049153044] 'process raft request' (duration: 201.602241ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.577184Z","caller":"traceutil/trace.go:171","msg":"trace[994177652] linearizableReadLoop","detail":"{readStateIndex:3243; appliedIndex:3236; }","duration":"194.334803ms","start":"2026-04-13T17:11:33.382823Z","end":"2026-04-13T17:11:33.577158Z","steps":["trace[994177652] 'read index received' (duration: 64.029126ms)","trace[994177652] 'applied index is now lower than readState.Index' (duration: 130.304857ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:33.577304Z","caller":"traceutil/trace.go:171","msg":"trace[1579773995] transaction","detail":"{read_only:false; response_revision:3127; number_of_response:1; }","duration":"200.682954ms","start":"2026-04-13T17:11:33.37661Z","end":"2026-04-13T17:11:33.577293Z","steps":["trace[1579773995] 'process raft request' (duration: 200.511464ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.577309Z","caller":"traceutil/trace.go:171","msg":"trace[1957344402] transaction","detail":"{read_only:false; response_revision:3125; number_of_response:1; }","duration":"202.026299ms","start":"2026-04-13T17:11:33.37526Z","end":"2026-04-13T17:11:33.577286Z","steps":["trace[1957344402] 'process raft request' (duration: 201.67815ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.5773Z","caller":"traceutil/trace.go:171","msg":"trace[330344291] transaction","detail":"{read_only:false; response_revision:3126; number_of_response:1; }","duration":"201.255711ms","start":"2026-04-13T17:11:33.376027Z","end":"2026-04-13T17:11:33.577283Z","steps":["trace[330344291] 'process raft request' (duration: 201.023732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.577605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.447275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:33.577659Z","caller":"traceutil/trace.go:171","msg":"trace[1873144273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3127; }","duration":"200.557215ms","start":"2026-04-13T17:11:33.377089Z","end":"2026-04-13T17:11:33.577646Z","steps":["trace[1873144273] 'agreement among raft nodes before linearized reading' (duration: 200.389896ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:33.603943Z","caller":"traceutil/trace.go:171","msg":"trace[1879260281] transaction","detail":"{read_only:false; response_revision:3128; number_of_response:1; }","duration":"220.406632ms","start":"2026-04-13T17:11:33.383516Z","end":"2026-04-13T17:11:33.603923Z","steps":["trace[1879260281] 'process raft request' (duration: 220.259822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.604214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.937917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-04-13T17:11:33.604286Z","caller":"traceutil/trace.go:171","msg":"trace[69166813] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3128; }","duration":"219.215656ms","start":"2026-04-13T17:11:33.385052Z","end":"2026-04-13T17:11:33.604268Z","steps":["trace[69166813] 'agreement among raft nodes before linearized reading' (duration: 218.854856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.877078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.396782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-13T17:11:33.877158Z","caller":"traceutil/trace.go:171","msg":"trace[930595515] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3128; }","duration":"160.522371ms","start":"2026-04-13T17:11:33.716616Z","end":"2026-04-13T17:11:33.877138Z","steps":["trace[930595515] 'range keys from in-memory index tree' (duration: 160.167912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:33.877236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.575807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-13T17:11:33.877568Z","caller":"traceutil/trace.go:171","msg":"trace[1584402983] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3128; }","duration":"171.713147ms","start":"2026-04-13T17:11:33.705587Z","end":"2026-04-13T17:11:33.877301Z","steps":["trace[1584402983] 'range keys from in-memory index tree' (duration: 171.209127ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:03.004258Z","caller":"traceutil/trace.go:171","msg":"trace[1995424421] transaction","detail":"{read_only:false; response_revision:4454; number_of_response:1; }","duration":"101.909156ms","start":"2026-04-13T17:14:02.902332Z","end":"2026-04-13T17:14:03.004241Z","steps":["trace[1995424421] 'process raft request' (duration: 101.76279ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:03.004451Z","caller":"traceutil/trace.go:171","msg":"trace[874602577] transaction","detail":"{read_only:false; response_revision:4455; number_of_response:1; }","duration":"101.152657ms","start":"2026-04-13T17:14:02.903271Z","end":"2026-04-13T17:14:03.004424Z","steps":["trace[874602577] 'process raft request' (duration: 100.907228ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:09.349894Z","caller":"traceutil/trace.go:171","msg":"trace[2087649450] linearizableReadLoop","detail":"{readStateIndex:4824; appliedIndex:4822; }","duration":"106.878446ms","start":"2026-04-13T17:14:09.242996Z","end":"2026-04-13T17:14:09.349875Z","steps":["trace[2087649450] 'read index received' (duration: 88.114969ms)","trace[2087649450] 'applied index is now lower than readState.Index' (duration: 18.762797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:09.350472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.459108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60455"} {"level":"info","ts":"2026-04-13T17:14:09.350507Z","caller":"traceutil/trace.go:171","msg":"trace[1718127883] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4647; }","duration":"107.52732ms","start":"2026-04-13T17:14:09.242972Z","end":"2026-04-13T17:14:09.350499Z","steps":["trace[1718127883] 'agreement among raft nodes before linearized reading' (duration: 107.022411ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:09.350478Z","caller":"traceutil/trace.go:171","msg":"trace[1197813368] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4647; }","duration":"109.838355ms","start":"2026-04-13T17:14:09.240601Z","end":"2026-04-13T17:14:09.35044Z","steps":["trace[1197813368] 'process raft request' (duration: 109.217313ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:09.350687Z","caller":"traceutil/trace.go:171","msg":"trace[1725981149] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"110.167967ms","start":"2026-04-13T17:14:09.240479Z","end":"2026-04-13T17:14:09.350647Z","steps":["trace[1725981149] 'process raft request' (duration: 90.539839ms)","trace[1725981149] 'compare' (duration: 18.678903ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:16.008447Z","caller":"traceutil/trace.go:171","msg":"trace[608993289] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4880; }","duration":"113.025921ms","start":"2026-04-13T17:14:15.895371Z","end":"2026-04-13T17:14:16.008397Z","steps":["trace[608993289] 'read index received' (duration: 104.271979ms)","trace[608993289] 'applied index is now lower than readState.Index' (duration: 8.752842ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:16.008482Z","caller":"traceutil/trace.go:171","msg":"trace[316386456] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"148.499955ms","start":"2026-04-13T17:14:15.85996Z","end":"2026-04-13T17:14:16.00846Z","steps":["trace[316386456] 'process raft request' (duration: 139.777685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:16.008604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.220387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:14:16.008654Z","caller":"traceutil/trace.go:171","msg":"trace[1891905124] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4702; }","duration":"113.310112ms","start":"2026-04-13T17:14:15.895318Z","end":"2026-04-13T17:14:16.008628Z","steps":["trace[1891905124] 'agreement among raft nodes before linearized reading' (duration: 113.213148ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:16.280617Z","caller":"traceutil/trace.go:171","msg":"trace[1540237580] linearizableReadLoop","detail":"{readStateIndex:4891; appliedIndex:4888; }","duration":"118.521615ms","start":"2026-04-13T17:14:16.162068Z","end":"2026-04-13T17:14:16.28059Z","steps":["trace[1540237580] 'read index received' (duration: 10.907905ms)","trace[1540237580] 'applied index is now lower than readState.Index' (duration: 107.61247ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:16.280643Z","caller":"traceutil/trace.go:171","msg":"trace[179772411] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"117.09641ms","start":"2026-04-13T17:14:16.163507Z","end":"2026-04-13T17:14:16.280603Z","steps":["trace[179772411] 'process raft request' (duration: 116.850371ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:16.280911Z","caller":"traceutil/trace.go:171","msg":"trace[5160369] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"116.170005ms","start":"2026-04-13T17:14:16.164713Z","end":"2026-04-13T17:14:16.280883Z","steps":["trace[5160369] 'process raft request' (duration: 115.823942ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:16.280973Z","caller":"traceutil/trace.go:171","msg":"trace[1274606450] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"122.848479ms","start":"2026-04-13T17:14:16.15808Z","end":"2026-04-13T17:14:16.280928Z","steps":["trace[1274606450] 'process raft request' (duration: 88.9263ms)","trace[1274606450] 'compare' (duration: 33.176701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:16.28107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.629069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-13T17:14:16.28111Z","caller":"traceutil/trace.go:171","msg":"trace[27367948] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4716; }","duration":"118.778975ms","start":"2026-04-13T17:14:16.16232Z","end":"2026-04-13T17:14:16.281099Z","steps":["trace[27367948] 'agreement among raft nodes before linearized reading' (duration: 118.64231ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:16.281249Z","caller":"traceutil/trace.go:171","msg":"trace[1095001025] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"117.506556ms","start":"2026-04-13T17:14:16.163725Z","end":"2026-04-13T17:14:16.281231Z","steps":["trace[1095001025] 'process raft request' (duration: 116.705956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:16.281403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.332915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-13T17:14:16.281495Z","caller":"traceutil/trace.go:171","msg":"trace[585403814] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"116.981966ms","start":"2026-04-13T17:14:16.16449Z","end":"2026-04-13T17:14:16.281472Z","steps":["trace[585403814] 'process raft request' (duration: 116.000129ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:16.281452Z","caller":"traceutil/trace.go:171","msg":"trace[701858495] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4716; }","duration":"119.365626ms","start":"2026-04-13T17:14:16.16206Z","end":"2026-04-13T17:14:16.281426Z","steps":["trace[701858495] 'agreement among raft nodes before linearized reading' (duration: 119.220571ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:16.397206Z","caller":"traceutil/trace.go:171","msg":"trace[824875524] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"101.760247ms","start":"2026-04-13T17:14:16.295418Z","end":"2026-04-13T17:14:16.397178Z","steps":["trace[824875524] 'process raft request' (duration: 99.48118ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:16.397224Z","caller":"traceutil/trace.go:171","msg":"trace[602857938] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"101.190645ms","start":"2026-04-13T17:14:16.296006Z","end":"2026-04-13T17:14:16.397197Z","steps":["trace[602857938] 'process raft request' (duration: 101.05859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:16.397521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.907433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-13T17:14:16.397604Z","caller":"traceutil/trace.go:171","msg":"trace[2049932742] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4722; }","duration":"102.09087ms","start":"2026-04-13T17:14:16.295485Z","end":"2026-04-13T17:14:16.397575Z","steps":["trace[2049932742] 'agreement among raft nodes before linearized reading' (duration: 101.766168ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:49.949057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2903} {"level":"info","ts":"2026-04-13T17:15:50.04707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2903,"took":"96.360428ms","hash":3158228278,"current-db-size-bytes":53501952,"current-db-size":"54 MB","current-db-size-in-use-bytes":28942336,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-13T17:15:50.047162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3158228278,"revision":2903,"compact-revision":-1} {"level":"info","ts":"2026-04-13T17:18:13.086729Z","caller":"traceutil/trace.go:171","msg":"trace[1790539979] transaction","detail":"{read_only:false; response_revision:6554; number_of_response:1; }","duration":"101.55016ms","start":"2026-04-13T17:18:12.985156Z","end":"2026-04-13T17:18:13.086707Z","steps":["trace[1790539979] 'process raft request' (duration: 101.379982ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:20:49.955574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5582} {"level":"info","ts":"2026-04-13T17:20:50.048287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5582,"took":"91.53667ms","hash":428248382,"current-db-size-bytes":53501952,"current-db-size":"54 MB","current-db-size-in-use-bytes":18096128,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-13T17:20:50.048342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":428248382,"revision":5582,"compact-revision":2903}