{"level":"warn","ts":"2026-10-02T15:12:59.661931Z","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-02T15:12:59.662072Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-02T15:12:59.662157Z","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-02T15:12:59.662217Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]}
{"level":"info","ts":"2026-10-02T15:12:59.662263Z","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-02T15:12:59.662967Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]}
{"level":"info","ts":"2026-10-02T15:12:59.663068Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-02T15:12:59.667408Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.062253ms"}
{"level":"info","ts":"2026-10-02T15:12:59.701989Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"}
{"level":"info","ts":"2026-10-02T15:12:59.702087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"}
{"level":"info","ts":"2026-10-02T15:12:59.702108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"}
{"level":"info","ts":"2026-10-02T15:12:59.702115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-10-02T15:12:59.702121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"}
{"level":"info","ts":"2026-10-02T15:12:59.702155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"}
{"level":"warn","ts":"2026-10-02T15:12:59.715016Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-10-02T15:12:59.716463Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-10-02T15:12:59.717786Z","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-02T15:12:59.719719Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-10-02T15:12:59.719859Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-10-02T15:12:59.720082Z","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-02T15:12:59.720169Z","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-02T15:12:59.720195Z","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-02T15:12:59.722179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"}
{"level":"info","ts":"2026-10-02T15:12:59.722424Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]}
{"level":"info","ts":"2026-10-02T15:12:59.728153Z","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-02T15:12:59.72824Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"}
{"level":"info","ts":"2026-10-02T15:12:59.728301Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"}
{"level":"info","ts":"2026-10-02T15:12:59.729176Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-10-02T15:12:59.729228Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-10-02T15:13:00.303616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"}
{"level":"info","ts":"2026-10-02T15:13:00.303713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"}
{"level":"info","ts":"2026-10-02T15:13:00.30376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"}
{"level":"info","ts":"2026-10-02T15:13:00.303783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"}
{"level":"info","ts":"2026-10-02T15:13:00.3038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"}
{"level":"info","ts":"2026-10-02T15:13:00.303819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"}
{"level":"info","ts":"2026-10-02T15:13:00.303839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"}
{"level":"info","ts":"2026-10-02T15:13:00.328969Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-02T15:13:00.330252Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"}
{"level":"info","ts":"2026-10-02T15:13:00.330359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-10-02T15:13:00.330386Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-10-02T15:13:00.330879Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-10-02T15:13:00.330978Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-10-02T15:13:00.333976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"}
{"level":"info","ts":"2026-10-02T15:13:00.334376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-10-02T15:13:00.335181Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-02T15:13:00.335334Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-02T15:13:00.335396Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"warn","ts":"2026-10-02T15:13:02.558423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.801153ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8471729383302707208 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/kube-apiserver-instance.18dabf27d0897738\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-apiserver-instance.18dabf27d0897738\" value_size:639 lease:8471729383302707126 >> failure:<>>","response":"size:14"}
{"level":"info","ts":"2026-10-02T15:13:02.558611Z","caller":"traceutil/trace.go:171","msg":"trace[1194425910] linearizableReadLoop","detail":"{readStateIndex:110; appliedIndex:109; }","duration":"228.713085ms","start":"2026-10-02T15:13:02.329874Z","end":"2026-10-02T15:13:02.558587Z","steps":["trace[1194425910] 'read index received'  (duration: 9.093825ms)","trace[1194425910] 'applied index is now lower than readState.Index'  (duration: 219.61778ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:13:02.558699Z","caller":"traceutil/trace.go:171","msg":"trace[1149751848] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"246.089071ms","start":"2026-10-02T15:13:02.31255Z","end":"2026-10-02T15:13:02.558639Z","steps":["trace[1149751848] 'process raft request'  (duration: 26.41632ms)","trace[1149751848] 'compare'  (duration: 218.587419ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T15:13:02.558866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.983391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-10-02T15:13:02.558903Z","caller":"traceutil/trace.go:171","msg":"trace[157869700] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:105; }","duration":"229.048932ms","start":"2026-10-02T15:13:02.329842Z","end":"2026-10-02T15:13:02.558891Z","steps":["trace[157869700] 'agreement among raft nodes before linearized reading'  (duration: 228.913469ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:13:02.558986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.303717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-10-02T15:13:02.559109Z","caller":"traceutil/trace.go:171","msg":"trace[244310517] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:105; }","duration":"205.493471ms","start":"2026-10-02T15:13:02.353583Z","end":"2026-10-02T15:13:02.559077Z","steps":["trace[244310517] 'agreement among raft nodes before linearized reading'  (duration: 205.284417ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:13:02.851701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.366576ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8471729383302707211 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/kube-scheduler-instance.18dabf27d1c47cf2\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-scheduler-instance.18dabf27d1c47cf2\" value_size:639 lease:8471729383302707126 >> failure:<>>","response":"size:14"}
{"level":"info","ts":"2026-10-02T15:13:02.852079Z","caller":"traceutil/trace.go:171","msg":"trace[313753533] linearizableReadLoop","detail":"{readStateIndex:111; appliedIndex:110; }","duration":"287.422325ms","start":"2026-10-02T15:13:02.56453Z","end":"2026-10-02T15:13:02.851952Z","steps":["trace[313753533] 'read index received'  (duration: 129.717362ms)","trace[313753533] 'applied index is now lower than readState.Index'  (duration: 157.703013ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:13:02.852047Z","caller":"traceutil/trace.go:171","msg":"trace[1951143196] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"287.65781ms","start":"2026-10-02T15:13:02.564336Z","end":"2026-10-02T15:13:02.851994Z","steps":["trace[1951143196] 'process raft request'  (duration: 129.908896ms)","trace[1951143196] 'compare'  (duration: 157.094051ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T15:13:02.852379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.822943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"}
{"level":"info","ts":"2026-10-02T15:13:02.852467Z","caller":"traceutil/trace.go:171","msg":"trace[1307005011] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:106; }","duration":"287.966657ms","start":"2026-10-02T15:13:02.564482Z","end":"2026-10-02T15:13:02.852449Z","steps":["trace[1307005011] 'agreement among raft nodes before linearized reading'  (duration: 287.613469ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:13:03.043875Z","caller":"traceutil/trace.go:171","msg":"trace[1220185607] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"186.750645ms","start":"2026-10-02T15:13:02.857081Z","end":"2026-10-02T15:13:03.043832Z","steps":["trace[1220185607] 'process raft request'  (duration: 127.969166ms)","trace[1220185607] 'compare'  (duration: 58.606816ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:13:03.049736Z","caller":"traceutil/trace.go:171","msg":"trace[1941950957] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"181.763253ms","start":"2026-10-02T15:13:02.86794Z","end":"2026-10-02T15:13:03.049703Z","steps":["trace[1941950957] 'process raft request'  (duration: 181.651091ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:13:03.049747Z","caller":"traceutil/trace.go:171","msg":"trace[1496862075] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"192.483942ms","start":"2026-10-02T15:13:02.8572Z","end":"2026-10-02T15:13:03.049684Z","steps":["trace[1496862075] 'process raft request'  (duration: 192.207286ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:13:39.052282Z","caller":"traceutil/trace.go:171","msg":"trace[1550780719] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"100.600805ms","start":"2026-10-02T15:13:38.95165Z","end":"2026-10-02T15:13:39.052251Z","steps":["trace[1550780719] 'process raft request'  (duration: 100.485622ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:13:39.05233Z","caller":"traceutil/trace.go:171","msg":"trace[1885519939] transaction","detail":"{read_only:false; number_of_response:1; response_revision:540; }","duration":"100.40781ms","start":"2026-10-02T15:13:38.951889Z","end":"2026-10-02T15:13:39.052297Z","steps":["trace[1885519939] 'process raft request'  (duration: 100.323979ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:13:45.184598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.941051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6x7bb\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-10-02T15:13:45.184703Z","caller":"traceutil/trace.go:171","msg":"trace[829708248] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6x7bb; range_end:; response_count:1; response_revision:627; }","duration":"136.110555ms","start":"2026-10-02T15:13:45.048568Z","end":"2026-10-02T15:13:45.184678Z","steps":["trace[829708248] 'range keys from in-memory index tree'  (duration: 135.762818ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:13:45.254369Z","caller":"traceutil/trace.go:171","msg":"trace[221448346] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"133.866103ms","start":"2026-10-02T15:13:45.12044Z","end":"2026-10-02T15:13:45.254306Z","steps":["trace[221448346] 'process raft request'  (duration: 132.779963ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:13:45.464129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.543978ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8471729383302708350 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/cilium-6x7bb\" mod_revision:524 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-6x7bb\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-6x7bb\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-02T15:13:45.464457Z","caller":"traceutil/trace.go:171","msg":"trace[1564485290] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"257.116091ms","start":"2026-10-02T15:13:45.207311Z","end":"2026-10-02T15:13:45.464427Z","steps":["trace[1564485290] 'process raft request'  (duration: 86.063474ms)","trace[1564485290] 'compare'  (duration: 170.380065ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:13:45.464563Z","caller":"traceutil/trace.go:171","msg":"trace[29441848] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"133.058708ms","start":"2026-10-02T15:13:45.331462Z","end":"2026-10-02T15:13:45.46452Z","steps":["trace[29441848] 'process raft request'  (duration: 132.758562ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:14:01.027117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.755261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-02T15:14:01.027299Z","caller":"traceutil/trace.go:171","msg":"trace[657225354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"196.999216ms","start":"2026-10-02T15:14:00.830273Z","end":"2026-10-02T15:14:01.027272Z","steps":["trace[657225354] 'range keys from in-memory index tree'  (duration: 196.67283ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:14:54.046209Z","caller":"traceutil/trace.go:171","msg":"trace[1355657761] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1506; }","duration":"153.147905ms","start":"2026-10-02T15:14:53.893038Z","end":"2026-10-02T15:14:54.046186Z","steps":["trace[1355657761] 'read index received'  (duration: 152.944772ms)","trace[1355657761] 'applied index is now lower than readState.Index'  (duration: 202.303µs)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:14:54.046329Z","caller":"traceutil/trace.go:171","msg":"trace[633633196] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"161.375025ms","start":"2026-10-02T15:14:53.884941Z","end":"2026-10-02T15:14:54.046316Z","steps":["trace[633633196] 'process raft request'  (duration: 161.09279ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:14:54.046515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.46177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-10-02T15:14:54.046544Z","caller":"traceutil/trace.go:171","msg":"trace[1870749858] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1463; }","duration":"153.530172ms","start":"2026-10-02T15:14:53.893003Z","end":"2026-10-02T15:14:54.046533Z","steps":["trace[1870749858] 'agreement among raft nodes before linearized reading'  (duration: 153.42163ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:07.076522Z","caller":"traceutil/trace.go:171","msg":"trace[1047563355] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"150.256075ms","start":"2026-10-02T15:15:06.92624Z","end":"2026-10-02T15:15:07.076496Z","steps":["trace[1047563355] 'process raft request'  (duration: 150.090133ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:07.085625Z","caller":"traceutil/trace.go:171","msg":"trace[179104602] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"142.062558ms","start":"2026-10-02T15:15:06.943528Z","end":"2026-10-02T15:15:07.08559Z","steps":["trace[179104602] 'process raft request'  (duration: 141.746552ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:18.380611Z","caller":"traceutil/trace.go:171","msg":"trace[4223063] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"104.039513ms","start":"2026-10-02T15:15:18.276537Z","end":"2026-10-02T15:15:18.380576Z","steps":["trace[4223063] 'process raft request'  (duration: 103.95797ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:18.380666Z","caller":"traceutil/trace.go:171","msg":"trace[404806399] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"104.998245ms","start":"2026-10-02T15:15:18.275636Z","end":"2026-10-02T15:15:18.380635Z","steps":["trace[404806399] 'process raft request'  (duration: 104.750607ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:21.379773Z","caller":"traceutil/trace.go:171","msg":"trace[1762363533] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"184.026003ms","start":"2026-10-02T15:15:21.195724Z","end":"2026-10-02T15:15:21.37975Z","steps":["trace[1762363533] 'read index received'  (duration: 183.881599ms)","trace[1762363533] 'applied index is now lower than readState.Index'  (duration: 143.284µs)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:15:21.379912Z","caller":"traceutil/trace.go:171","msg":"trace[1835138888] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"221.808609ms","start":"2026-10-02T15:15:21.15806Z","end":"2026-10-02T15:15:21.379868Z","steps":["trace[1835138888] 'process raft request'  (duration: 221.550921ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:21.380015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.873433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"}
{"level":"info","ts":"2026-10-02T15:15:21.380059Z","caller":"traceutil/trace.go:171","msg":"trace[622226733] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1878; }","duration":"170.000328ms","start":"2026-10-02T15:15:21.210045Z","end":"2026-10-02T15:15:21.380045Z","steps":["trace[622226733] 'agreement among raft nodes before linearized reading'  (duration: 169.859354ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:21.380034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.290853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-10-02T15:15:21.38015Z","caller":"traceutil/trace.go:171","msg":"trace[850797283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1878; }","duration":"184.451708ms","start":"2026-10-02T15:15:21.195674Z","end":"2026-10-02T15:15:21.380126Z","steps":["trace[850797283] 'agreement among raft nodes before linearized reading'  (duration: 184.195839ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:21.667274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.525376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8824bba4-cf5d-41b0-84fa-5692b3c7d4f5\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-10-02T15:15:21.667383Z","caller":"traceutil/trace.go:171","msg":"trace[1874882288] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8824bba4-cf5d-41b0-84fa-5692b3c7d4f5; range_end:; response_count:1; response_revision:1882; }","duration":"102.669731ms","start":"2026-10-02T15:15:21.564673Z","end":"2026-10-02T15:15:21.667343Z","steps":["trace[1874882288] 'range keys from in-memory index tree'  (duration: 102.34927ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:33.759243Z","caller":"traceutil/trace.go:171","msg":"trace[1708145775] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"224.146526ms","start":"2026-10-02T15:15:33.53507Z","end":"2026-10-02T15:15:33.759217Z","steps":["trace[1708145775] 'process raft request'  (duration: 223.976051ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:33.930167Z","caller":"traceutil/trace.go:171","msg":"trace[1617693374] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"394.776965ms","start":"2026-10-02T15:15:33.53535Z","end":"2026-10-02T15:15:33.930127Z","steps":["trace[1617693374] 'process raft request'  (duration: 315.606861ms)","trace[1617693374] 'compare'  (duration: 78.922176ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:15:33.930339Z","caller":"traceutil/trace.go:171","msg":"trace[214410539] linearizableReadLoop","detail":"{readStateIndex:2061; appliedIndex:2058; }","duration":"172.411626ms","start":"2026-10-02T15:15:33.757897Z","end":"2026-10-02T15:15:33.930308Z","steps":["trace[214410539] 'read index received'  (duration: 1.135966ms)","trace[214410539] 'applied index is now lower than readState.Index'  (duration: 171.27372ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T15:15:33.930362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:15:33.535329Z","time spent":"394.94127ms","remote":"127.0.0.1:43788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:1970 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-10-02T15:15:33.930484Z","caller":"traceutil/trace.go:171","msg":"trace[1548981327] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"204.747007ms","start":"2026-10-02T15:15:33.7257Z","end":"2026-10-02T15:15:33.930447Z","steps":["trace[1548981327] 'process raft request'  (duration: 204.357055ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:33.930653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.741427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11982"}
{"level":"info","ts":"2026-10-02T15:15:33.9307Z","caller":"traceutil/trace.go:171","msg":"trace[1802119040] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1986; }","duration":"172.85864ms","start":"2026-10-02T15:15:33.757829Z","end":"2026-10-02T15:15:33.930688Z","steps":["trace[1802119040] 'agreement among raft nodes before linearized reading'  (duration: 172.639323ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:33.930678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.784429ms","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-02T15:15:33.930752Z","caller":"traceutil/trace.go:171","msg":"trace[610670170] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1986; }","duration":"130.906334ms","start":"2026-10-02T15:15:33.799827Z","end":"2026-10-02T15:15:33.930733Z","steps":["trace[610670170] 'agreement among raft nodes before linearized reading'  (duration: 130.675406ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:34.320344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.928582ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8471729383302711036 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.204.45.138\" mod_revision:1904 > success:<request_put:<key:\"/registry/masterleases/199.204.45.138\" value_size:67 lease:8471729383302711032 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.138\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-02T15:15:34.32054Z","caller":"traceutil/trace.go:171","msg":"trace[1901555425] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"307.148897ms","start":"2026-10-02T15:15:34.013343Z","end":"2026-10-02T15:15:34.320491Z","steps":["trace[1901555425] 'process raft request'  (duration: 106.967257ms)","trace[1901555425] 'compare'  (duration: 199.761237ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:15:34.320661Z","caller":"traceutil/trace.go:171","msg":"trace[961717970] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"303.914584ms","start":"2026-10-02T15:15:34.016675Z","end":"2026-10-02T15:15:34.320589Z","steps":["trace[961717970] 'process raft request'  (duration: 303.78808ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:34.320732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:15:34.013314Z","time spent":"307.316313ms","remote":"127.0.0.1:43580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.138\" mod_revision:1904 > success:<request_put:<key:\"/registry/masterleases/199.204.45.138\" value_size:67 lease:8471729383302711032 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.138\" > >"}
{"level":"info","ts":"2026-10-02T15:15:34.320756Z","caller":"traceutil/trace.go:171","msg":"trace[738984496] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"305.802215ms","start":"2026-10-02T15:15:34.014929Z","end":"2026-10-02T15:15:34.320731Z","steps":["trace[738984496] 'read index received'  (duration: 105.350686ms)","trace[738984496] 'applied index is now lower than readState.Index'  (duration: 200.449069ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T15:15:34.320824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:15:34.016657Z","time spent":"304.113861ms","remote":"127.0.0.1:43788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:1978 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-10-02T15:15:34.320954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.011831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-02T15:15:34.321012Z","caller":"traceutil/trace.go:171","msg":"trace[1300213332] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1988; }","duration":"306.091744ms","start":"2026-10-02T15:15:34.014905Z","end":"2026-10-02T15:15:34.320996Z","steps":["trace[1300213332] 'agreement among raft nodes before linearized reading'  (duration: 305.911919ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:34.321053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:15:34.014889Z","time spent":"306.153046ms","remote":"127.0.0.1:43738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "}
{"level":"info","ts":"2026-10-02T15:15:34.347442Z","caller":"traceutil/trace.go:171","msg":"trace[1424188983] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"196.248585ms","start":"2026-10-02T15:15:34.151169Z","end":"2026-10-02T15:15:34.347418Z","steps":["trace[1424188983] 'process raft request'  (duration: 195.543072ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:34.539567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.661083ms","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-10-02T15:15:34.539665Z","caller":"traceutil/trace.go:171","msg":"trace[2143911113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1989; }","duration":"114.792368ms","start":"2026-10-02T15:15:34.424835Z","end":"2026-10-02T15:15:34.539627Z","steps":["trace[2143911113] 'range keys from in-memory index tree'  (duration: 114.438036ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:34.665549Z","caller":"traceutil/trace.go:171","msg":"trace[247190998] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"121.640524ms","start":"2026-10-02T15:15:34.543879Z","end":"2026-10-02T15:15:34.665519Z","steps":["trace[247190998] 'process raft request'  (duration: 121.468649ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:38.097249Z","caller":"traceutil/trace.go:171","msg":"trace[349220288] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2090; }","duration":"150.841166ms","start":"2026-10-02T15:15:37.946364Z","end":"2026-10-02T15:15:38.097205Z","steps":["trace[349220288] 'read index received'  (duration: 150.683091ms)","trace[349220288] 'applied index is now lower than readState.Index'  (duration: 156.964µs)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T15:15:38.097424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.049303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-10-02T15:15:38.097469Z","caller":"traceutil/trace.go:171","msg":"trace[391860128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2015; }","duration":"151.108645ms","start":"2026-10-02T15:15:37.946353Z","end":"2026-10-02T15:15:38.097462Z","steps":["trace[391860128] 'agreement among raft nodes before linearized reading'  (duration: 150.95773ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:38.097594Z","caller":"traceutil/trace.go:171","msg":"trace[1204158288] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"154.294465ms","start":"2026-10-02T15:15:37.94298Z","end":"2026-10-02T15:15:38.097275Z","steps":["trace[1204158288] 'process raft request'  (duration: 154.047557ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:38.241796Z","caller":"traceutil/trace.go:171","msg":"trace[730240264] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"144.329906ms","start":"2026-10-02T15:15:38.097426Z","end":"2026-10-02T15:15:38.241756Z","steps":["trace[730240264] 'read index received'  (duration: 144.289935ms)","trace[730240264] 'applied index is now lower than readState.Index'  (duration: 38.341µs)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:15:38.241832Z","caller":"traceutil/trace.go:171","msg":"trace[2036573015] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"246.014955ms","start":"2026-10-02T15:15:37.995785Z","end":"2026-10-02T15:15:38.2418Z","steps":["trace[2036573015] 'process raft request'  (duration: 245.805369ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:15:38.242043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.813934ms","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-02T15:15:38.242099Z","caller":"traceutil/trace.go:171","msg":"trace[342620086] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2016; }","duration":"283.910077ms","start":"2026-10-02T15:15:37.95817Z","end":"2026-10-02T15:15:38.24208Z","steps":["trace[342620086] 'agreement among raft nodes before linearized reading'  (duration: 283.70117ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:15:38.243531Z","caller":"traceutil/trace.go:171","msg":"trace[1088924298] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"143.300043ms","start":"2026-10-02T15:15:38.10021Z","end":"2026-10-02T15:15:38.24351Z","steps":["trace[1088924298] 'process raft request'  (duration: 143.21125ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.381604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.909351ms","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-02T15:16:02.381721Z","caller":"traceutil/trace.go:171","msg":"trace[1602065581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2147; }","duration":"451.057256ms","start":"2026-10-02T15:16:01.930629Z","end":"2026-10-02T15:16:02.381686Z","steps":["trace[1602065581] 'range keys from in-memory index tree'  (duration: 450.687115ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.381838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:01.930615Z","time spent":"451.19873ms","remote":"127.0.0.1:43788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-10-02T15:16:02.381829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.072756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-10-02T15:16:02.381871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.991343ms","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-02T15:16:02.382013Z","caller":"traceutil/trace.go:171","msg":"trace[1096437687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2147; }","duration":"737.181947ms","start":"2026-10-02T15:16:01.644806Z","end":"2026-10-02T15:16:02.381988Z","steps":["trace[1096437687] 'range keys from in-memory index tree'  (duration: 736.684653ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:16:02.382046Z","caller":"traceutil/trace.go:171","msg":"trace[1134900077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2147; }","duration":"550.300673ms","start":"2026-10-02T15:16:01.831675Z","end":"2026-10-02T15:16:02.381975Z","steps":["trace[1134900077] 'range keys from in-memory index tree'  (duration: 549.966613ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.3821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.638852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127950"}
{"level":"info","ts":"2026-10-02T15:16:02.382165Z","caller":"traceutil/trace.go:171","msg":"trace[1443382959] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2147; }","duration":"389.724065ms","start":"2026-10-02T15:16:01.992431Z","end":"2026-10-02T15:16:02.382155Z","steps":["trace[1443382959] 'range keys from in-memory index tree'  (duration: 389.463497ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.382182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:01.99242Z","time spent":"389.756316ms","remote":"127.0.0.1:43612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":127974,"request content":"key:\"/registry/ranges/serviceips\" "}
{"level":"warn","ts":"2026-10-02T15:16:02.382166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:01.831648Z","time spent":"550.501658ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-10-02T15:16:02.382061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:01.644774Z","time spent":"737.27319ms","remote":"127.0.0.1:43788","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-10-02T15:16:02.382024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.321882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"}
{"level":"info","ts":"2026-10-02T15:16:02.382374Z","caller":"traceutil/trace.go:171","msg":"trace[1223113483] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2147; }","duration":"390.742354ms","start":"2026-10-02T15:16:01.991614Z","end":"2026-10-02T15:16:02.382356Z","steps":["trace[1223113483] 'range keys from in-memory index tree'  (duration: 390.202759ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.382466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:01.991587Z","time spent":"390.863338ms","remote":"127.0.0.1:43622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "}
{"level":"warn","ts":"2026-10-02T15:16:02.382265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.552366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"}
{"level":"info","ts":"2026-10-02T15:16:02.382678Z","caller":"traceutil/trace.go:171","msg":"trace[118364071] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2147; }","duration":"729.075341ms","start":"2026-10-02T15:16:01.653585Z","end":"2026-10-02T15:16:02.38266Z","steps":["trace[118364071] 'range keys from in-memory index tree'  (duration: 728.289388ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.382725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:01.653543Z","time spent":"729.167084ms","remote":"127.0.0.1:43788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-10-02T15:16:02.566177Z","caller":"traceutil/trace.go:171","msg":"trace[538859415] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"381.03484ms","start":"2026-10-02T15:16:02.185111Z","end":"2026-10-02T15:16:02.566145Z","steps":["trace[538859415] 'process raft request'  (duration: 380.878916ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.56649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:02.185087Z","time spent":"381.176165ms","remote":"127.0.0.1:43788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2141 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-10-02T15:16:02.567043Z","caller":"traceutil/trace.go:171","msg":"trace[419956818] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2229; }","duration":"216.993796ms","start":"2026-10-02T15:16:02.350035Z","end":"2026-10-02T15:16:02.567029Z","steps":["trace[419956818] 'read index received'  (duration: 216.989226ms)","trace[419956818] 'applied index is now lower than readState.Index'  (duration: 3.67µs)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T15:16:02.56729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.228762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-10-02T15:16:02.567335Z","caller":"traceutil/trace.go:171","msg":"trace[1475123513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2148; }","duration":"217.358257ms","start":"2026-10-02T15:16:02.349964Z","end":"2026-10-02T15:16:02.567322Z","steps":["trace[1475123513] 'agreement among raft nodes before linearized reading'  (duration: 217.14201ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:16:02.665684Z","caller":"traceutil/trace.go:171","msg":"trace[595107870] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"277.989551ms","start":"2026-10-02T15:16:02.387664Z","end":"2026-10-02T15:16:02.665653Z","steps":["trace[595107870] 'process raft request'  (duration: 237.281946ms)","trace[595107870] 'compare'  (duration: 40.54211ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:16:02.666106Z","caller":"traceutil/trace.go:171","msg":"trace[1830086762] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"277.519977ms","start":"2026-10-02T15:16:02.388557Z","end":"2026-10-02T15:16:02.666077Z","steps":["trace[1830086762] 'process raft request'  (duration: 277.347502ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.666415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.138372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25784"}
{"level":"info","ts":"2026-10-02T15:16:02.667619Z","caller":"traceutil/trace.go:171","msg":"trace[628199910] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2153; }","duration":"282.40287ms","start":"2026-10-02T15:16:02.385199Z","end":"2026-10-02T15:16:02.667602Z","steps":["trace[628199910] 'agreement among raft nodes before linearized reading'  (duration: 280.900336ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.666427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.644198ms","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-02T15:16:02.667856Z","caller":"traceutil/trace.go:171","msg":"trace[661255506] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"260.141202ms","start":"2026-10-02T15:16:02.407687Z","end":"2026-10-02T15:16:02.667828Z","steps":["trace[661255506] 'agreement among raft nodes before linearized reading'  (duration: 258.598427ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.666468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.061151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-02T15:16:02.668054Z","caller":"traceutil/trace.go:171","msg":"trace[1628214620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2153; }","duration":"281.760811ms","start":"2026-10-02T15:16:02.386283Z","end":"2026-10-02T15:16:02.668044Z","steps":["trace[1628214620] 'agreement among raft nodes before linearized reading'  (duration: 280.162645ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:16:02.666471Z","caller":"traceutil/trace.go:171","msg":"trace[669719901] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"220.545509ms","start":"2026-10-02T15:16:02.445914Z","end":"2026-10-02T15:16:02.666459Z","steps":["trace[669719901] 'process raft request'  (duration: 220.028434ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.666548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.461692ms","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-02T15:16:02.668365Z","caller":"traceutil/trace.go:171","msg":"trace[1461467303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2153; }","duration":"217.316416ms","start":"2026-10-02T15:16:02.451039Z","end":"2026-10-02T15:16:02.668355Z","steps":["trace[1461467303] 'agreement among raft nodes before linearized reading'  (duration: 215.450471ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:16:02.666651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.731031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25784"}
{"level":"info","ts":"2026-10-02T15:16:02.668499Z","caller":"traceutil/trace.go:171","msg":"trace[1101451501] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2153; }","duration":"282.581935ms","start":"2026-10-02T15:16:02.38589Z","end":"2026-10-02T15:16:02.668472Z","steps":["trace[1101451501] 'agreement among raft nodes before linearized reading'  (duration: 280.596457ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:16:02.666697Z","caller":"traceutil/trace.go:171","msg":"trace[1572919631] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"278.97965ms","start":"2026-10-02T15:16:02.387704Z","end":"2026-10-02T15:16:02.666683Z","steps":["trace[1572919631] 'process raft request'  (duration: 277.900648ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:16:02.784465Z","caller":"traceutil/trace.go:171","msg":"trace[1857603228] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"111.080273ms","start":"2026-10-02T15:16:02.673353Z","end":"2026-10-02T15:16:02.784433Z","steps":["trace[1857603228] 'process raft request'  (duration: 110.215318ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:22:02.839297Z","caller":"traceutil/trace.go:171","msg":"trace[1912870661] linearizableReadLoop","detail":"{readStateIndex:5041; appliedIndex:5040; }","duration":"115.075835ms","start":"2026-10-02T15:22:02.724198Z","end":"2026-10-02T15:22:02.839274Z","steps":["trace[1912870661] 'read index received'  (duration: 115.053215ms)","trace[1912870661] 'applied index is now lower than readState.Index'  (duration: 21.59µs)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:22:02.839406Z","caller":"traceutil/trace.go:171","msg":"trace[1824489256] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"123.309171ms","start":"2026-10-02T15:22:02.716041Z","end":"2026-10-02T15:22:02.83935Z","steps":["trace[1824489256] 'process raft request'  (duration: 123.120807ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:22:02.839532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.32199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-10-02T15:22:02.839579Z","caller":"traceutil/trace.go:171","msg":"trace[761378787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4860; }","duration":"115.406541ms","start":"2026-10-02T15:22:02.724162Z","end":"2026-10-02T15:22:02.839569Z","steps":["trace[761378787] 'agreement among raft nodes before linearized reading'  (duration: 115.241298ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:22:08.676606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.026549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-10-02T15:22:08.676684Z","caller":"traceutil/trace.go:171","msg":"trace[2016223215] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4960; }","duration":"160.152092ms","start":"2026-10-02T15:22:08.516512Z","end":"2026-10-02T15:22:08.676664Z","steps":["trace[2016223215] 'count revisions from in-memory index tree'  (duration: 159.957438ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:22:09.076051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.296644ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8471729383302718494 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:4886 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-02T15:22:09.076181Z","caller":"traceutil/trace.go:171","msg":"trace[1005611526] linearizableReadLoop","detail":"{readStateIndex:5154; appliedIndex:5152; }","duration":"165.767447ms","start":"2026-10-02T15:22:08.910398Z","end":"2026-10-02T15:22:09.076165Z","steps":["trace[1005611526] 'read index received'  (duration: 5.26728ms)","trace[1005611526] 'applied index is now lower than readState.Index'  (duration: 160.499207ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:22:09.07628Z","caller":"traceutil/trace.go:171","msg":"trace[1649736008] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"170.797482ms","start":"2026-10-02T15:22:08.905445Z","end":"2026-10-02T15:22:09.076242Z","steps":["trace[1649736008] 'process raft request'  (duration: 170.68262ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:22:09.076447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.051443ms","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-10-02T15:22:09.076463Z","caller":"traceutil/trace.go:171","msg":"trace[1062583428] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"175.058473ms","start":"2026-10-02T15:22:08.901387Z","end":"2026-10-02T15:22:09.076445Z","steps":["trace[1062583428] 'process raft request'  (duration: 14.269949ms)","trace[1062583428] 'compare'  (duration: 160.04995ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:22:09.07648Z","caller":"traceutil/trace.go:171","msg":"trace[1810862083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4971; }","duration":"166.111834ms","start":"2026-10-02T15:22:08.910359Z","end":"2026-10-02T15:22:09.076471Z","steps":["trace[1810862083] 'agreement among raft nodes before linearized reading'  (duration: 165.994062ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:22:09.113836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.755417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-10-02T15:22:09.113908Z","caller":"traceutil/trace.go:171","msg":"trace[842193906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4972; }","duration":"201.849828ms","start":"2026-10-02T15:22:08.91204Z","end":"2026-10-02T15:22:09.113889Z","steps":["trace[842193906] 'agreement among raft nodes before linearized reading'  (duration: 201.612724ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:22:09.113957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.000109ms","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-10-02T15:22:09.11403Z","caller":"traceutil/trace.go:171","msg":"trace[571150813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4972; }","duration":"186.108551ms","start":"2026-10-02T15:22:08.927902Z","end":"2026-10-02T15:22:09.11401Z","steps":["trace[571150813] 'agreement among raft nodes before linearized reading'  (duration: 185.904027ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:22:09.114047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.94553ms","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-02T15:22:09.114075Z","caller":"traceutil/trace.go:171","msg":"trace[334064332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4972; }","duration":"201.980021ms","start":"2026-10-02T15:22:08.912086Z","end":"2026-10-02T15:22:09.114066Z","steps":["trace[334064332] 'agreement among raft nodes before linearized reading'  (duration: 201.904459ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T15:22:20.345745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.393461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-02T15:22:20.345843Z","caller":"traceutil/trace.go:171","msg":"trace[212711027] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5114; }","duration":"106.577075ms","start":"2026-10-02T15:22:20.239242Z","end":"2026-10-02T15:22:20.345819Z","steps":["trace[212711027] 'count revisions from in-memory index tree'  (duration: 106.30757ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:22:20.896965Z","caller":"traceutil/trace.go:171","msg":"trace[882412707] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"104.658369ms","start":"2026-10-02T15:22:20.792264Z","end":"2026-10-02T15:22:20.896922Z","steps":["trace[882412707] 'process raft request'  (duration: 83.003261ms)","trace[882412707] 'compare'  (duration: 21.481205ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T15:22:20.897154Z","caller":"traceutil/trace.go:171","msg":"trace[1296678903] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"104.381493ms","start":"2026-10-02T15:22:20.792734Z","end":"2026-10-02T15:22:20.897116Z","steps":["trace[1296678903] 'process raft request'  (duration: 104.150049ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:22:20.897669Z","caller":"traceutil/trace.go:171","msg":"trace[480713995] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"104.75882ms","start":"2026-10-02T15:22:20.79288Z","end":"2026-10-02T15:22:20.897639Z","steps":["trace[480713995] 'process raft request'  (duration: 104.058997ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:22:20.898419Z","caller":"traceutil/trace.go:171","msg":"trace[1867234111] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"104.841432ms","start":"2026-10-02T15:22:20.793546Z","end":"2026-10-02T15:22:20.898387Z","steps":["trace[1867234111] 'process raft request'  (duration: 103.457436ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T15:23:00.367477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2893}
{"level":"info","ts":"2026-10-02T15:23:00.462433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2893,"took":"92.992855ms","hash":3331937216,"current-db-size-bytes":52285440,"current-db-size":"52 MB","current-db-size-in-use-bytes":28536832,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-10-02T15:23:00.462523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3331937216,"revision":2893,"compact-revision":-1}
{"level":"info","ts":"2026-10-02T15:28:00.375918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5479}
{"level":"info","ts":"2026-10-02T15:28:00.498063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5479,"took":"120.43643ms","hash":3885559250,"current-db-size-bytes":52285440,"current-db-size":"52 MB","current-db-size-in-use-bytes":18649088,"current-db-size-in-use":"19 MB"}
{"level":"info","ts":"2026-10-02T15:28:00.498116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3885559250,"revision":5479,"compact-revision":2893}