{"level":"warn","ts":"2026-05-05T11:21:59.642833Z","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-05T11:21:59.64293Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-05T11:21:59.643003Z","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-05T11:21:59.643015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-05T11:21:59.643035Z","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-05T11:21:59.643767Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-05T11:21:59.643886Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-05T11:21:59.647455Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.350461ms"} {"level":"info","ts":"2026-05-05T11:21:59.653082Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-05T11:21:59.653188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T11:21:59.653221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-05T11:21:59.653246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T11:21:59.65327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-05T11:21:59.65333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-05T11:21:59.660259Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T11:21:59.661546Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T11:21:59.662739Z","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-05T11:21:59.664991Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T11:21:59.665233Z","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-05T11:21:59.665388Z","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-05T11:21:59.665404Z","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-05T11:21:59.665365Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T11:21:59.666306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-05T11:21:59.667834Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-05T11:21:59.672315Z","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-05T11:21:59.672444Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-05T11:21:59.672486Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-05T11:21:59.672917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T11:21:59.672982Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T11:22:00.454054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T11:22:00.454121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T11:22:00.454165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-05T11:22:00.454187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-05T11:22:00.454199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-05T11:22:00.454216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-05T11:22:00.454231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-05T11:22:00.455974Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:22:00.458011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:22:00.458009Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T11:22:00.458088Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:22:00.458397Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T11:22:00.458513Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T11:22:00.458562Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:22:00.458671Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:22:00.458706Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:22:00.461177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-05-05T11:22:00.462007Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T11:22:36.942371Z","caller":"traceutil/trace.go:171","msg":"trace[963848592] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"138.791667ms","start":"2026-05-05T11:22:36.803525Z","end":"2026-05-05T11:22:36.942317Z","steps":["trace[963848592] 'process raft request' (duration: 138.490771ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:37.214598Z","caller":"traceutil/trace.go:171","msg":"trace[527455780] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"335.230141ms","start":"2026-05-05T11:22:36.879339Z","end":"2026-05-05T11:22:37.214569Z","steps":["trace[527455780] 'process raft request' (duration: 334.72935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:37.21522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:22:36.879305Z","time spent":"335.374664ms","remote":"127.0.0.1:41266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:22:37.748914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.089736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:22:37.749054Z","caller":"traceutil/trace.go:171","msg":"trace[1446452696] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"180.568944ms","start":"2026-05-05T11:22:37.568468Z","end":"2026-05-05T11:22:37.749037Z","steps":["trace[1446452696] 'read index received' (duration: 43.931µs)","trace[1446452696] 'applied index is now lower than readState.Index' (duration: 180.523323ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:22:37.749149Z","caller":"traceutil/trace.go:171","msg":"trace[1282240536] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"476.968032ms","start":"2026-05-05T11:22:37.272131Z","end":"2026-05-05T11:22:37.749099Z","steps":["trace[1282240536] 'process raft request' (duration: 20.452838ms)","trace[1282240536] 'compare' (duration: 455.901631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:22:37.749208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.719177ms","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-05-05T11:22:37.749286Z","caller":"traceutil/trace.go:171","msg":"trace[1283634227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"180.84441ms","start":"2026-05-05T11:22:37.568425Z","end":"2026-05-05T11:22:37.749269Z","steps":["trace[1283634227] 'agreement among raft nodes before linearized reading' (duration: 180.679596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:37.749324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:22:37.2721Z","time spent":"477.143786ms","remote":"127.0.0.1:41670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:24:05.876944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.083998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:24:05.877048Z","caller":"traceutil/trace.go:171","msg":"trace[254259920] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"320.944412ms","start":"2026-05-05T11:24:05.556089Z","end":"2026-05-05T11:24:05.877033Z","steps":["trace[254259920] 'read index received' (duration: 93.595058ms)","trace[254259920] 'applied index is now lower than readState.Index' (duration: 227.348164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:24:05.877246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.160656ms","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-05T11:24:05.877186Z","caller":"traceutil/trace.go:171","msg":"trace[627853308] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"346.710068ms","start":"2026-05-05T11:24:05.530431Z","end":"2026-05-05T11:24:05.877141Z","steps":["trace[627853308] 'process raft request' (duration: 119.338854ms)","trace[627853308] 'compare' (duration: 226.893045ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:24:05.877287Z","caller":"traceutil/trace.go:171","msg":"trace[1704422499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1825; }","duration":"321.235308ms","start":"2026-05-05T11:24:05.556043Z","end":"2026-05-05T11:24:05.877278Z","steps":["trace[1704422499] 'agreement among raft nodes before linearized reading' (duration: 321.065054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:05.877311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:24:05.556024Z","time spent":"321.280628ms","remote":"127.0.0.1:41444","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":"warn","ts":"2026-05-05T11:24:05.877372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:24:05.530415Z","time spent":"346.886021ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:24:05.955552Z","caller":"traceutil/trace.go:171","msg":"trace[2141595390] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"112.01059ms","start":"2026-05-05T11:24:05.84351Z","end":"2026-05-05T11:24:05.955521Z","steps":["trace[2141595390] 'process raft request' (duration: 111.838586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:05.955664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.62674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d342981-0070-49ad-9a0e-d1d35b9c512c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T11:24:05.955734Z","caller":"traceutil/trace.go:171","msg":"trace[6523903] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d342981-0070-49ad-9a0e-d1d35b9c512c; range_end:; response_count:1; response_revision:1826; }","duration":"116.745672ms","start":"2026-05-05T11:24:05.838969Z","end":"2026-05-05T11:24:05.955714Z","steps":["trace[6523903] 'agreement among raft nodes before linearized reading' (duration: 116.506908ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:24:06.033362Z","caller":"traceutil/trace.go:171","msg":"trace[1815016341] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"152.25654ms","start":"2026-05-05T11:24:05.881081Z","end":"2026-05-05T11:24:06.033338Z","steps":["trace[1815016341] 'process raft request' (duration: 97.377742ms)","trace[1815016341] 'compare' (duration: 54.707125ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:24:06.488952Z","caller":"traceutil/trace.go:171","msg":"trace[592033812] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1897; }","duration":"200.398374ms","start":"2026-05-05T11:24:06.288526Z","end":"2026-05-05T11:24:06.488924Z","steps":["trace[592033812] 'read index received' (duration: 200.16055ms)","trace[592033812] 'applied index is now lower than readState.Index' (duration: 236.244µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:24:06.489014Z","caller":"traceutil/trace.go:171","msg":"trace[1994572493] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"219.780854ms","start":"2026-05-05T11:24:06.269197Z","end":"2026-05-05T11:24:06.488978Z","steps":["trace[1994572493] 'process raft request' (duration: 219.53226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:06.489202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.65697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T11:24:06.48924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.514143ms","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-05T11:24:06.489263Z","caller":"traceutil/trace.go:171","msg":"trace[1808311781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1828; }","duration":"200.771493ms","start":"2026-05-05T11:24:06.288478Z","end":"2026-05-05T11:24:06.48925Z","steps":["trace[1808311781] 'agreement among raft nodes before linearized reading' (duration: 200.591258ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:24:06.489294Z","caller":"traceutil/trace.go:171","msg":"trace[814778473] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1828; }","duration":"103.620334ms","start":"2026-05-05T11:24:06.385656Z","end":"2026-05-05T11:24:06.489277Z","steps":["trace[814778473] 'agreement among raft nodes before linearized reading' (duration: 103.425229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:14.79733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.540581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:24:14.797465Z","caller":"traceutil/trace.go:171","msg":"trace[348135188] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"116.799613ms","start":"2026-05-05T11:24:14.680645Z","end":"2026-05-05T11:24:14.797445Z","steps":["trace[348135188] 'read index received' (duration: 6.967307ms)","trace[348135188] 'applied index is now lower than readState.Index' (duration: 109.830556ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:24:14.797547Z","caller":"traceutil/trace.go:171","msg":"trace[673518708] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"205.79832ms","start":"2026-05-05T11:24:14.591719Z","end":"2026-05-05T11:24:14.797517Z","steps":["trace[673518708] 'process raft request' (duration: 95.965714ms)","trace[673518708] 'compare' (duration: 109.395248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:24:14.797649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.002587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11982"} {"level":"info","ts":"2026-05-05T11:24:14.797713Z","caller":"traceutil/trace.go:171","msg":"trace[1835392509] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1915; }","duration":"117.066648ms","start":"2026-05-05T11:24:14.680632Z","end":"2026-05-05T11:24:14.797698Z","steps":["trace[1835392509] 'agreement among raft nodes before linearized reading' (duration: 116.870154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:45.788065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.114953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-05-05T11:24:45.788151Z","caller":"traceutil/trace.go:171","msg":"trace[1337749833] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2100; }","duration":"246.253494ms","start":"2026-05-05T11:24:45.541877Z","end":"2026-05-05T11:24:45.78813Z","steps":["trace[1337749833] 'range keys from in-memory index tree' (duration: 245.896138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:45.788165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.595093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T11:24:45.788247Z","caller":"traceutil/trace.go:171","msg":"trace[376842696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2100; }","duration":"297.687194ms","start":"2026-05-05T11:24:45.490544Z","end":"2026-05-05T11:24:45.788231Z","steps":["trace[376842696] 'range keys from in-memory index tree' (duration: 297.433929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:45.788308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.235321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T11:24:45.788589Z","caller":"traceutil/trace.go:171","msg":"trace[1674242789] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2100; }","duration":"337.333953ms","start":"2026-05-05T11:24:45.451026Z","end":"2026-05-05T11:24:45.78836Z","steps":["trace[1674242789] 'range keys from in-memory index tree' (duration: 336.703511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:45.788653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:24:45.45101Z","time spent":"337.626898ms","remote":"127.0.0.1:45338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-05T11:24:45.895001Z","caller":"traceutil/trace.go:171","msg":"trace[265707635] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"294.94317ms","start":"2026-05-05T11:24:45.600032Z","end":"2026-05-05T11:24:45.894975Z","steps":["trace[265707635] 'process raft request' (duration: 294.777307ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:24:45.953693Z","caller":"traceutil/trace.go:171","msg":"trace[1439236154] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"161.036742ms","start":"2026-05-05T11:24:45.792631Z","end":"2026-05-05T11:24:45.953668Z","steps":["trace[1439236154] 'process raft request' (duration: 160.885239ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:24:46.123052Z","caller":"traceutil/trace.go:171","msg":"trace[299557126] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"103.478911ms","start":"2026-05-05T11:24:46.019544Z","end":"2026-05-05T11:24:46.123022Z","steps":["trace[299557126] 'process raft request' (duration: 65.103207ms)","trace[299557126] 'compare' (duration: 38.164889ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:26:19.619582Z","caller":"traceutil/trace.go:171","msg":"trace[272517120] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"131.666321ms","start":"2026-05-05T11:26:19.487882Z","end":"2026-05-05T11:26:19.619548Z","steps":["trace[272517120] 'process raft request' (duration: 131.446827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:45.611619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.627999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:28:45.611729Z","caller":"traceutil/trace.go:171","msg":"trace[1970694366] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:3772; }","duration":"100.770803ms","start":"2026-05-05T11:28:45.510938Z","end":"2026-05-05T11:28:45.611709Z","steps":["trace[1970694366] 'count revisions from in-memory index tree' (duration: 100.479516ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:08.736803Z","caller":"traceutil/trace.go:171","msg":"trace[2124244875] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"118.01453ms","start":"2026-05-05T11:30:08.618765Z","end":"2026-05-05T11:30:08.736779Z","steps":["trace[2124244875] 'process raft request' (duration: 117.848587ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:08.960927Z","caller":"traceutil/trace.go:171","msg":"trace[245609981] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5112; }","duration":"224.144691ms","start":"2026-05-05T11:30:08.736758Z","end":"2026-05-05T11:30:08.960902Z","steps":["trace[245609981] 'read index received' (duration: 224.040489ms)","trace[245609981] 'applied index is now lower than readState.Index' (duration: 103.012µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:30:08.96105Z","caller":"traceutil/trace.go:171","msg":"trace[524676236] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"262.394176ms","start":"2026-05-05T11:30:08.69862Z","end":"2026-05-05T11:30:08.961014Z","steps":["trace[524676236] 'process raft request' (duration: 262.121661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:08.961183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.954738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:30:08.961226Z","caller":"traceutil/trace.go:171","msg":"trace[1936286458] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4932; }","duration":"240.017989ms","start":"2026-05-05T11:30:08.721198Z","end":"2026-05-05T11:30:08.961216Z","steps":["trace[1936286458] 'agreement among raft nodes before linearized reading' (duration: 239.850436ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:08.962246Z","caller":"traceutil/trace.go:171","msg":"trace[1598410452] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"221.817435ms","start":"2026-05-05T11:30:08.740409Z","end":"2026-05-05T11:30:08.962227Z","steps":["trace[1598410452] 'process raft request' (duration: 221.502958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:08.974647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.891446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa59a70c-d52d-4626-818d-fa1be5f8dd52\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T11:30:08.974715Z","caller":"traceutil/trace.go:171","msg":"trace[788403044] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa59a70c-d52d-4626-818d-fa1be5f8dd52; range_end:; response_count:1; response_revision:4933; }","duration":"212.997438ms","start":"2026-05-05T11:30:08.761703Z","end":"2026-05-05T11:30:08.9747Z","steps":["trace[788403044] 'agreement among raft nodes before linearized reading' (duration: 212.829794ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:10.297566Z","caller":"traceutil/trace.go:171","msg":"trace[1384932492] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"100.952258ms","start":"2026-05-05T11:30:10.196577Z","end":"2026-05-05T11:30:10.29753Z","steps":["trace[1384932492] 'process raft request' (duration: 100.772455ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:19.307043Z","caller":"traceutil/trace.go:171","msg":"trace[443059831] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"107.773876ms","start":"2026-05-05T11:30:19.199232Z","end":"2026-05-05T11:30:19.307006Z","steps":["trace[443059831] 'process raft request' (duration: 69.883088ms)","trace[443059831] 'compare' (duration: 37.585842ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:30:19.307144Z","caller":"traceutil/trace.go:171","msg":"trace[1816135962] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"107.793126ms","start":"2026-05-05T11:30:19.199313Z","end":"2026-05-05T11:30:19.307106Z","steps":["trace[1816135962] 'process raft request' (duration: 107.593152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:19.307186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.832655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:30:19.307222Z","caller":"traceutil/trace.go:171","msg":"trace[583483277] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5152; }","duration":"101.928598ms","start":"2026-05-05T11:30:19.205284Z","end":"2026-05-05T11:30:19.307212Z","steps":["trace[583483277] 'agreement among raft nodes before linearized reading' (duration: 101.837146ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:19.307078Z","caller":"traceutil/trace.go:171","msg":"trace[1596496616] linearizableReadLoop","detail":"{readStateIndex:5341; appliedIndex:5338; }","duration":"101.710483ms","start":"2026-05-05T11:30:19.205341Z","end":"2026-05-05T11:30:19.307051Z","steps":["trace[1596496616] 'read index received' (duration: 55.102211ms)","trace[1596496616] 'applied index is now lower than readState.Index' (duration: 46.606602ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:30:27.481852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.105051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:30:27.482047Z","caller":"traceutil/trace.go:171","msg":"trace[803410042] linearizableReadLoop","detail":"{readStateIndex:5519; appliedIndex:5517; }","duration":"134.332876ms","start":"2026-05-05T11:30:27.347695Z","end":"2026-05-05T11:30:27.482027Z","steps":["trace[803410042] 'read index received' (duration: 66.317846ms)","trace[803410042] 'applied index is now lower than readState.Index' (duration: 68.01395ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:30:27.482135Z","caller":"traceutil/trace.go:171","msg":"trace[1500590517] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"199.513559ms","start":"2026-05-05T11:30:27.282596Z","end":"2026-05-05T11:30:27.48211Z","steps":["trace[1500590517] 'process raft request' (duration: 42.056631ms)","trace[1500590517] 'compare' (duration: 156.775795ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:30:27.482157Z","caller":"traceutil/trace.go:171","msg":"trace[1911709557] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"142.418198ms","start":"2026-05-05T11:30:27.339712Z","end":"2026-05-05T11:30:27.482131Z","steps":["trace[1911709557] 'process raft request' (duration: 142.238564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:27.482354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.661792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e50c41cb-1060-4f75-b8f3-12a38239f482\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-05-05T11:30:27.482386Z","caller":"traceutil/trace.go:171","msg":"trace[216314161] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e50c41cb-1060-4f75-b8f3-12a38239f482; range_end:; response_count:1; response_revision:5324; }","duration":"134.695523ms","start":"2026-05-05T11:30:27.34768Z","end":"2026-05-05T11:30:27.482376Z","steps":["trace[216314161] 'agreement among raft nodes before linearized reading' (duration: 134.604331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:27.774341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.902797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:30:27.774519Z","caller":"traceutil/trace.go:171","msg":"trace[1466299477] linearizableReadLoop","detail":"{readStateIndex:5520; appliedIndex:5519; }","duration":"292.410656ms","start":"2026-05-05T11:30:27.482082Z","end":"2026-05-05T11:30:27.774492Z","steps":["trace[1466299477] 'read index received' (duration: 104.796766ms)","trace[1466299477] 'applied index is now lower than readState.Index' (duration: 187.61216ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:30:27.774591Z","caller":"traceutil/trace.go:171","msg":"trace[1572232754] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"410.635401ms","start":"2026-05-05T11:30:27.363925Z","end":"2026-05-05T11:30:27.774561Z","steps":["trace[1572232754] 'process raft request' (duration: 223.351976ms)","trace[1572232754] 'compare' (duration: 186.700803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:30:27.774691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.919867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T11:30:27.774765Z","caller":"traceutil/trace.go:171","msg":"trace[1677509204] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5325; }","duration":"319.030079ms","start":"2026-05-05T11:30:27.455721Z","end":"2026-05-05T11:30:27.774751Z","steps":["trace[1677509204] 'agreement among raft nodes before linearized reading' (duration: 318.909417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:27.774778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:30:27.363909Z","time spent":"410.742643ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:30:27.774813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:30:27.455704Z","time spent":"319.09556ms","remote":"127.0.0.1:46430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T11:30:27.774822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.468077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T11:30:27.774932Z","caller":"traceutil/trace.go:171","msg":"trace[1477075495] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5325; }","duration":"400.62755ms","start":"2026-05-05T11:30:27.374272Z","end":"2026-05-05T11:30:27.7749Z","steps":["trace[1477075495] 'agreement among raft nodes before linearized reading' (duration: 400.447756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:27.774961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.880147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T11:30:27.774997Z","caller":"traceutil/trace.go:171","msg":"trace[85328817] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5325; }","duration":"121.945379ms","start":"2026-05-05T11:30:27.653041Z","end":"2026-05-05T11:30:27.774986Z","steps":["trace[85328817] 'agreement among raft nodes before linearized reading' (duration: 121.802096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:27.774994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:30:27.374252Z","time spent":"400.727843ms","remote":"127.0.0.1:45300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-05T11:32:00.493139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3022} {"level":"info","ts":"2026-05-05T11:32:00.576218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3022,"took":"81.582579ms","hash":285135256,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":32038912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T11:32:00.576281Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":285135256,"revision":3022,"compact-revision":-1} {"level":"info","ts":"2026-05-05T11:36:19.974616Z","caller":"traceutil/trace.go:171","msg":"trace[1875837270] linearizableReadLoop","detail":"{readStateIndex:8365; appliedIndex:8364; }","duration":"246.549362ms","start":"2026-05-05T11:36:19.728034Z","end":"2026-05-05T11:36:19.974583Z","steps":["trace[1875837270] 'read index received' (duration: 246.376939ms)","trace[1875837270] 'applied index is now lower than readState.Index' (duration: 170.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:36:19.974688Z","caller":"traceutil/trace.go:171","msg":"trace[1931833787] transaction","detail":"{read_only:false; response_revision:8085; number_of_response:1; }","duration":"249.250385ms","start":"2026-05-05T11:36:19.725383Z","end":"2026-05-05T11:36:19.974634Z","steps":["trace[1931833787] 'process raft request' (duration: 249.026611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:36:19.974851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.784046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T11:36:19.974891Z","caller":"traceutil/trace.go:171","msg":"trace[479982898] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8085; }","duration":"246.873859ms","start":"2026-05-05T11:36:19.728007Z","end":"2026-05-05T11:36:19.974881Z","steps":["trace[479982898] 'agreement among raft nodes before linearized reading' (duration: 246.697135ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:36:20.132854Z","caller":"traceutil/trace.go:171","msg":"trace[1951851691] transaction","detail":"{read_only:false; response_revision:8086; number_of_response:1; }","duration":"128.051944ms","start":"2026-05-05T11:36:20.004747Z","end":"2026-05-05T11:36:20.132799Z","steps":["trace[1951851691] 'process raft request' (duration: 127.187307ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:36:33.272486Z","caller":"traceutil/trace.go:171","msg":"trace[372673922] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"119.65889ms","start":"2026-05-05T11:36:33.152786Z","end":"2026-05-05T11:36:33.272444Z","steps":["trace[372673922] 'process raft request' (duration: 119.432695ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:00.699946Z","caller":"traceutil/trace.go:171","msg":"trace[1599465152] linearizableReadLoop","detail":"{readStateIndex:8645; appliedIndex:8644; }","duration":"260.279142ms","start":"2026-05-05T11:37:00.43964Z","end":"2026-05-05T11:37:00.699919Z","steps":["trace[1599465152] 'read index received' (duration: 260.208361ms)","trace[1599465152] 'applied index is now lower than readState.Index' (duration: 69.541µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:37:00.700389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.714732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T11:37:00.700367Z","caller":"traceutil/trace.go:171","msg":"trace[316295358] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"400.586469ms","start":"2026-05-05T11:37:00.299596Z","end":"2026-05-05T11:37:00.700183Z","steps":["trace[316295358] 'process raft request' (duration: 400.146621ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:00.700444Z","caller":"traceutil/trace.go:171","msg":"trace[1868092939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8354; }","duration":"260.843593ms","start":"2026-05-05T11:37:00.43959Z","end":"2026-05-05T11:37:00.700433Z","steps":["trace[1868092939] 'agreement among raft nodes before linearized reading' (duration: 260.589748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:37:00.700559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:37:00.299567Z","time spent":"400.900786ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:37:00.700883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.388396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T11:37:00.700907Z","caller":"traceutil/trace.go:171","msg":"trace[1667274957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8355; }","duration":"253.521969ms","start":"2026-05-05T11:37:00.447378Z","end":"2026-05-05T11:37:00.7009Z","steps":["trace[1667274957] 'agreement among raft nodes before linearized reading' (duration: 253.417577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:37:00.701023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.578274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:37:00.701065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.283631ms","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":"info","ts":"2026-05-05T11:37:00.701077Z","caller":"traceutil/trace.go:171","msg":"trace[952849712] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8355; }","duration":"170.674005ms","start":"2026-05-05T11:37:00.530385Z","end":"2026-05-05T11:37:00.701059Z","steps":["trace[952849712] 'agreement among raft nodes before linearized reading' (duration: 170.587734ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:00.701184Z","caller":"traceutil/trace.go:171","msg":"trace[1462859415] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"206.784438ms","start":"2026-05-05T11:37:00.494392Z","end":"2026-05-05T11:37:00.701177Z","steps":["trace[1462859415] 'process raft request' (duration: 206.320589ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:00.701088Z","caller":"traceutil/trace.go:171","msg":"trace[585180441] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8355; }","duration":"194.305632ms","start":"2026-05-05T11:37:00.506774Z","end":"2026-05-05T11:37:00.70108Z","steps":["trace[585180441] 'agreement among raft nodes before linearized reading' (duration: 194.241ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:00.705122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6167} {"level":"info","ts":"2026-05-05T11:37:00.807003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6167,"took":"98.992331ms","hash":1069402481,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":23175168,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T11:37:00.807108Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1069402481,"revision":6167,"compact-revision":3022} {"level":"info","ts":"2026-05-05T11:37:12.964917Z","caller":"traceutil/trace.go:171","msg":"trace[1660402121] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8464; }","duration":"113.039959ms","start":"2026-05-05T11:37:12.851854Z","end":"2026-05-05T11:37:12.964894Z","steps":["trace[1660402121] 'process raft request' (duration: 112.897876ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:13.110373Z","caller":"traceutil/trace.go:171","msg":"trace[358081205] linearizableReadLoop","detail":"{readStateIndex:8762; appliedIndex:8760; }","duration":"138.342028ms","start":"2026-05-05T11:37:12.972009Z","end":"2026-05-05T11:37:13.110351Z","steps":["trace[358081205] 'read index received' (duration: 72.722104ms)","trace[358081205] 'applied index is now lower than readState.Index' (duration: 65.619104ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:37:13.110456Z","caller":"traceutil/trace.go:171","msg":"trace[447625064] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"253.340866ms","start":"2026-05-05T11:37:12.857084Z","end":"2026-05-05T11:37:13.110425Z","steps":["trace[447625064] 'process raft request' (duration: 187.632099ms)","trace[447625064] 'compare' (duration: 65.469942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:37:13.110577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.529023ms","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-05-05T11:37:13.110612Z","caller":"traceutil/trace.go:171","msg":"trace[1174136641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8466; }","duration":"138.643584ms","start":"2026-05-05T11:37:12.971956Z","end":"2026-05-05T11:37:13.1106Z","steps":["trace[1174136641] 'agreement among raft nodes before linearized reading' (duration: 138.467421ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:13.110583Z","caller":"traceutil/trace.go:171","msg":"trace[184895532] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"178.958699ms","start":"2026-05-05T11:37:12.931595Z","end":"2026-05-05T11:37:13.110553Z","steps":["trace[184895532] 'process raft request' (duration: 178.691984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:37:13.901183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.417743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-05-05T11:37:13.901281Z","caller":"traceutil/trace.go:171","msg":"trace[1631881736] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8470; }","duration":"124.582076ms","start":"2026-05-05T11:37:13.776676Z","end":"2026-05-05T11:37:13.901258Z","steps":["trace[1631881736] 'range keys from in-memory index tree' (duration: 124.200118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:37:13.901462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.876289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T11:37:13.901557Z","caller":"traceutil/trace.go:171","msg":"trace[1986516602] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:8470; }","duration":"171.027722ms","start":"2026-05-05T11:37:13.730507Z","end":"2026-05-05T11:37:13.901534Z","steps":["trace[1986516602] 'range keys from in-memory index tree' (duration: 170.636455ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:14.548164Z","caller":"traceutil/trace.go:171","msg":"trace[1903790110] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"124.931434ms","start":"2026-05-05T11:37:14.423202Z","end":"2026-05-05T11:37:14.548134Z","steps":["trace[1903790110] 'process raft request' (duration: 113.125931ms)","trace[1903790110] 'compare' (duration: 11.593479ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:37:14.885146Z","caller":"traceutil/trace.go:171","msg":"trace[1160814116] linearizableReadLoop","detail":"{readStateIndex:8776; appliedIndex:8775; }","duration":"127.84116ms","start":"2026-05-05T11:37:14.75728Z","end":"2026-05-05T11:37:14.885121Z","steps":["trace[1160814116] 'read index received' (duration: 104.780165ms)","trace[1160814116] 'applied index is now lower than readState.Index' (duration: 23.059775ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:37:14.885213Z","caller":"traceutil/trace.go:171","msg":"trace[1591761595] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"134.431821ms","start":"2026-05-05T11:37:14.750741Z","end":"2026-05-05T11:37:14.885173Z","steps":["trace[1591761595] 'process raft request' (duration: 111.316535ms)","trace[1591761595] 'compare' (duration: 22.957583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:37:14.885367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.071305ms","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":"info","ts":"2026-05-05T11:37:14.88541Z","caller":"traceutil/trace.go:171","msg":"trace[420164261] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8479; }","duration":"128.180247ms","start":"2026-05-05T11:37:14.757216Z","end":"2026-05-05T11:37:14.885397Z","steps":["trace[420164261] 'agreement among raft nodes before linearized reading' (duration: 128.029614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:37:14.885459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.017658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T11:37:14.885551Z","caller":"traceutil/trace.go:171","msg":"trace[1229517756] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8479; }","duration":"113.149621ms","start":"2026-05-05T11:37:14.772379Z","end":"2026-05-05T11:37:14.885528Z","steps":["trace[1229517756] 'agreement among raft nodes before linearized reading' (duration: 112.981807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:37:14.885591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.193899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-05T11:37:14.885568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.177729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T11:37:14.885627Z","caller":"traceutil/trace.go:171","msg":"trace[1191271082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8479; }","duration":"123.24916ms","start":"2026-05-05T11:37:14.762369Z","end":"2026-05-05T11:37:14.885618Z","steps":["trace[1191271082] 'agreement among raft nodes before linearized reading' (duration: 123.162248ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:14.885667Z","caller":"traceutil/trace.go:171","msg":"trace[1287635215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8479; }","duration":"123.336043ms","start":"2026-05-05T11:37:14.762309Z","end":"2026-05-05T11:37:14.885645Z","steps":["trace[1287635215] 'agreement among raft nodes before linearized reading' (duration: 123.012856ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:37:27.293663Z","caller":"traceutil/trace.go:171","msg":"trace[753428768] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"102.279288ms","start":"2026-05-05T11:37:27.191355Z","end":"2026-05-05T11:37:27.293634Z","steps":["trace[753428768] 'process raft request' (duration: 12.64287ms)","trace[753428768] 'compare' (duration: 89.23226ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:38:09.132819Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247a.wal"} {"level":"info","ts":"2026-05-05T11:39:33.593096Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T11:39:33.598628Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T11:39:33.598749Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T11:42:00.71215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8355} {"level":"info","ts":"2026-05-05T11:42:00.786955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8355,"took":"73.076879ms","hash":2507486034,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":26505216,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T11:42:00.787032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2507486034,"revision":8355,"compact-revision":6167} {"level":"info","ts":"2026-05-05T11:47:00.719665Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10997} {"level":"info","ts":"2026-05-05T11:47:00.804897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10997,"took":"82.277661ms","hash":3576387400,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":27807744,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T11:47:00.804971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3576387400,"revision":10997,"compact-revision":8355} {"level":"warn","ts":"2026-05-05T11:47:54.080824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.7033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:47:54.080959Z","caller":"traceutil/trace.go:171","msg":"trace[1460312847] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:13404; }","duration":"128.871293ms","start":"2026-05-05T11:47:53.952061Z","end":"2026-05-05T11:47:54.080932Z","steps":["trace[1460312847] 'count revisions from in-memory index tree' (duration: 128.646249ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:52:00.727382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13026} {"level":"info","ts":"2026-05-05T11:52:00.804125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13026,"took":"74.449348ms","hash":909772709,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":23670784,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T11:52:00.804181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":909772709,"revision":13026,"compact-revision":10997} {"level":"info","ts":"2026-05-05T11:53:11.505652Z","caller":"traceutil/trace.go:171","msg":"trace[42033735] transaction","detail":"{read_only:false; response_revision:15023; number_of_response:1; }","duration":"484.220054ms","start":"2026-05-05T11:53:11.021406Z","end":"2026-05-05T11:53:11.505626Z","steps":["trace[42033735] 'process raft request' (duration: 484.068261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:53:11.505886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:53:11.021391Z","time spent":"484.415758ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:53:11.843339Z","caller":"traceutil/trace.go:171","msg":"trace[1117731031] transaction","detail":"{read_only:false; response_revision:15024; number_of_response:1; }","duration":"765.177281ms","start":"2026-05-05T11:53:11.078138Z","end":"2026-05-05T11:53:11.843316Z","steps":["trace[1117731031] 'process raft request' (duration: 765.030588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:53:11.843477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:53:11.078115Z","time spent":"765.284373ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:53:11.843668Z","caller":"traceutil/trace.go:171","msg":"trace[701600691] linearizableReadLoop","detail":"{readStateIndex:15544; appliedIndex:15544; }","duration":"127.905139ms","start":"2026-05-05T11:53:11.715737Z","end":"2026-05-05T11:53:11.843642Z","steps":["trace[701600691] 'read index received' (duration: 127.819607ms)","trace[701600691] 'applied index is now lower than readState.Index' (duration: 83.092µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:53:11.844105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.349408ms","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":"info","ts":"2026-05-05T11:53:11.844174Z","caller":"traceutil/trace.go:171","msg":"trace[79385671] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15024; }","duration":"128.43168ms","start":"2026-05-05T11:53:11.715725Z","end":"2026-05-05T11:53:11.844156Z","steps":["trace[79385671] 'agreement among raft nodes before linearized reading' (duration: 127.993691ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:53:11.84512Z","caller":"traceutil/trace.go:171","msg":"trace[199251071] transaction","detail":"{read_only:false; response_revision:15025; number_of_response:1; }","duration":"120.915808ms","start":"2026-05-05T11:53:11.724149Z","end":"2026-05-05T11:53:11.845065Z","steps":["trace[199251071] 'process raft request' (duration: 120.635822ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:57:00.735347Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14666} {"level":"info","ts":"2026-05-05T11:57:00.802908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14666,"took":"65.130501ms","hash":2266304805,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":20451328,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T11:57:00.802959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2266304805,"revision":14666,"compact-revision":13026} {"level":"info","ts":"2026-05-05T12:02:00.743378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16188} {"level":"info","ts":"2026-05-05T12:02:00.811495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16188,"took":"65.671768ms","hash":2038174355,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":20578304,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-05T12:02:00.811558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2038174355,"revision":16188,"compact-revision":14666}