{"level":"warn","ts":"2026-10-03T22:09:53.015503Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T22:09:53.01559Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.214: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://162.253.55.214:2380","--initial-cluster=instance=https://162.253.55.214:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.214:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.214: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-10-03T22:09:53.01566Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T22:09:53.015676Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.214:2380"]} {"level":"info","ts":"2026-10-03T22:09:53.015717Z","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-10-03T22:09:53.016826Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.214:2379"]} {"level":"info","ts":"2026-10-03T22:09:53.016933Z","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://162.253.55.214:2380"],"listen-peer-urls":["https://162.253.55.214:2380"],"advertise-client-urls":["https://162.253.55.214:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.214:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.214: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-10-03T22:09:53.021817Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.56249ms"} {"level":"info","ts":"2026-10-03T22:09:53.037138Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"86f12f2ae020828","cluster-id":"dfe59afaaa064e4a"} {"level":"info","ts":"2026-10-03T22:09:53.03724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T22:09:53.037311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 became follower at term 0"} {"level":"info","ts":"2026-10-03T22:09:53.037346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 86f12f2ae020828 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T22:09:53.037477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 became follower at term 1"} {"level":"info","ts":"2026-10-03T22:09:53.037644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 switched to configuration voters=(607725308229060648)"} {"level":"warn","ts":"2026-10-03T22:09:53.050648Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T22:09:53.053742Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T22:09:53.055566Z","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-10-03T22:09:53.057739Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"86f12f2ae020828","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T22:09:53.058021Z","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-10-03T22:09:53.058119Z","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-10-03T22:09:53.058126Z","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-10-03T22:09:53.058285Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"86f12f2ae020828","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T22:09:53.061412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 switched to configuration voters=(607725308229060648)"} {"level":"info","ts":"2026-10-03T22:09:53.0616Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfe59afaaa064e4a","local-member-id":"86f12f2ae020828","added-peer-id":"86f12f2ae020828","added-peer-peer-urls":["https://162.253.55.214:2380"]} {"level":"info","ts":"2026-10-03T22:09:53.063926Z","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-10-03T22:09:53.06407Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.214:2380"} {"level":"info","ts":"2026-10-03T22:09:53.064115Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.214:2380"} {"level":"info","ts":"2026-10-03T22:09:53.064727Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"86f12f2ae020828","initial-advertise-peer-urls":["https://162.253.55.214:2380"],"listen-peer-urls":["https://162.253.55.214:2380"],"advertise-client-urls":["https://162.253.55.214:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.214:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T22:09:53.064765Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T22:09:53.9389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T22:09:53.938951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T22:09:53.939139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 received MsgPreVoteResp from 86f12f2ae020828 at term 1"} {"level":"info","ts":"2026-10-03T22:09:53.939152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 became candidate at term 2"} {"level":"info","ts":"2026-10-03T22:09:53.939159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 received MsgVoteResp from 86f12f2ae020828 at term 2"} {"level":"info","ts":"2026-10-03T22:09:53.939168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"86f12f2ae020828 became leader at term 2"} {"level":"info","ts":"2026-10-03T22:09:53.939177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 86f12f2ae020828 elected leader 86f12f2ae020828 at term 2"} {"level":"info","ts":"2026-10-03T22:09:53.940618Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"86f12f2ae020828","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.214:2379]}","request-path":"/0/members/86f12f2ae020828/attributes","cluster-id":"dfe59afaaa064e4a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T22:09:53.940656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T22:09:53.940664Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:09:53.940902Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T22:09:53.940958Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T22:09:53.941013Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T22:09:53.942849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfe59afaaa064e4a","local-member-id":"86f12f2ae020828","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:09:53.942977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:09:53.94302Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:09:53.943302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.214:2379"} {"level":"info","ts":"2026-10-03T22:09:53.943459Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T22:10:09.396591Z","caller":"traceutil/trace.go:171","msg":"trace[793439429] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"235.086207ms","start":"2026-10-03T22:10:09.161466Z","end":"2026-10-03T22:10:09.396552Z","steps":["trace[793439429] 'process raft request' (duration: 234.872122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:10:09.92264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.207623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:10:09.922888Z","caller":"traceutil/trace.go:171","msg":"trace[788338228] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"522.02793ms","start":"2026-10-03T22:10:09.400781Z","end":"2026-10-03T22:10:09.922809Z","steps":["trace[788338228] 'process raft request' (duration: 296.373074ms)","trace[788338228] 'compare' (duration: 224.064449ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:10:09.923045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:10:09.400759Z","time spent":"522.213065ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:10:10.518408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.460142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:10:10.518536Z","caller":"traceutil/trace.go:171","msg":"trace[436539642] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"671.615294ms","start":"2026-10-03T22:10:09.8469Z","end":"2026-10-03T22:10:10.518515Z","steps":["trace[436539642] 'read index received' (duration: 451.799534ms)","trace[436539642] 'applied index is now lower than readState.Index' (duration: 219.81418ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:10:10.518626Z","caller":"traceutil/trace.go:171","msg":"trace[877410782] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"942.714945ms","start":"2026-10-03T22:10:09.575861Z","end":"2026-10-03T22:10:10.518576Z","steps":["trace[877410782] 'process raft request' (duration: 722.945926ms)","trace[877410782] 'compare' (duration: 218.664504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:10:10.518902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.995993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-03T22:10:10.518905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:10:09.575836Z","time spent":"942.94335ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:10:10.518964Z","caller":"traceutil/trace.go:171","msg":"trace[2142743852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"672.088134ms","start":"2026-10-03T22:10:09.846861Z","end":"2026-10-03T22:10:10.518949Z","steps":["trace[2142743852] 'agreement among raft nodes before linearized reading' (duration: 671.703985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:10:10.519006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:10:09.846843Z","time spent":"672.152996ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-03T22:10:10.689277Z","caller":"traceutil/trace.go:171","msg":"trace[567301119] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"121.704916ms","start":"2026-10-03T22:10:10.567543Z","end":"2026-10-03T22:10:10.689248Z","steps":["trace[567301119] 'read index received' (duration: 119.200628ms)","trace[567301119] 'applied index is now lower than readState.Index' (duration: 2.502848ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:10:10.68957Z","caller":"traceutil/trace.go:171","msg":"trace[1999674430] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"165.770236ms","start":"2026-10-03T22:10:10.523784Z","end":"2026-10-03T22:10:10.689554Z","steps":["trace[1999674430] 'process raft request' (duration: 162.961631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:10:10.689659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.109386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:10:10.689963Z","caller":"traceutil/trace.go:171","msg":"trace[848623574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"122.458804ms","start":"2026-10-03T22:10:10.567445Z","end":"2026-10-03T22:10:10.689904Z","steps":["trace[848623574] 'agreement among raft nodes before linearized reading' (duration: 122.092975ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:10:38.22663Z","caller":"traceutil/trace.go:171","msg":"trace[649232700] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"163.873719ms","start":"2026-10-03T22:10:38.062694Z","end":"2026-10-03T22:10:38.226568Z","steps":["trace[649232700] 'read index received' (duration: 160.928203ms)","trace[649232700] 'applied index is now lower than readState.Index' (duration: 2.944136ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:10:38.226675Z","caller":"traceutil/trace.go:171","msg":"trace[2055839802] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"278.314046ms","start":"2026-10-03T22:10:37.948324Z","end":"2026-10-03T22:10:38.226638Z","steps":["trace[2055839802] 'process raft request' (duration: 276.179559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:10:38.227617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.881851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-03T22:10:38.227744Z","caller":"traceutil/trace.go:171","msg":"trace[69803187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"165.076855ms","start":"2026-10-03T22:10:38.062617Z","end":"2026-10-03T22:10:38.227694Z","steps":["trace[69803187] 'agreement among raft nodes before linearized reading' (duration: 164.107553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:10:38.809207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":587896789135758176,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-03T22:10:38.838503Z","caller":"traceutil/trace.go:171","msg":"trace[193610000] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"529.659376ms","start":"2026-10-03T22:10:38.308808Z","end":"2026-10-03T22:10:38.838467Z","steps":["trace[193610000] 'read index received' (duration: 529.37937ms)","trace[193610000] 'applied index is now lower than readState.Index' (duration: 278.836µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:10:38.838571Z","caller":"traceutil/trace.go:171","msg":"trace[128793015] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"532.429708ms","start":"2026-10-03T22:10:38.306099Z","end":"2026-10-03T22:10:38.838529Z","steps":["trace[128793015] 'process raft request' (duration: 532.129522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:10:38.838785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:10:38.306066Z","time spent":"532.612932ms","remote":"127.0.0.1:57984","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-10-03T22:10:38.839001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.152287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m8dnk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-03T22:10:38.839141Z","caller":"traceutil/trace.go:171","msg":"trace[1101696522] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m8dnk; range_end:; response_count:1; response_revision:634; }","duration":"530.364442ms","start":"2026-10-03T22:10:38.308746Z","end":"2026-10-03T22:10:38.83911Z","steps":["trace[1101696522] 'agreement among raft nodes before linearized reading' (duration: 529.890711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:10:38.839648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:10:38.308725Z","time spent":"530.906404ms","remote":"127.0.0.1:57656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-m8dnk\" "} {"level":"info","ts":"2026-10-03T22:10:40.624091Z","caller":"traceutil/trace.go:171","msg":"trace[1947843807] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"136.321446ms","start":"2026-10-03T22:10:40.487738Z","end":"2026-10-03T22:10:40.624059Z","steps":["trace[1947843807] 'process raft request' (duration: 86.721951ms)","trace[1947843807] 'compare' (duration: 49.420301ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:10:40.624244Z","caller":"traceutil/trace.go:171","msg":"trace[251477072] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"100.804934ms","start":"2026-10-03T22:10:40.523415Z","end":"2026-10-03T22:10:40.62422Z","steps":["trace[251477072] 'process raft request' (duration: 100.59543ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:10:40.814117Z","caller":"traceutil/trace.go:171","msg":"trace[1404688810] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"154.233554ms","start":"2026-10-03T22:10:40.65986Z","end":"2026-10-03T22:10:40.814094Z","steps":["trace[1404688810] 'process raft request' (duration: 154.094121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:11:41.032113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.000058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-03T22:11:41.032228Z","caller":"traceutil/trace.go:171","msg":"trace[525765683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"132.160581ms","start":"2026-10-03T22:11:40.900051Z","end":"2026-10-03T22:11:41.032211Z","steps":["trace[525765683] 'range keys from in-memory index tree' (duration: 131.757233ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:02.623275Z","caller":"traceutil/trace.go:171","msg":"trace[2090043327] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1711; }","duration":"121.718135ms","start":"2026-10-03T22:12:02.501511Z","end":"2026-10-03T22:12:02.623229Z","steps":["trace[2090043327] 'process raft request' (duration: 121.636434ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:13.866756Z","caller":"traceutil/trace.go:171","msg":"trace[227774682] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"308.933588ms","start":"2026-10-03T22:12:13.557799Z","end":"2026-10-03T22:12:13.866733Z","steps":["trace[227774682] 'process raft request' (duration: 308.780765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:13.866893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:13.557781Z","time spent":"309.04152ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:12:14.123394Z","caller":"traceutil/trace.go:171","msg":"trace[644707813] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1944; }","duration":"316.915327ms","start":"2026-10-03T22:12:13.806438Z","end":"2026-10-03T22:12:14.123353Z","steps":["trace[644707813] 'read index received' (duration: 60.172857ms)","trace[644707813] 'applied index is now lower than readState.Index' (duration: 256.74107ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:12:14.123723Z","caller":"traceutil/trace.go:171","msg":"trace[264906298] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"564.936834ms","start":"2026-10-03T22:12:13.558754Z","end":"2026-10-03T22:12:14.123691Z","steps":["trace[264906298] 'process raft request' (duration: 549.952953ms)","trace[264906298] 'compare' (duration: 14.323557ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:12:14.123812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.362066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4aeb47c3-f25c-4258-a758-63b14198c7d8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-03T22:12:14.123856Z","caller":"traceutil/trace.go:171","msg":"trace[933306944] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4aeb47c3-f25c-4258-a758-63b14198c7d8; range_end:; response_count:1; response_revision:1875; }","duration":"317.472758ms","start":"2026-10-03T22:12:13.80637Z","end":"2026-10-03T22:12:14.123843Z","steps":["trace[933306944] 'agreement among raft nodes before linearized reading' (duration: 317.310966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:14.123892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:13.806355Z","time spent":"317.529279ms","remote":"127.0.0.1:57656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4aeb47c3-f25c-4258-a758-63b14198c7d8\" "} {"level":"warn","ts":"2026-10-03T22:12:14.123979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:13.558741Z","time spent":"565.053415ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:12:14.556918Z","caller":"traceutil/trace.go:171","msg":"trace[1086887595] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"425.215935ms","start":"2026-10-03T22:12:14.131659Z","end":"2026-10-03T22:12:14.556875Z","steps":["trace[1086887595] 'process raft request' (duration: 389.491879ms)","trace[1086887595] 'compare' (duration: 35.329188ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:12:14.557421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.13163Z","time spent":"425.368808ms","remote":"127.0.0.1:57656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:12:15.109232Z","caller":"traceutil/trace.go:171","msg":"trace[864263581] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"853.591152ms","start":"2026-10-03T22:12:14.255606Z","end":"2026-10-03T22:12:15.109197Z","steps":["trace[864263581] 'process raft request' (duration: 757.220402ms)","trace[864263581] 'compare' (duration: 96.062024ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:12:15.109388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.255581Z","time spent":"853.729954ms","remote":"127.0.0.1:57778","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-10-03T22:12:15.110689Z","caller":"traceutil/trace.go:171","msg":"trace[561208306] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"187.726352ms","start":"2026-10-03T22:12:14.922933Z","end":"2026-10-03T22:12:15.11066Z","steps":["trace[561208306] 'process raft request' (duration: 187.62575ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:15.110647Z","caller":"traceutil/trace.go:171","msg":"trace[1814762155] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"806.363095ms","start":"2026-10-03T22:12:14.304258Z","end":"2026-10-03T22:12:15.110621Z","steps":["trace[1814762155] 'process raft request' (duration: 804.770563ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:15.110766Z","caller":"traceutil/trace.go:171","msg":"trace[1473200707] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"450.625783ms","start":"2026-10-03T22:12:14.660096Z","end":"2026-10-03T22:12:15.110722Z","steps":["trace[1473200707] 'process raft request' (duration: 450.147683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:15.110785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.962028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4aeb47c3-f25c-4258-a758-63b14198c7d8\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-03T22:12:15.110387Z","caller":"traceutil/trace.go:171","msg":"trace[498480276] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1947; }","duration":"841.55145ms","start":"2026-10-03T22:12:14.268815Z","end":"2026-10-03T22:12:15.110366Z","steps":["trace[498480276] 'read index received' (duration: 252.42767ms)","trace[498480276] 'applied index is now lower than readState.Index' (duration: 589.12238ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:12:15.110835Z","caller":"traceutil/trace.go:171","msg":"trace[1730945993] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4aeb47c3-f25c-4258-a758-63b14198c7d8; range_end:; response_count:1; response_revision:1881; }","duration":"842.04393ms","start":"2026-10-03T22:12:14.268778Z","end":"2026-10-03T22:12:15.110822Z","steps":["trace[1730945993] 'agreement among raft nodes before linearized reading' (duration: 841.852986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:15.110865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.268759Z","time spent":"842.098961ms","remote":"127.0.0.1:57656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4aeb47c3-f25c-4258-a758-63b14198c7d8\" "} {"level":"warn","ts":"2026-10-03T22:12:15.110887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.660073Z","time spent":"450.748535ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:12:15.110931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.304232Z","time spent":"806.570629ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:12:15.111743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.593102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T22:12:15.11178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.477277ms","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-10-03T22:12:15.111818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.749721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-03T22:12:15.111842Z","caller":"traceutil/trace.go:171","msg":"trace[1212495084] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1881; }","duration":"187.565978ms","start":"2026-10-03T22:12:14.924261Z","end":"2026-10-03T22:12:15.111827Z","steps":["trace[1212495084] 'agreement among raft nodes before linearized reading' (duration: 187.416245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:15.111874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.012586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-03T22:12:15.111889Z","caller":"traceutil/trace.go:171","msg":"trace[1869165966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"252.716625ms","start":"2026-10-03T22:12:14.8591Z","end":"2026-10-03T22:12:15.111817Z","steps":["trace[1869165966] 'agreement among raft nodes before linearized reading' (duration: 252.602713ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:15.111938Z","caller":"traceutil/trace.go:171","msg":"trace[362713214] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"551.106608ms","start":"2026-10-03T22:12:14.560806Z","end":"2026-10-03T22:12:15.111913Z","steps":["trace[362713214] 'agreement among raft nodes before linearized reading' (duration: 550.970905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:15.11198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.560785Z","time spent":"551.187188ms","remote":"127.0.0.1:51204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-03T22:12:15.111963Z","caller":"traceutil/trace.go:171","msg":"trace[905792428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1881; }","duration":"353.846332ms","start":"2026-10-03T22:12:14.75802Z","end":"2026-10-03T22:12:15.111866Z","steps":["trace[905792428] 'agreement among raft nodes before linearized reading' (duration: 353.680779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:15.112107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.758Z","time spent":"354.073756ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-03T22:12:15.112423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.827552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-03T22:12:15.112463Z","caller":"traceutil/trace.go:171","msg":"trace[713039334] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1881; }","duration":"572.896803ms","start":"2026-10-03T22:12:14.539556Z","end":"2026-10-03T22:12:15.112452Z","steps":["trace[713039334] 'agreement among raft nodes before linearized reading' (duration: 572.789461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:15.112492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:14.539536Z","time spent":"572.947325ms","remote":"127.0.0.1:57778","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":"info","ts":"2026-10-03T22:12:48.48785Z","caller":"traceutil/trace.go:171","msg":"trace[1934548524] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"123.12313ms","start":"2026-10-03T22:12:48.364707Z","end":"2026-10-03T22:12:48.48783Z","steps":["trace[1934548524] 'read index received' (duration: 122.951376ms)","trace[1934548524] 'applied index is now lower than readState.Index' (duration: 170.744µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:12:48.487929Z","caller":"traceutil/trace.go:171","msg":"trace[540815073] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"123.829684ms","start":"2026-10-03T22:12:48.364058Z","end":"2026-10-03T22:12:48.487887Z","steps":["trace[540815073] 'process raft request' (duration: 123.61843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:48.488072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.346833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-03T22:12:48.488125Z","caller":"traceutil/trace.go:171","msg":"trace[1241651707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2113; }","duration":"123.449036ms","start":"2026-10-03T22:12:48.364664Z","end":"2026-10-03T22:12:48.488113Z","steps":["trace[1241651707] 'agreement among raft nodes before linearized reading' (duration: 123.288083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:48.48821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.836667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-03T22:12:48.488292Z","caller":"traceutil/trace.go:171","msg":"trace[1681041732] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"114.921609ms","start":"2026-10-03T22:12:48.373335Z","end":"2026-10-03T22:12:48.488256Z","steps":["trace[1681041732] 'agreement among raft nodes before linearized reading' (duration: 114.774026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:49.555496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.902677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:12:49.555668Z","caller":"traceutil/trace.go:171","msg":"trace[1736837769] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2194; }","duration":"246.964291ms","start":"2026-10-03T22:12:49.308687Z","end":"2026-10-03T22:12:49.555651Z","steps":["trace[1736837769] 'read index received' (duration: 43.141µs)","trace[1736837769] 'applied index is now lower than readState.Index' (duration: 246.92023ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:12:49.555952Z","caller":"traceutil/trace.go:171","msg":"trace[1407497976] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"300.968187ms","start":"2026-10-03T22:12:49.254972Z","end":"2026-10-03T22:12:49.55594Z","steps":["trace[1407497976] 'process raft request' (duration: 300.61476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:49.555955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.238606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-03T22:12:49.556043Z","caller":"traceutil/trace.go:171","msg":"trace[669948329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2117; }","duration":"247.385449ms","start":"2026-10-03T22:12:49.308629Z","end":"2026-10-03T22:12:49.556014Z","steps":["trace[669948329] 'agreement among raft nodes before linearized reading' (duration: 247.122154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:49.556048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:49.254961Z","time spent":"301.048828ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:12:49.55595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.952429ms","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-10-03T22:12:49.556202Z","caller":"traceutil/trace.go:171","msg":"trace[834829761] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2117; }","duration":"226.279086ms","start":"2026-10-03T22:12:49.329908Z","end":"2026-10-03T22:12:49.556187Z","steps":["trace[834829761] 'agreement among raft nodes before linearized reading' (duration: 225.893488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:49.556385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.246801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-03T22:12:49.556447Z","caller":"traceutil/trace.go:171","msg":"trace[2048477272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2117; }","duration":"102.344813ms","start":"2026-10-03T22:12:49.454091Z","end":"2026-10-03T22:12:49.556436Z","steps":["trace[2048477272] 'agreement among raft nodes before linearized reading' (duration: 102.22627ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:49.555808Z","caller":"traceutil/trace.go:171","msg":"trace[1751952025] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"307.312231ms","start":"2026-10-03T22:12:49.248462Z","end":"2026-10-03T22:12:49.555774Z","steps":["trace[1751952025] 'process raft request' (duration: 34.042426ms)","trace[1751952025] 'compare' (duration: 272.657973ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:12:49.55678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:49.248435Z","time spent":"308.285601ms","remote":"127.0.0.1:57778","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-10-03T22:12:49.827171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.073977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:12:49.82729Z","caller":"traceutil/trace.go:171","msg":"trace[1244584480] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"265.34732ms","start":"2026-10-03T22:12:49.561919Z","end":"2026-10-03T22:12:49.827266Z","steps":["trace[1244584480] 'process raft request' (duration: 98.087669ms)","trace[1244584480] 'compare' (duration: 166.756411ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:12:49.880536Z","caller":"traceutil/trace.go:171","msg":"trace[393082776] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"318.528091ms","start":"2026-10-03T22:12:49.561978Z","end":"2026-10-03T22:12:49.880506Z","steps":["trace[393082776] 'process raft request' (duration: 318.352187ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:12:49.880675Z","caller":"traceutil/trace.go:171","msg":"trace[1868391073] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"316.603293ms","start":"2026-10-03T22:12:49.564032Z","end":"2026-10-03T22:12:49.880636Z","steps":["trace[1868391073] 'process raft request' (duration: 316.42877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:49.8807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:49.561953Z","time spent":"318.660063ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:12:49.880829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:12:49.564016Z","time spent":"316.728925ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:12:49.893631Z","caller":"traceutil/trace.go:171","msg":"trace[1238143699] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"179.680604ms","start":"2026-10-03T22:12:49.713928Z","end":"2026-10-03T22:12:49.893609Z","steps":["trace[1238143699] 'process raft request' (duration: 179.50279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:12:50.18676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.52077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-03T22:12:50.186834Z","caller":"traceutil/trace.go:171","msg":"trace[80647632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2121; }","duration":"113.731035ms","start":"2026-10-03T22:12:50.073086Z","end":"2026-10-03T22:12:50.186817Z","steps":["trace[80647632] 'range keys from in-memory index tree' (duration: 113.291266ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:17:24.906499Z","caller":"traceutil/trace.go:171","msg":"trace[1562232404] transaction","detail":"{read_only:false; response_revision:3762; number_of_response:1; }","duration":"132.476674ms","start":"2026-10-03T22:17:24.773998Z","end":"2026-10-03T22:17:24.906474Z","steps":["trace[1562232404] 'process raft request' (duration: 114.671973ms)","trace[1562232404] 'compare' (duration: 17.697338ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:19:11.964057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.28831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:19:11.964166Z","caller":"traceutil/trace.go:171","msg":"trace[1381758371] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4998; }","duration":"300.443074ms","start":"2026-10-03T22:19:11.663688Z","end":"2026-10-03T22:19:11.964131Z","steps":["trace[1381758371] 'count revisions from in-memory index tree' (duration: 300.205687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:11.96422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:11.663669Z","time spent":"300.538657ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-10-03T22:19:11.964559Z","caller":"traceutil/trace.go:171","msg":"trace[1014016661] linearizableReadLoop","detail":"{readStateIndex:5187; appliedIndex:5186; }","duration":"122.572034ms","start":"2026-10-03T22:19:11.841974Z","end":"2026-10-03T22:19:11.964546Z","steps":["trace[1014016661] 'read index received' (duration: 102.201335ms)","trace[1014016661] 'applied index is now lower than readState.Index' (duration: 20.369609ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:11.964634Z","caller":"traceutil/trace.go:171","msg":"trace[109327293] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"172.283676ms","start":"2026-10-03T22:19:11.792288Z","end":"2026-10-03T22:19:11.964572Z","steps":["trace[109327293] 'process raft request' (duration: 151.981328ms)","trace[109327293] 'compare' (duration: 19.677971ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:19:11.964733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.499256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T22:19:11.96476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.78858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0210ef5-7870-4d37-adc0-50541a09bd2e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-03T22:19:11.964812Z","caller":"traceutil/trace.go:171","msg":"trace[1540540138] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b0210ef5-7870-4d37-adc0-50541a09bd2e; range_end:; response_count:1; response_revision:4999; }","duration":"122.875822ms","start":"2026-10-03T22:19:11.841927Z","end":"2026-10-03T22:19:11.964803Z","steps":["trace[1540540138] 'agreement among raft nodes before linearized reading' (duration: 122.710087ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:11.964813Z","caller":"traceutil/trace.go:171","msg":"trace[1360786611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4999; }","duration":"106.603569ms","start":"2026-10-03T22:19:11.858187Z","end":"2026-10-03T22:19:11.964791Z","steps":["trace[1360786611] 'agreement among raft nodes before linearized reading' (duration: 106.480776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:11.964865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.765586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0210ef5-7870-4d37-adc0-50541a09bd2e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-03T22:19:11.964937Z","caller":"traceutil/trace.go:171","msg":"trace[1173336349] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b0210ef5-7870-4d37-adc0-50541a09bd2e; range_end:; response_count:1; response_revision:4999; }","duration":"103.864007ms","start":"2026-10-03T22:19:11.861052Z","end":"2026-10-03T22:19:11.964916Z","steps":["trace[1173336349] 'agreement among raft nodes before linearized reading' (duration: 103.637062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:12.371879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.578921ms","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-10-03T22:19:12.371911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.900567ms","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-10-03T22:19:12.371957Z","caller":"traceutil/trace.go:171","msg":"trace[1212611729] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5001; }","duration":"144.705545ms","start":"2026-10-03T22:19:12.227234Z","end":"2026-10-03T22:19:12.371939Z","steps":["trace[1212611729] 'range keys from in-memory index tree' (duration: 144.424658ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:12.371994Z","caller":"traceutil/trace.go:171","msg":"trace[2119344251] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5001; }","duration":"146.040701ms","start":"2026-10-03T22:19:12.225931Z","end":"2026-10-03T22:19:12.371972Z","steps":["trace[2119344251] 'count revisions from in-memory index tree' (duration: 145.816355ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:12.396984Z","caller":"traceutil/trace.go:171","msg":"trace[1010436012] linearizableReadLoop","detail":"{readStateIndex:5190; appliedIndex:5189; }","duration":"140.583038ms","start":"2026-10-03T22:19:12.256377Z","end":"2026-10-03T22:19:12.39696Z","steps":["trace[1010436012] 'read index received' (duration: 80.592922ms)","trace[1010436012] 'applied index is now lower than readState.Index' (duration: 59.989076ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:19:12.397222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.824705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-03T22:19:12.397261Z","caller":"traceutil/trace.go:171","msg":"trace[147649030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5002; }","duration":"140.907857ms","start":"2026-10-03T22:19:12.256344Z","end":"2026-10-03T22:19:12.397252Z","steps":["trace[147649030] 'agreement among raft nodes before linearized reading' (duration: 140.745272ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:14.005214Z","caller":"traceutil/trace.go:171","msg":"trace[987769351] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"107.9849ms","start":"2026-10-03T22:19:13.897199Z","end":"2026-10-03T22:19:14.005183Z","steps":["trace[987769351] 'process raft request' (duration: 102.892168ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:15.840597Z","caller":"traceutil/trace.go:171","msg":"trace[2049870112] linearizableReadLoop","detail":"{readStateIndex:5251; appliedIndex:5250; }","duration":"101.22214ms","start":"2026-10-03T22:19:15.739354Z","end":"2026-10-03T22:19:15.840576Z","steps":["trace[2049870112] 'read index received' (duration: 100.933072ms)","trace[2049870112] 'applied index is now lower than readState.Index' (duration: 288.138µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:19:15.840782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.416665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-03T22:19:15.840814Z","caller":"traceutil/trace.go:171","msg":"trace[695402999] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5061; }","duration":"101.501487ms","start":"2026-10-03T22:19:15.739302Z","end":"2026-10-03T22:19:15.840803Z","steps":["trace[695402999] 'agreement among raft nodes before linearized reading' (duration: 101.360183ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:15.840896Z","caller":"traceutil/trace.go:171","msg":"trace[925605609] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"141.725378ms","start":"2026-10-03T22:19:15.699145Z","end":"2026-10-03T22:19:15.84087Z","steps":["trace[925605609] 'process raft request' (duration: 141.215924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:26.099796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.70573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-10-03T22:19:26.099878Z","caller":"traceutil/trace.go:171","msg":"trace[615991329] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5165; }","duration":"109.833672ms","start":"2026-10-03T22:19:25.990026Z","end":"2026-10-03T22:19:26.09986Z","steps":["trace[615991329] 'range keys from in-memory index tree' (duration: 109.456004ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:26.446274Z","caller":"traceutil/trace.go:171","msg":"trace[1860465324] linearizableReadLoop","detail":"{readStateIndex:5361; appliedIndex:5360; }","duration":"286.441598ms","start":"2026-10-03T22:19:26.159808Z","end":"2026-10-03T22:19:26.446249Z","steps":["trace[1860465324] 'read index received' (duration: 286.243843ms)","trace[1860465324] 'applied index is now lower than readState.Index' (duration: 196.925µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:26.446292Z","caller":"traceutil/trace.go:171","msg":"trace[1931646095] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"330.619399ms","start":"2026-10-03T22:19:26.115651Z","end":"2026-10-03T22:19:26.44627Z","steps":["trace[1931646095] 'process raft request' (duration: 330.452585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:26.446455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:19:26.11563Z","time spent":"330.746663ms","remote":"127.0.0.1:57656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:19:26.446483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.663203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-03T22:19:26.446521Z","caller":"traceutil/trace.go:171","msg":"trace[1538957918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5166; }","duration":"286.742366ms","start":"2026-10-03T22:19:26.159766Z","end":"2026-10-03T22:19:26.446508Z","steps":["trace[1538957918] 'agreement among raft nodes before linearized reading' (duration: 286.595932ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:26.567218Z","caller":"traceutil/trace.go:171","msg":"trace[1183964945] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"114.124504ms","start":"2026-10-03T22:19:26.453066Z","end":"2026-10-03T22:19:26.56719Z","steps":["trace[1183964945] 'process raft request' (duration: 68.717571ms)","trace[1183964945] 'compare' (duration: 45.130606ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:26.567646Z","caller":"traceutil/trace.go:171","msg":"trace[1663075342] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5361; }","duration":"112.195154ms","start":"2026-10-03T22:19:26.455428Z","end":"2026-10-03T22:19:26.567623Z","steps":["trace[1663075342] 'read index received' (duration: 3.48993ms)","trace[1663075342] 'applied index is now lower than readState.Index' (duration: 108.704094ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:26.567661Z","caller":"traceutil/trace.go:171","msg":"trace[2006782335] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"114.444872ms","start":"2026-10-03T22:19:26.45316Z","end":"2026-10-03T22:19:26.567605Z","steps":["trace[2006782335] 'process raft request' (duration: 113.94686ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:26.567851Z","caller":"traceutil/trace.go:171","msg":"trace[386152115] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"114.537444ms","start":"2026-10-03T22:19:26.453236Z","end":"2026-10-03T22:19:26.567774Z","steps":["trace[386152115] 'process raft request' (duration: 114.212006ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:26.567949Z","caller":"traceutil/trace.go:171","msg":"trace[1667100075] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"113.414855ms","start":"2026-10-03T22:19:26.4545Z","end":"2026-10-03T22:19:26.567915Z","steps":["trace[1667100075] 'process raft request' (duration: 113.064686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:19:26.568128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.678637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-03T22:19:26.56973Z","caller":"traceutil/trace.go:171","msg":"trace[1359510513] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5171; }","duration":"114.318899ms","start":"2026-10-03T22:19:26.455385Z","end":"2026-10-03T22:19:26.569704Z","steps":["trace[1359510513] 'agreement among raft nodes before linearized reading' (duration: 112.561683ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:26.777978Z","caller":"traceutil/trace.go:171","msg":"trace[845425040] linearizableReadLoop","detail":"{readStateIndex:5371; appliedIndex:5369; }","duration":"109.850724ms","start":"2026-10-03T22:19:26.668106Z","end":"2026-10-03T22:19:26.777957Z","steps":["trace[845425040] 'read index received' (duration: 38.502916ms)","trace[845425040] 'applied index is now lower than readState.Index' (duration: 71.346788ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:19:26.778161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.037838ms","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-10-03T22:19:26.77819Z","caller":"traceutil/trace.go:171","msg":"trace[1558237458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5176; }","duration":"110.12308ms","start":"2026-10-03T22:19:26.668058Z","end":"2026-10-03T22:19:26.778181Z","steps":["trace[1558237458] 'agreement among raft nodes before linearized reading' (duration: 109.979727ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:26.778488Z","caller":"traceutil/trace.go:171","msg":"trace[604816573] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"136.372133ms","start":"2026-10-03T22:19:26.642103Z","end":"2026-10-03T22:19:26.778475Z","steps":["trace[604816573] 'process raft request' (duration: 64.490572ms)","trace[604816573] 'compare' (duration: 71.146942ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:26.778759Z","caller":"traceutil/trace.go:171","msg":"trace[1295817326] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"125.519815ms","start":"2026-10-03T22:19:26.653225Z","end":"2026-10-03T22:19:26.778745Z","steps":["trace[1295817326] 'process raft request' (duration: 124.655203ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:26.94202Z","caller":"traceutil/trace.go:171","msg":"trace[741258519] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"151.270545ms","start":"2026-10-03T22:19:26.790719Z","end":"2026-10-03T22:19:26.941989Z","steps":["trace[741258519] 'process raft request' (duration: 114.946345ms)","trace[741258519] 'compare' (duration: 35.997432ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:19:26.942299Z","caller":"traceutil/trace.go:171","msg":"trace[900109304] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"149.028728ms","start":"2026-10-03T22:19:26.793243Z","end":"2026-10-03T22:19:26.942272Z","steps":["trace[900109304] 'process raft request' (duration: 148.907205ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:26.942496Z","caller":"traceutil/trace.go:171","msg":"trace[775634905] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"151.732687ms","start":"2026-10-03T22:19:26.790741Z","end":"2026-10-03T22:19:26.942473Z","steps":["trace[775634905] 'process raft request' (duration: 151.180573ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:19:53.978923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2891} {"level":"info","ts":"2026-10-03T22:19:54.058671Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2891,"took":"78.210956ms","hash":3431390257,"current-db-size-bytes":52383744,"current-db-size":"52 MB","current-db-size-in-use-bytes":27738112,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-03T22:19:54.058831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3431390257,"revision":2891,"compact-revision":-1} {"level":"info","ts":"2026-10-03T22:20:14.693879Z","caller":"traceutil/trace.go:171","msg":"trace[1537179405] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"107.30182ms","start":"2026-10-03T22:20:14.586552Z","end":"2026-10-03T22:20:14.693854Z","steps":["trace[1537179405] 'process raft request' (duration: 107.093505ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:23.537063Z","caller":"traceutil/trace.go:171","msg":"trace[339178846] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"127.596817ms","start":"2026-10-03T22:20:23.409447Z","end":"2026-10-03T22:20:23.537044Z","steps":["trace[339178846] 'process raft request' (duration: 127.466044ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:20:26.571819Z","caller":"traceutil/trace.go:171","msg":"trace[397470992] transaction","detail":"{read_only:false; response_revision:5673; number_of_response:1; }","duration":"133.294359ms","start":"2026-10-03T22:20:26.438503Z","end":"2026-10-03T22:20:26.571797Z","steps":["trace[397470992] 'process raft request' (duration: 133.154366ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:24:53.985791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5379} {"level":"info","ts":"2026-10-03T22:24:54.088138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5379,"took":"100.938064ms","hash":95181412,"current-db-size-bytes":52383744,"current-db-size":"52 MB","current-db-size-in-use-bytes":18509824,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-03T22:24:54.088191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":95181412,"revision":5379,"compact-revision":2891}