{"level":"warn","ts":"2026-04-07T00:08:17.541227Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:08:17.541356Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.29:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.29:2380","--initial-cluster=instance=https://199.19.213.29:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.29:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.29:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T00:08:17.541452Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:08:17.54147Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.29:2380"]} {"level":"info","ts":"2026-04-07T00:08:17.541495Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:08:17.542759Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"]} {"level":"info","ts":"2026-04-07T00:08:17.542869Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.29:2380"],"listen-peer-urls":["https://199.19.213.29:2380"],"advertise-client-urls":["https://199.19.213.29:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.29:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T00:08:17.633941Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"90.768072ms"} {"level":"info","ts":"2026-04-07T00:08:18.349283Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1ecbac2f2214dbd","cluster-id":"94b127ce9c24156a"} {"level":"info","ts":"2026-04-07T00:08:18.349429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:08:18.349461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became follower at term 0"} {"level":"info","ts":"2026-04-07T00:08:18.349481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1ecbac2f2214dbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:08:18.349494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became follower at term 1"} {"level":"info","ts":"2026-04-07T00:08:18.349551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=(12820827585665650109)"} {"level":"warn","ts":"2026-04-07T00:08:18.357697Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:08:18.359505Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:08:18.361242Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T00:08:18.364279Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1ecbac2f2214dbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:08:18.36472Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:08:18.364865Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:08:18.364932Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:08:18.364768Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1ecbac2f2214dbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:08:18.36553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=(12820827585665650109)"} {"level":"info","ts":"2026-04-07T00:08:18.368033Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"94b127ce9c24156a","local-member-id":"b1ecbac2f2214dbd","added-peer-id":"b1ecbac2f2214dbd","added-peer-peer-urls":["https://199.19.213.29:2380"]} {"level":"info","ts":"2026-04-07T00:08:18.370456Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:08:18.370812Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1ecbac2f2214dbd","initial-advertise-peer-urls":["https://199.19.213.29:2380"],"listen-peer-urls":["https://199.19.213.29:2380"],"advertise-client-urls":["https://199.19.213.29:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:08:18.370866Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:08:18.370937Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.29:2380"} {"level":"info","ts":"2026-04-07T00:08:18.370962Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.29:2380"} {"level":"info","ts":"2026-04-07T00:08:19.353373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:08:19.35344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:08:19.353473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd received MsgPreVoteResp from b1ecbac2f2214dbd at term 1"} {"level":"info","ts":"2026-04-07T00:08:19.353492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:08:19.353503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd received MsgVoteResp from b1ecbac2f2214dbd at term 2"} {"level":"info","ts":"2026-04-07T00:08:19.353524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became leader at term 2"} {"level":"info","ts":"2026-04-07T00:08:19.353542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1ecbac2f2214dbd elected leader b1ecbac2f2214dbd at term 2"} {"level":"info","ts":"2026-04-07T00:08:19.354662Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:19.355537Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1ecbac2f2214dbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.29:2379]}","request-path":"/0/members/b1ecbac2f2214dbd/attributes","cluster-id":"94b127ce9c24156a","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:08:19.355561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:08:19.355714Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:08:19.356024Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:08:19.356233Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:08:19.35653Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"94b127ce9c24156a","local-member-id":"b1ecbac2f2214dbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:19.356942Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:19.357016Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:19.358752Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.29:2379"} {"level":"info","ts":"2026-04-07T00:08:19.359882Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:08:49.312838Z","caller":"traceutil/trace.go:171","msg":"trace[988217487] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"118.677569ms","start":"2026-04-07T00:08:49.194134Z","end":"2026-04-07T00:08:49.312811Z","steps":["trace[988217487] 'process raft request' (duration: 111.18882ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:49.31345Z","caller":"traceutil/trace.go:171","msg":"trace[174349092] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"113.928578ms","start":"2026-04-07T00:08:49.199507Z","end":"2026-04-07T00:08:49.313436Z","steps":["trace[174349092] 'process raft request' (duration: 113.869877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:49.31366Z","caller":"traceutil/trace.go:171","msg":"trace[281183511] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"117.841769ms","start":"2026-04-07T00:08:49.195807Z","end":"2026-04-07T00:08:49.313649Z","steps":["trace[281183511] 'process raft request' (duration: 117.347765ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:49.313794Z","caller":"traceutil/trace.go:171","msg":"trace[1961992932] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"116.178406ms","start":"2026-04-07T00:08:49.197592Z","end":"2026-04-07T00:08:49.313771Z","steps":["trace[1961992932] 'process raft request' (duration: 115.635501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:54.309103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.967257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:08:54.309414Z","caller":"traceutil/trace.go:171","msg":"trace[1610959472] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"154.364005ms","start":"2026-04-07T00:08:54.154958Z","end":"2026-04-07T00:08:54.309322Z","steps":["trace[1610959472] 'process raft request' (duration: 13.66919ms)","trace[1610959472] 'compare' (duration: 139.815852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:55.683217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.204286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:08:55.683333Z","caller":"traceutil/trace.go:171","msg":"trace[457801596] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"343.673437ms","start":"2026-04-07T00:08:55.339643Z","end":"2026-04-07T00:08:55.683317Z","steps":["trace[457801596] 'read index received' (duration: 195.887696ms)","trace[457801596] 'applied index is now lower than readState.Index' (duration: 147.784681ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:55.683426Z","caller":"traceutil/trace.go:171","msg":"trace[2078830016] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"485.265345ms","start":"2026-04-07T00:08:55.198102Z","end":"2026-04-07T00:08:55.683367Z","steps":["trace[2078830016] 'process raft request' (duration: 337.70146ms)","trace[2078830016] 'compare' (duration: 146.305604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:55.683563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.909462ms","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-04-07T00:08:55.683628Z","caller":"traceutil/trace.go:171","msg":"trace[350964350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"344.004665ms","start":"2026-04-07T00:08:55.3396Z","end":"2026-04-07T00:08:55.683605Z","steps":["trace[350964350] 'agreement among raft nodes before linearized reading' (duration: 343.82682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:55.6836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:55.198078Z","time spent":"485.427818ms","remote":"127.0.0.1:52966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:55.683675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:55.339578Z","time spent":"344.085226ms","remote":"127.0.0.1:53118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T00:08:55.683725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.675482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gqxls\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-07T00:08:55.683821Z","caller":"traceutil/trace.go:171","msg":"trace[759886757] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gqxls; range_end:; response_count:1; response_revision:615; }","duration":"216.815555ms","start":"2026-04-07T00:08:55.466987Z","end":"2026-04-07T00:08:55.683802Z","steps":["trace[759886757] 'agreement among raft nodes before linearized reading' (duration: 216.520468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:55.683803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.28331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T00:08:55.685543Z","caller":"traceutil/trace.go:171","msg":"trace[1687642445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"327.014684ms","start":"2026-04-07T00:08:55.358473Z","end":"2026-04-07T00:08:55.685488Z","steps":["trace[1687642445] 'agreement among raft nodes before linearized reading' (duration: 325.015174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:55.685692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:55.358464Z","time spent":"327.174077ms","remote":"127.0.0.1:53118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T00:08:55.993967Z","caller":"traceutil/trace.go:171","msg":"trace[1131079980] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"301.101479ms","start":"2026-04-07T00:08:55.692833Z","end":"2026-04-07T00:08:55.993934Z","steps":["trace[1131079980] 'process raft request' (duration: 215.070131ms)","trace[1131079980] 'compare' (duration: 85.387252ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:55.993988Z","caller":"traceutil/trace.go:171","msg":"trace[1488263840] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"298.884825ms","start":"2026-04-07T00:08:55.695072Z","end":"2026-04-07T00:08:55.993957Z","steps":["trace[1488263840] 'process raft request' (duration: 298.66069ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:55.994Z","caller":"traceutil/trace.go:171","msg":"trace[1468872722] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"196.402389ms","start":"2026-04-07T00:08:55.797577Z","end":"2026-04-07T00:08:55.993979Z","steps":["trace[1468872722] 'read index received' (duration: 110.428623ms)","trace[1468872722] 'applied index is now lower than readState.Index' (duration: 85.972856ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:55.994262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.678695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:55.994375Z","caller":"traceutil/trace.go:171","msg":"trace[753864844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"196.828219ms","start":"2026-04-07T00:08:55.797526Z","end":"2026-04-07T00:08:55.994354Z","steps":["trace[753864844] 'agreement among raft nodes before linearized reading' (duration: 196.656935ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:55.994316Z","caller":"traceutil/trace.go:171","msg":"trace[222742014] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"284.014175ms","start":"2026-04-07T00:08:55.710281Z","end":"2026-04-07T00:08:55.994295Z","steps":["trace[222742014] 'process raft request' (duration: 283.580254ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:55.994544Z","caller":"traceutil/trace.go:171","msg":"trace[132767046] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"248.749199ms","start":"2026-04-07T00:08:55.745748Z","end":"2026-04-07T00:08:55.994498Z","steps":["trace[132767046] 'process raft request' (duration: 248.178775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:55.994771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:55.692809Z","time spent":"301.238553ms","remote":"127.0.0.1:53118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:09:39.725086Z","caller":"traceutil/trace.go:171","msg":"trace[21799629] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"186.585495ms","start":"2026-04-07T00:09:39.53847Z","end":"2026-04-07T00:09:39.725056Z","steps":["trace[21799629] 'process raft request' (duration: 162.739443ms)","trace[21799629] 'compare' (duration: 23.706599ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:25.341183Z","caller":"traceutil/trace.go:171","msg":"trace[57667713] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1904; }","duration":"190.115224ms","start":"2026-04-07T00:10:25.151039Z","end":"2026-04-07T00:10:25.341154Z","steps":["trace[57667713] 'read index received' (duration: 189.91962ms)","trace[57667713] 'applied index is now lower than readState.Index' (duration: 194.414µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:25.341322Z","caller":"traceutil/trace.go:171","msg":"trace[2014306935] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"387.836163ms","start":"2026-04-07T00:10:24.953474Z","end":"2026-04-07T00:10:25.34131Z","steps":["trace[2014306935] 'process raft request' (duration: 387.518957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:25.341638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:24.953452Z","time spent":"388.096108ms","remote":"127.0.0.1:53048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:25.34163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.573093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T00:10:25.341711Z","caller":"traceutil/trace.go:171","msg":"trace[563186447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1834; }","duration":"190.735616ms","start":"2026-04-07T00:10:25.150956Z","end":"2026-04-07T00:10:25.341692Z","steps":["trace[563186447] 'agreement among raft nodes before linearized reading' (duration: 190.463711ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:25.592884Z","caller":"traceutil/trace.go:171","msg":"trace[347393663] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1905; }","duration":"251.572766ms","start":"2026-04-07T00:10:25.341272Z","end":"2026-04-07T00:10:25.592845Z","steps":["trace[347393663] 'read index received' (duration: 251.429353ms)","trace[347393663] 'applied index is now lower than readState.Index' (duration: 141.383µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:25.593015Z","caller":"traceutil/trace.go:171","msg":"trace[199451507] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"322.254876ms","start":"2026-04-07T00:10:25.270749Z","end":"2026-04-07T00:10:25.593004Z","steps":["trace[199451507] 'process raft request' (duration: 321.92703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:25.593128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:25.270726Z","time spent":"322.320158ms","remote":"127.0.0.1:53118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:25.593284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.638631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23c4bbbc-36b1-43ad-85b2-731fb88c4993\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-07T00:10:25.593395Z","caller":"traceutil/trace.go:171","msg":"trace[1825196889] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23c4bbbc-36b1-43ad-85b2-731fb88c4993; range_end:; response_count:1; response_revision:1835; }","duration":"368.810305ms","start":"2026-04-07T00:10:25.224564Z","end":"2026-04-07T00:10:25.593374Z","steps":["trace[1825196889] 'agreement among raft nodes before linearized reading' (duration: 368.56264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:25.593445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:25.224538Z","time spent":"368.895856ms","remote":"127.0.0.1:53048","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-23c4bbbc-36b1-43ad-85b2-731fb88c4993\" "} {"level":"info","ts":"2026-04-07T00:10:25.636209Z","caller":"traceutil/trace.go:171","msg":"trace[1297874831] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"289.753885ms","start":"2026-04-07T00:10:25.346429Z","end":"2026-04-07T00:10:25.636183Z","steps":["trace[1297874831] 'process raft request' (duration: 285.464557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:25.636278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.590206ms","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-04-07T00:10:25.636365Z","caller":"traceutil/trace.go:171","msg":"trace[1728204907] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1836; }","duration":"248.721008ms","start":"2026-04-07T00:10:25.387619Z","end":"2026-04-07T00:10:25.63634Z","steps":["trace[1728204907] 'agreement among raft nodes before linearized reading' (duration: 248.433432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:25.636622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.770374ms","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-04-07T00:10:25.636664Z","caller":"traceutil/trace.go:171","msg":"trace[1921344182] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1836; }","duration":"209.847496ms","start":"2026-04-07T00:10:25.426807Z","end":"2026-04-07T00:10:25.636654Z","steps":["trace[1921344182] 'agreement among raft nodes before linearized reading' (duration: 209.721764ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:58.8973Z","caller":"traceutil/trace.go:171","msg":"trace[923929690] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"123.279609ms","start":"2026-04-07T00:10:58.773988Z","end":"2026-04-07T00:10:58.897268Z","steps":["trace[923929690] 'process raft request' (duration: 111.879139ms)","trace[923929690] 'compare' (duration: 11.108815ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:59.652264Z","caller":"traceutil/trace.go:171","msg":"trace[1614541499] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"108.858897ms","start":"2026-04-07T00:10:59.54338Z","end":"2026-04-07T00:10:59.652239Z","steps":["trace[1614541499] 'process raft request' (duration: 108.707924ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:59.850052Z","caller":"traceutil/trace.go:171","msg":"trace[836867149] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"138.92728ms","start":"2026-04-07T00:10:59.711097Z","end":"2026-04-07T00:10:59.850025Z","steps":["trace[836867149] 'process raft request' (duration: 113.972477ms)","trace[836867149] 'compare' (duration: 24.032026ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:00.054516Z","caller":"traceutil/trace.go:171","msg":"trace[66134156] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"200.726954ms","start":"2026-04-07T00:10:59.853758Z","end":"2026-04-07T00:11:00.054485Z","steps":["trace[66134156] 'process raft request' (duration: 200.548871ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:00.132745Z","caller":"traceutil/trace.go:171","msg":"trace[1641550096] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"277.783614ms","start":"2026-04-07T00:10:59.854926Z","end":"2026-04-07T00:11:00.13271Z","steps":["trace[1641550096] 'process raft request' (duration: 277.567289ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:00.132763Z","caller":"traceutil/trace.go:171","msg":"trace[700358529] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"277.683771ms","start":"2026-04-07T00:10:59.855063Z","end":"2026-04-07T00:11:00.132747Z","steps":["trace[700358529] 'process raft request' (duration: 277.553779ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:00.132678Z","caller":"traceutil/trace.go:171","msg":"trace[1806532356] linearizableReadLoop","detail":"{readStateIndex:2162; appliedIndex:2160; }","duration":"227.48142ms","start":"2026-04-07T00:10:59.905177Z","end":"2026-04-07T00:11:00.132659Z","steps":["trace[1806532356] 'read index received' (duration: 149.40485ms)","trace[1806532356] 'applied index is now lower than readState.Index' (duration: 78.07561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:00.133387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.158552ms","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-04-07T00:11:00.133456Z","caller":"traceutil/trace.go:171","msg":"trace[745013537] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2083; }","duration":"228.303035ms","start":"2026-04-07T00:10:59.905133Z","end":"2026-04-07T00:11:00.133436Z","steps":["trace[745013537] 'agreement among raft nodes before linearized reading' (duration: 227.717534ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:03.320453Z","caller":"traceutil/trace.go:171","msg":"trace[291533051] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"210.710202ms","start":"2026-04-07T00:12:03.109712Z","end":"2026-04-07T00:12:03.320423Z","steps":["trace[291533051] 'process raft request' (duration: 208.893609ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:36.816552Z","caller":"traceutil/trace.go:171","msg":"trace[1668295899] linearizableReadLoop","detail":"{readStateIndex:2808; appliedIndex:2807; }","duration":"286.325613ms","start":"2026-04-07T00:12:36.530196Z","end":"2026-04-07T00:12:36.816521Z","steps":["trace[1668295899] 'read index received' (duration: 286.12165ms)","trace[1668295899] 'applied index is now lower than readState.Index' (duration: 202.373µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:36.816627Z","caller":"traceutil/trace.go:171","msg":"trace[892635171] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"307.967045ms","start":"2026-04-07T00:12:36.508625Z","end":"2026-04-07T00:12:36.816592Z","steps":["trace[892635171] 'process raft request' (duration: 307.661669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:36.816945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.736931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a3eab3e8182e56\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-07T00:12:36.81705Z","caller":"traceutil/trace.go:171","msg":"trace[965508958] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a3eab3e8182e56; range_end:; response_count:1; response_revision:2707; }","duration":"286.883693ms","start":"2026-04-07T00:12:36.530145Z","end":"2026-04-07T00:12:36.817028Z","steps":["trace[965508958] 'agreement among raft nodes before linearized reading' (duration: 286.661079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:36.817055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:36.508588Z","time spent":"308.367941ms","remote":"127.0.0.1:53118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:12:36.817091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.758441ms","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-04-07T00:12:36.817158Z","caller":"traceutil/trace.go:171","msg":"trace[561611081] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2707; }","duration":"253.883394ms","start":"2026-04-07T00:12:36.563255Z","end":"2026-04-07T00:12:36.817139Z","steps":["trace[561611081] 'agreement among raft nodes before linearized reading' (duration: 253.6747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:36.817487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.954958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:12:36.817524Z","caller":"traceutil/trace.go:171","msg":"trace[1557286607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2707; }","duration":"141.012278ms","start":"2026-04-07T00:12:36.676501Z","end":"2026-04-07T00:12:36.817514Z","steps":["trace[1557286607] 'agreement among raft nodes before linearized reading' (duration: 140.905877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:36.817636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.464365ms","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":"warn","ts":"2026-04-07T00:12:36.817673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.244046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:12:36.81769Z","caller":"traceutil/trace.go:171","msg":"trace[1846054211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2707; }","duration":"154.540667ms","start":"2026-04-07T00:12:36.663134Z","end":"2026-04-07T00:12:36.817675Z","steps":["trace[1846054211] 'agreement among raft nodes before linearized reading' (duration: 154.399385ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:36.817703Z","caller":"traceutil/trace.go:171","msg":"trace[1217140069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2707; }","duration":"140.296187ms","start":"2026-04-07T00:12:36.677399Z","end":"2026-04-07T00:12:36.817695Z","steps":["trace[1217140069] 'agreement among raft nodes before linearized reading' (duration: 140.200725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:36.817875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.925159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-07T00:12:36.817934Z","caller":"traceutil/trace.go:171","msg":"trace[390946228] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2707; }","duration":"168.9817ms","start":"2026-04-07T00:12:36.64894Z","end":"2026-04-07T00:12:36.817922Z","steps":["trace[390946228] 'agreement among raft nodes before linearized reading' (duration: 168.849918ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:36.289588Z","caller":"traceutil/trace.go:171","msg":"trace[913191749] transaction","detail":"{read_only:false; response_revision:3817; number_of_response:1; }","duration":"252.697964ms","start":"2026-04-07T00:15:36.036866Z","end":"2026-04-07T00:15:36.289564Z","steps":["trace[913191749] 'process raft request' (duration: 252.548808ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:36.291994Z","caller":"traceutil/trace.go:171","msg":"trace[1004972660] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"229.311056ms","start":"2026-04-07T00:15:36.062655Z","end":"2026-04-07T00:15:36.291966Z","steps":["trace[1004972660] 'process raft request' (duration: 229.13248ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:40.613206Z","caller":"traceutil/trace.go:171","msg":"trace[1233096050] transaction","detail":"{read_only:false; response_revision:3840; number_of_response:1; }","duration":"103.703667ms","start":"2026-04-07T00:15:40.509456Z","end":"2026-04-07T00:15:40.613159Z","steps":["trace[1233096050] 'process raft request' (duration: 103.460739ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:27.967367Z","caller":"traceutil/trace.go:171","msg":"trace[1193450523] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"162.78988ms","start":"2026-04-07T00:16:27.804514Z","end":"2026-04-07T00:16:27.967303Z","steps":["trace[1193450523] 'process raft request' (duration: 162.586904ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.188956Z","caller":"traceutil/trace.go:171","msg":"trace[1323312272] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"184.825233ms","start":"2026-04-07T00:16:28.004114Z","end":"2026-04-07T00:16:28.188939Z","steps":["trace[1323312272] 'process raft request' (duration: 184.627605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.189211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.982915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:16:28.189304Z","caller":"traceutil/trace.go:171","msg":"trace[2061658811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4638; }","duration":"153.12645ms","start":"2026-04-07T00:16:28.036155Z","end":"2026-04-07T00:16:28.189281Z","steps":["trace[2061658811] 'agreement among raft nodes before linearized reading' (duration: 152.837801ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.188897Z","caller":"traceutil/trace.go:171","msg":"trace[2050197621] linearizableReadLoop","detail":"{readStateIndex:4815; appliedIndex:4814; }","duration":"152.660485ms","start":"2026-04-07T00:16:28.036214Z","end":"2026-04-07T00:16:28.188874Z","steps":["trace[2050197621] 'read index received' (duration: 152.438667ms)","trace[2050197621] 'applied index is now lower than readState.Index' (duration: 220.898µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:28.189689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.398534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:16:28.189734Z","caller":"traceutil/trace.go:171","msg":"trace[898050071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4638; }","duration":"151.467937ms","start":"2026-04-07T00:16:28.038256Z","end":"2026-04-07T00:16:28.189724Z","steps":["trace[898050071] 'agreement among raft nodes before linearized reading' (duration: 151.345443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.189755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.569392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T00:16:28.189754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.946335ms","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-04-07T00:16:28.189809Z","caller":"traceutil/trace.go:171","msg":"trace[804155164] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4638; }","duration":"152.644485ms","start":"2026-04-07T00:16:28.037149Z","end":"2026-04-07T00:16:28.189794Z","steps":["trace[804155164] 'agreement among raft nodes before linearized reading' (duration: 152.49299ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.189822Z","caller":"traceutil/trace.go:171","msg":"trace[1799287190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4638; }","duration":"153.063448ms","start":"2026-04-07T00:16:28.03674Z","end":"2026-04-07T00:16:28.189803Z","steps":["trace[1799287190] 'agreement among raft nodes before linearized reading' (duration: 152.844981ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.693336Z","caller":"traceutil/trace.go:171","msg":"trace[10034138] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"119.375741ms","start":"2026-04-07T00:16:28.573923Z","end":"2026-04-07T00:16:28.693299Z","steps":["trace[10034138] 'process raft request' (duration: 118.10569ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.915327Z","caller":"traceutil/trace.go:171","msg":"trace[1564170949] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"102.348932ms","start":"2026-04-07T00:16:28.812948Z","end":"2026-04-07T00:16:28.915297Z","steps":["trace[1564170949] 'process raft request' (duration: 101.966899ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.915349Z","caller":"traceutil/trace.go:171","msg":"trace[1461518986] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"103.472228ms","start":"2026-04-07T00:16:28.811826Z","end":"2026-04-07T00:16:28.915298Z","steps":["trace[1461518986] 'process raft request' (duration: 100.407209ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.915718Z","caller":"traceutil/trace.go:171","msg":"trace[1518842860] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"103.662854ms","start":"2026-04-07T00:16:28.812039Z","end":"2026-04-07T00:16:28.915702Z","steps":["trace[1518842860] 'process raft request' (duration: 102.752504ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.915869Z","caller":"traceutil/trace.go:171","msg":"trace[672790237] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"103.002622ms","start":"2026-04-07T00:16:28.812853Z","end":"2026-04-07T00:16:28.915856Z","steps":["trace[672790237] 'process raft request' (duration: 102.00021ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.784655Z","caller":"traceutil/trace.go:171","msg":"trace[1091832676] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"145.474669ms","start":"2026-04-07T00:16:40.639151Z","end":"2026-04-07T00:16:40.784626Z","steps":["trace[1091832676] 'process raft request' (duration: 145.293984ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.950769Z","caller":"traceutil/trace.go:171","msg":"trace[1151866004] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"131.512747ms","start":"2026-04-07T00:16:40.819227Z","end":"2026-04-07T00:16:40.95074Z","steps":["trace[1151866004] 'process raft request' (duration: 131.449125ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.950888Z","caller":"traceutil/trace.go:171","msg":"trace[487663931] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"131.671913ms","start":"2026-04-07T00:16:40.81917Z","end":"2026-04-07T00:16:40.950842Z","steps":["trace[487663931] 'process raft request' (duration: 131.368963ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.950977Z","caller":"traceutil/trace.go:171","msg":"trace[1300203390] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"131.738504ms","start":"2026-04-07T00:16:40.819226Z","end":"2026-04-07T00:16:40.950965Z","steps":["trace[1300203390] 'process raft request' (duration: 131.387493ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.951023Z","caller":"traceutil/trace.go:171","msg":"trace[854136923] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"131.93066ms","start":"2026-04-07T00:16:40.81906Z","end":"2026-04-07T00:16:40.950991Z","steps":["trace[854136923] 'process raft request' (duration: 99.134753ms)","trace[854136923] 'compare' (duration: 32.171428ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:18:19.386235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2935} {"level":"info","ts":"2026-04-07T00:18:19.473265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2935,"took":"85.481303ms","hash":4230968642,"current-db-size-bytes":54321152,"current-db-size":"54 MB","current-db-size-in-use-bytes":29302784,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T00:18:19.473319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4230968642,"revision":2935,"compact-revision":-1} {"level":"info","ts":"2026-04-07T00:20:14.071505Z","caller":"traceutil/trace.go:171","msg":"trace[1216272135] transaction","detail":"{read_only:false; response_revision:6428; number_of_response:1; }","duration":"114.570701ms","start":"2026-04-07T00:20:13.956908Z","end":"2026-04-07T00:20:14.071479Z","steps":["trace[1216272135] 'process raft request' (duration: 114.404737ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:20:14.074212Z","caller":"traceutil/trace.go:171","msg":"trace[1635238134] transaction","detail":"{read_only:false; response_revision:6429; number_of_response:1; }","duration":"114.018588ms","start":"2026-04-07T00:20:13.960175Z","end":"2026-04-07T00:20:14.074194Z","steps":["trace[1635238134] 'process raft request' (duration: 112.579923ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:19.392833Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5651} {"level":"info","ts":"2026-04-07T00:23:19.467829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5651,"took":"73.574846ms","hash":281098258,"current-db-size-bytes":54321152,"current-db-size":"54 MB","current-db-size-in-use-bytes":18276352,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T00:23:19.467888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":281098258,"revision":5651,"compact-revision":2935}