{"level":"warn","ts":"2026-09-18T22:54:25.352495Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T22:54:25.352669Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.82:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.82:2380","--initial-cluster=instance=https://162.253.55.82:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.82:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.82: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-09-18T22:54:25.352784Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T22:54:25.35281Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.82:2380"]} {"level":"info","ts":"2026-09-18T22:54:25.352849Z","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-09-18T22:54:25.353791Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.82:2379"]} {"level":"info","ts":"2026-09-18T22:54:25.353912Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.82:2380"],"listen-peer-urls":["https://162.253.55.82:2380"],"advertise-client-urls":["https://162.253.55.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.82: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-09-18T22:54:25.36046Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.088525ms"} {"level":"info","ts":"2026-09-18T22:54:25.37266Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c5298098e2dc0f92","cluster-id":"e15b04acbd0c3f73"} {"level":"info","ts":"2026-09-18T22:54:25.372816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T22:54:25.372863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became follower at term 0"} {"level":"info","ts":"2026-09-18T22:54:25.372889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c5298098e2dc0f92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T22:54:25.373003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became follower at term 1"} {"level":"info","ts":"2026-09-18T22:54:25.373076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 switched to configuration voters=(14207027893646397330)"} {"level":"warn","ts":"2026-09-18T22:54:25.384177Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T22:54:25.387248Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T22:54:25.38876Z","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-09-18T22:54:25.391322Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c5298098e2dc0f92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T22:54:25.394442Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c5298098e2dc0f92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T22:54:25.394807Z","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-09-18T22:54:25.394862Z","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-09-18T22:54:25.394873Z","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-09-18T22:54:25.395647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 switched to configuration voters=(14207027893646397330)"} {"level":"info","ts":"2026-09-18T22:54:25.400939Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e15b04acbd0c3f73","local-member-id":"c5298098e2dc0f92","added-peer-id":"c5298098e2dc0f92","added-peer-peer-urls":["https://162.253.55.82:2380"]} {"level":"info","ts":"2026-09-18T22:54:25.405007Z","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-09-18T22:54:25.405119Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.82:2380"} {"level":"info","ts":"2026-09-18T22:54:25.405189Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.82:2380"} {"level":"info","ts":"2026-09-18T22:54:25.405971Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c5298098e2dc0f92","initial-advertise-peer-urls":["https://162.253.55.82:2380"],"listen-peer-urls":["https://162.253.55.82:2380"],"advertise-client-urls":["https://162.253.55.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T22:54:25.406013Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T22:54:26.273393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T22:54:26.273479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T22:54:26.273501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 received MsgPreVoteResp from c5298098e2dc0f92 at term 1"} {"level":"info","ts":"2026-09-18T22:54:26.273516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became candidate at term 2"} {"level":"info","ts":"2026-09-18T22:54:26.273522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 received MsgVoteResp from c5298098e2dc0f92 at term 2"} {"level":"info","ts":"2026-09-18T22:54:26.273532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5298098e2dc0f92 became leader at term 2"} {"level":"info","ts":"2026-09-18T22:54:26.273555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c5298098e2dc0f92 elected leader c5298098e2dc0f92 at term 2"} {"level":"info","ts":"2026-09-18T22:54:26.27461Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T22:54:26.275411Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c5298098e2dc0f92","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.82:2379]}","request-path":"/0/members/c5298098e2dc0f92/attributes","cluster-id":"e15b04acbd0c3f73","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T22:54:26.275445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T22:54:26.275998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e15b04acbd0c3f73","local-member-id":"c5298098e2dc0f92","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T22:54:26.276074Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T22:54:26.2761Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T22:54:26.276125Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T22:54:26.276123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T22:54:26.276138Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T22:54:26.278418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.82:2379"} {"level":"info","ts":"2026-09-18T22:54:26.280442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T22:54:50.671072Z","caller":"traceutil/trace.go:171","msg":"trace[1608261571] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"101.853171ms","start":"2026-09-18T22:54:50.569187Z","end":"2026-09-18T22:54:50.67104Z","steps":["trace[1608261571] 'process raft request' (duration: 47.360037ms)","trace[1608261571] 'compare' (duration: 54.274998ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:54:50.677209Z","caller":"traceutil/trace.go:171","msg":"trace[1745997383] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"104.855139ms","start":"2026-09-18T22:54:50.572329Z","end":"2026-09-18T22:54:50.677184Z","steps":["trace[1745997383] 'process raft request' (duration: 104.713285ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:54:50.677259Z","caller":"traceutil/trace.go:171","msg":"trace[878230543] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"105.231539ms","start":"2026-09-18T22:54:50.571996Z","end":"2026-09-18T22:54:50.677228Z","steps":["trace[878230543] 'process raft request' (duration: 104.940311ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:05.021563Z","caller":"traceutil/trace.go:171","msg":"trace[424131886] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"232.300118ms","start":"2026-09-18T22:55:04.789239Z","end":"2026-09-18T22:55:05.021539Z","steps":["trace[424131886] 'process raft request' (duration: 232.154504ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:05.034214Z","caller":"traceutil/trace.go:171","msg":"trace[2137634547] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"244.910178ms","start":"2026-09-18T22:55:04.789286Z","end":"2026-09-18T22:55:05.034196Z","steps":["trace[2137634547] 'process raft request' (duration: 243.762638ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:05.034527Z","caller":"traceutil/trace.go:171","msg":"trace[1524824366] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"221.425271ms","start":"2026-09-18T22:55:04.813088Z","end":"2026-09-18T22:55:05.034514Z","steps":["trace[1524824366] 'process raft request' (duration: 220.287872ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:11.504288Z","caller":"traceutil/trace.go:171","msg":"trace[397205509] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"214.830268ms","start":"2026-09-18T22:55:11.289434Z","end":"2026-09-18T22:55:11.504264Z","steps":["trace[397205509] 'read index received' (duration: 214.018678ms)","trace[397205509] 'applied index is now lower than readState.Index' (duration: 809.3µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:55:11.50454Z","caller":"traceutil/trace.go:171","msg":"trace[867476114] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"222.445131ms","start":"2026-09-18T22:55:11.282065Z","end":"2026-09-18T22:55:11.50451Z","steps":["trace[867476114] 'process raft request' (duration: 221.63155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.55269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.89067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:55:11.552857Z","caller":"traceutil/trace.go:171","msg":"trace[218750266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"242.108024ms","start":"2026-09-18T22:55:11.310698Z","end":"2026-09-18T22:55:11.552806Z","steps":["trace[218750266] 'agreement among raft nodes before linearized reading' (duration: 193.849732ms)","trace[218750266] 'range keys from in-memory index tree' (duration: 48.039687ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:55:11.552835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.369468ms","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-09-18T22:55:11.55293Z","caller":"traceutil/trace.go:171","msg":"trace[1605038897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"263.506952ms","start":"2026-09-18T22:55:11.2894Z","end":"2026-09-18T22:55:11.552907Z","steps":["trace[1605038897] 'agreement among raft nodes before linearized reading' (duration: 215.055174ms)","trace[1605038897] 'range keys from in-memory index tree' (duration: 48.174811ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:55:11.863538Z","caller":"traceutil/trace.go:171","msg":"trace[1425966806] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"302.649916ms","start":"2026-09-18T22:55:11.560863Z","end":"2026-09-18T22:55:11.863512Z","steps":["trace[1425966806] 'process raft request' (duration: 302.483382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:11.864387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:11.560841Z","time spent":"302.791589ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:55:11.875892Z","caller":"traceutil/trace.go:171","msg":"trace[1195648127] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"292.07632ms","start":"2026-09-18T22:55:11.583781Z","end":"2026-09-18T22:55:11.875857Z","steps":["trace[1195648127] 'process raft request' (duration: 291.714161ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:12.292185Z","caller":"traceutil/trace.go:171","msg":"trace[1885316681] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"134.425963ms","start":"2026-09-18T22:55:12.157719Z","end":"2026-09-18T22:55:12.292145Z","steps":["trace[1885316681] 'process raft request' (duration: 134.188428ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:12.794962Z","caller":"traceutil/trace.go:171","msg":"trace[2100656498] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"241.838489ms","start":"2026-09-18T22:55:12.553097Z","end":"2026-09-18T22:55:12.794936Z","steps":["trace[2100656498] 'read index received' (duration: 241.019259ms)","trace[2100656498] 'applied index is now lower than readState.Index' (duration: 818.16µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:55:12.795051Z","caller":"traceutil/trace.go:171","msg":"trace[1606425912] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"489.28836ms","start":"2026-09-18T22:55:12.305709Z","end":"2026-09-18T22:55:12.794997Z","steps":["trace[1606425912] 'process raft request' (duration: 488.582652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.79523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:12.305682Z","time spent":"489.449083ms","remote":"127.0.0.1:44034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T22:55:12.795216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.096475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7xmvx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-18T22:55:12.795302Z","caller":"traceutil/trace.go:171","msg":"trace[487907730] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7xmvx; range_end:; response_count:1; response_revision:588; }","duration":"242.24524ms","start":"2026-09-18T22:55:12.553036Z","end":"2026-09-18T22:55:12.795281Z","steps":["trace[487907730] 'agreement among raft nodes before linearized reading' (duration: 241.992704ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:12.84748Z","caller":"traceutil/trace.go:171","msg":"trace[1862067712] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"225.018238ms","start":"2026-09-18T22:55:12.622438Z","end":"2026-09-18T22:55:12.847456Z","steps":["trace[1862067712] 'process raft request' (duration: 224.72517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:12.848147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.875084ms","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-09-18T22:55:12.848282Z","caller":"traceutil/trace.go:171","msg":"trace[1337635695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"286.071089ms","start":"2026-09-18T22:55:12.562178Z","end":"2026-09-18T22:55:12.848249Z","steps":["trace[1337635695] 'agreement among raft nodes before linearized reading' (duration: 285.646549ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:55:13.296038Z","caller":"traceutil/trace.go:171","msg":"trace[910286762] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"448.737814ms","start":"2026-09-18T22:55:12.847275Z","end":"2026-09-18T22:55:13.296013Z","steps":["trace[910286762] 'read index received' (duration: 448.56952ms)","trace[910286762] 'applied index is now lower than readState.Index' (duration: 167.314µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:55:13.296107Z","caller":"traceutil/trace.go:171","msg":"trace[316727523] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"459.245957ms","start":"2026-09-18T22:55:12.836832Z","end":"2026-09-18T22:55:13.296078Z","steps":["trace[316727523] 'process raft request' (duration: 459.033602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:13.296209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.551913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:55:13.296243Z","caller":"traceutil/trace.go:171","msg":"trace[1734779160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"490.632255ms","start":"2026-09-18T22:55:12.805598Z","end":"2026-09-18T22:55:13.296231Z","steps":["trace[1734779160] 'agreement among raft nodes before linearized reading' (duration: 490.559473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:13.296275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:12.805562Z","time spent":"490.705527ms","remote":"127.0.0.1:43940","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-09-18T22:55:13.296269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:12.836808Z","time spent":"459.374831ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:55:13.702021Z","caller":"traceutil/trace.go:171","msg":"trace[1558910181] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"849.93592ms","start":"2026-09-18T22:55:12.852054Z","end":"2026-09-18T22:55:13.70199Z","steps":["trace[1558910181] 'process raft request' (duration: 839.369525ms)","trace[1558910181] 'compare' (duration: 10.260438ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:55:13.702173Z","caller":"traceutil/trace.go:171","msg":"trace[988856836] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"404.92054ms","start":"2026-09-18T22:55:13.297233Z","end":"2026-09-18T22:55:13.702154Z","steps":["trace[988856836] 'read index received' (duration: 394.194691ms)","trace[988856836] 'applied index is now lower than readState.Index' (duration: 10.724269ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:55:13.702211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:12.85203Z","time spent":"850.064023ms","remote":"127.0.0.1:44202","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-09-18T22:55:13.702429Z","caller":"traceutil/trace.go:171","msg":"trace[857650731] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"819.861976ms","start":"2026-09-18T22:55:12.882553Z","end":"2026-09-18T22:55:13.702415Z","steps":["trace[857650731] 'process raft request' (duration: 819.262091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:13.702429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.182737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:55:13.702488Z","caller":"traceutil/trace.go:171","msg":"trace[531265652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"405.274739ms","start":"2026-09-18T22:55:13.2972Z","end":"2026-09-18T22:55:13.702474Z","steps":["trace[531265652] 'agreement among raft nodes before linearized reading' (duration: 405.017043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:13.702526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:13.297183Z","time spent":"405.33345ms","remote":"127.0.0.1:43934","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-09-18T22:55:13.702543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:55:12.882528Z","time spent":"819.965819ms","remote":"127.0.0.1:44282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T22:55:14.763411Z","caller":"traceutil/trace.go:171","msg":"trace[1484448865] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"100.105017ms","start":"2026-09-18T22:55:14.66328Z","end":"2026-09-18T22:55:14.763385Z","steps":["trace[1484448865] 'process raft request' (duration: 91.548443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:30.912364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.568467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:55:30.912494Z","caller":"traceutil/trace.go:171","msg":"trace[622949173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"108.719118ms","start":"2026-09-18T22:55:30.803731Z","end":"2026-09-18T22:55:30.91245Z","steps":["trace[622949173] 'range keys from in-memory index tree' (duration: 108.507946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:55:30.912522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.74306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q64l9\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-09-18T22:55:30.912574Z","caller":"traceutil/trace.go:171","msg":"trace[476052939] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q64l9; range_end:; response_count:1; response_revision:766; }","duration":"252.834351ms","start":"2026-09-18T22:55:30.659725Z","end":"2026-09-18T22:55:30.91256Z","steps":["trace[476052939] 'range keys from in-memory index tree' (duration: 252.523908ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:02.320677Z","caller":"traceutil/trace.go:171","msg":"trace[1401121442] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"138.283771ms","start":"2026-09-18T22:56:02.182366Z","end":"2026-09-18T22:56:02.32065Z","steps":["trace[1401121442] 'read index received' (duration: 138.125709ms)","trace[1401121442] 'applied index is now lower than readState.Index' (duration: 156.602µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:56:02.320988Z","caller":"traceutil/trace.go:171","msg":"trace[1167045515] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"204.039038ms","start":"2026-09-18T22:56:02.116921Z","end":"2026-09-18T22:56:02.32096Z","steps":["trace[1167045515] 'process raft request' (duration: 203.56667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:02.321012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.564866ms","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-09-18T22:56:02.321144Z","caller":"traceutil/trace.go:171","msg":"trace[1751858832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1166; }","duration":"138.81208ms","start":"2026-09-18T22:56:02.182314Z","end":"2026-09-18T22:56:02.321126Z","steps":["trace[1751858832] 'agreement among raft nodes before linearized reading' (duration: 138.456734ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.316715Z","caller":"traceutil/trace.go:171","msg":"trace[1837355349] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"194.001956ms","start":"2026-09-18T22:56:07.122687Z","end":"2026-09-18T22:56:07.316689Z","steps":["trace[1837355349] 'process raft request' (duration: 193.196954ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.316767Z","caller":"traceutil/trace.go:171","msg":"trace[952434583] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"187.6782ms","start":"2026-09-18T22:56:07.129059Z","end":"2026-09-18T22:56:07.316738Z","steps":["trace[952434583] 'process raft request' (duration: 187.62349ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.316937Z","caller":"traceutil/trace.go:171","msg":"trace[2143387082] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"194.235599ms","start":"2026-09-18T22:56:07.122691Z","end":"2026-09-18T22:56:07.316927Z","steps":["trace[2143387082] 'process raft request' (duration: 193.916055ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.365294Z","caller":"traceutil/trace.go:171","msg":"trace[1948223695] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"228.972696ms","start":"2026-09-18T22:56:07.136292Z","end":"2026-09-18T22:56:07.365265Z","steps":["trace[1948223695] 'process raft request' (duration: 228.847154ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.483154Z","caller":"traceutil/trace.go:171","msg":"trace[989723986] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"161.210679ms","start":"2026-09-18T22:56:07.321902Z","end":"2026-09-18T22:56:07.483113Z","steps":["trace[989723986] 'process raft request' (duration: 151.30771ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.483245Z","caller":"traceutil/trace.go:171","msg":"trace[581945764] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"160.403787ms","start":"2026-09-18T22:56:07.32283Z","end":"2026-09-18T22:56:07.483234Z","steps":["trace[581945764] 'process raft request' (duration: 160.189254ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:07.483194Z","caller":"traceutil/trace.go:171","msg":"trace[384118053] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1214; }","duration":"154.927774ms","start":"2026-09-18T22:56:07.328248Z","end":"2026-09-18T22:56:07.483176Z","steps":["trace[384118053] 'process raft request' (duration: 154.863923ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:52.866712Z","caller":"traceutil/trace.go:171","msg":"trace[624546194] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"324.963013ms","start":"2026-09-18T22:56:52.541723Z","end":"2026-09-18T22:56:52.866686Z","steps":["trace[624546194] 'process raft request' (duration: 324.79134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:52.866861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:52.541694Z","time spent":"325.090126ms","remote":"127.0.0.1:44202","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-09-18T22:56:52.879706Z","caller":"traceutil/trace.go:171","msg":"trace[1863895235] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"335.455069ms","start":"2026-09-18T22:56:52.544205Z","end":"2026-09-18T22:56:52.87966Z","steps":["trace[1863895235] 'process raft request' (duration: 335.303317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:56:52.880119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:56:52.544183Z","time spent":"335.774897ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T22:56:53.339292Z","caller":"traceutil/trace.go:171","msg":"trace[2016613879] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"119.060004ms","start":"2026-09-18T22:56:53.220212Z","end":"2026-09-18T22:56:53.339272Z","steps":["trace[2016613879] 'read index received' (duration: 101.009703ms)","trace[2016613879] 'applied index is now lower than readState.Index' (duration: 18.049671ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:56:53.339506Z","caller":"traceutil/trace.go:171","msg":"trace[845065729] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"256.632215ms","start":"2026-09-18T22:56:53.082861Z","end":"2026-09-18T22:56:53.339494Z","steps":["trace[845065729] 'process raft request' (duration: 238.34692ms)","trace[845065729] 'compare' (duration: 17.988088ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:56:53.339758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.527172ms","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-09-18T22:56:53.339858Z","caller":"traceutil/trace.go:171","msg":"trace[583309696] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1855; }","duration":"119.670045ms","start":"2026-09-18T22:56:53.220161Z","end":"2026-09-18T22:56:53.339831Z","steps":["trace[583309696] 'agreement among raft nodes before linearized reading' (duration: 119.41671ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:56:53.345802Z","caller":"traceutil/trace.go:171","msg":"trace[357565605] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"124.356853ms","start":"2026-09-18T22:56:53.221417Z","end":"2026-09-18T22:56:53.345774Z","steps":["trace[357565605] 'process raft request' (duration: 124.146259ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:57:00.149973Z","caller":"traceutil/trace.go:171","msg":"trace[1208583188] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"104.891825ms","start":"2026-09-18T22:57:00.045048Z","end":"2026-09-18T22:57:00.14994Z","steps":["trace[1208583188] 'process raft request' (duration: 104.710401ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:57:07.027998Z","caller":"traceutil/trace.go:171","msg":"trace[379103319] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"165.608677ms","start":"2026-09-18T22:57:06.862362Z","end":"2026-09-18T22:57:07.027971Z","steps":["trace[379103319] 'process raft request' (duration: 165.383143ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:57:07.315815Z","caller":"traceutil/trace.go:171","msg":"trace[678883600] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"112.13825ms","start":"2026-09-18T22:57:07.203617Z","end":"2026-09-18T22:57:07.315755Z","steps":["trace[678883600] 'process raft request' (duration: 73.430614ms)","trace[678883600] 'compare' (duration: 38.544312ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:57:07.316123Z","caller":"traceutil/trace.go:171","msg":"trace[1510003783] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"112.483166ms","start":"2026-09-18T22:57:07.203627Z","end":"2026-09-18T22:57:07.31611Z","steps":["trace[1510003783] 'process raft request' (duration: 112.383384ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:57:33.341154Z","caller":"traceutil/trace.go:171","msg":"trace[1355061072] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"111.229286ms","start":"2026-09-18T22:57:33.229895Z","end":"2026-09-18T22:57:33.341124Z","steps":["trace[1355061072] 'read index received' (duration: 48.776839ms)","trace[1355061072] 'applied index is now lower than readState.Index' (duration: 62.451077ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:57:33.341608Z","caller":"traceutil/trace.go:171","msg":"trace[524451154] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"159.929743ms","start":"2026-09-18T22:57:33.181635Z","end":"2026-09-18T22:57:33.341565Z","steps":["trace[524451154] 'process raft request' (duration: 97.108838ms)","trace[524451154] 'compare' (duration: 62.146112ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:57:33.341664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.743547ms","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-09-18T22:57:33.341783Z","caller":"traceutil/trace.go:171","msg":"trace[1755014124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2117; }","duration":"111.91078ms","start":"2026-09-18T22:57:33.229851Z","end":"2026-09-18T22:57:33.341762Z","steps":["trace[1755014124] 'agreement among raft nodes before linearized reading' (duration: 111.557713ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:57:33.614704Z","caller":"traceutil/trace.go:171","msg":"trace[1514124036] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"125.354366ms","start":"2026-09-18T22:57:33.489315Z","end":"2026-09-18T22:57:33.61467Z","steps":["trace[1514124036] 'process raft request' (duration: 125.152432ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:57:34.505695Z","caller":"traceutil/trace.go:171","msg":"trace[209387266] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2207; }","duration":"169.588036ms","start":"2026-09-18T22:57:34.336073Z","end":"2026-09-18T22:57:34.505661Z","steps":["trace[209387266] 'read index received' (duration: 169.476024ms)","trace[209387266] 'applied index is now lower than readState.Index' (duration: 110.972µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:57:34.505776Z","caller":"traceutil/trace.go:171","msg":"trace[1984274330] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"252.510575ms","start":"2026-09-18T22:57:34.253236Z","end":"2026-09-18T22:57:34.505747Z","steps":["trace[1984274330] 'process raft request' (duration: 252.233489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:57:34.505912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.827151ms","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-09-18T22:57:34.506173Z","caller":"traceutil/trace.go:171","msg":"trace[1955751395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2122; }","duration":"169.911132ms","start":"2026-09-18T22:57:34.336022Z","end":"2026-09-18T22:57:34.505933Z","steps":["trace[1955751395] 'agreement among raft nodes before linearized reading' (duration: 169.77386ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:57:34.517602Z","caller":"traceutil/trace.go:171","msg":"trace[169400478] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"154.71701ms","start":"2026-09-18T22:57:34.36286Z","end":"2026-09-18T22:57:34.517577Z","steps":["trace[169400478] 'process raft request' (duration: 154.400064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:57:34.517641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.928142ms","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-09-18T22:57:34.517707Z","caller":"traceutil/trace.go:171","msg":"trace[1557160263] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2123; }","duration":"168.050694ms","start":"2026-09-18T22:57:34.349636Z","end":"2026-09-18T22:57:34.517686Z","steps":["trace[1557160263] 'agreement among raft nodes before linearized reading' (duration: 167.81947ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:57:34.717744Z","caller":"traceutil/trace.go:171","msg":"trace[1830965119] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2209; }","duration":"183.251615ms","start":"2026-09-18T22:57:34.534462Z","end":"2026-09-18T22:57:34.717714Z","steps":["trace[1830965119] 'read index received' (duration: 5.729767ms)","trace[1830965119] 'applied index is now lower than readState.Index' (duration: 177.520318ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T22:57:34.717835Z","caller":"traceutil/trace.go:171","msg":"trace[186207263] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"194.566648ms","start":"2026-09-18T22:57:34.523225Z","end":"2026-09-18T22:57:34.717791Z","steps":["trace[186207263] 'process raft request' (duration: 174.741141ms)","trace[186207263] 'compare' (duration: 19.600832ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:57:34.717973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.49624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T22:57:34.718055Z","caller":"traceutil/trace.go:171","msg":"trace[981738962] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2125; }","duration":"183.672844ms","start":"2026-09-18T22:57:34.534362Z","end":"2026-09-18T22:57:34.718034Z","steps":["trace[981738962] 'agreement among raft nodes before linearized reading' (duration: 183.4912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:59:03.641384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.429836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-09-18T22:59:03.641415Z","caller":"traceutil/trace.go:171","msg":"trace[1872754984] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"102.516451ms","start":"2026-09-18T22:59:03.538877Z","end":"2026-09-18T22:59:03.641394Z","steps":["trace[1872754984] 'process raft request' (duration: 102.147653ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T22:59:03.641451Z","caller":"traceutil/trace.go:171","msg":"trace[1714743265] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2697; }","duration":"266.517408ms","start":"2026-09-18T22:59:03.374925Z","end":"2026-09-18T22:59:03.641442Z","steps":["trace[1714743265] 'range keys from in-memory index tree' (duration: 266.218762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:59:35.121967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.736133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-18T22:59:35.122165Z","caller":"traceutil/trace.go:171","msg":"trace[229825938] linearizableReadLoop","detail":"{readStateIndex:2985; appliedIndex:2984; }","duration":"316.991628ms","start":"2026-09-18T22:59:34.805153Z","end":"2026-09-18T22:59:35.122145Z","steps":["trace[229825938] 'read index received' (duration: 28.801µs)","trace[229825938] 'applied index is now lower than readState.Index' (duration: 316.960827ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T22:59:35.122283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.09318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:59:35.122336Z","caller":"traceutil/trace.go:171","msg":"trace[1956867044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2871; }","duration":"317.225283ms","start":"2026-09-18T22:59:34.805098Z","end":"2026-09-18T22:59:35.122323Z","steps":["trace[1956867044] 'agreement among raft nodes before linearized reading' (duration: 317.10745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T22:59:35.122381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T22:59:34.805081Z","time spent":"317.291615ms","remote":"127.0.0.1:43940","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-09-18T22:59:35.122215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.240038ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T22:59:35.122741Z","caller":"traceutil/trace.go:171","msg":"trace[1170464683] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2871; }","duration":"113.79299ms","start":"2026-09-18T22:59:35.008853Z","end":"2026-09-18T22:59:35.122646Z","steps":["trace[1170464683] 'range keys from in-memory index tree' (duration: 113.222647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:12.494147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.326678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-18T23:04:12.494266Z","caller":"traceutil/trace.go:171","msg":"trace[150197119] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5033; }","duration":"118.588415ms","start":"2026-09-18T23:04:12.375658Z","end":"2026-09-18T23:04:12.494246Z","steps":["trace[150197119] 'range keys from in-memory index tree' (duration: 118.110614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:12.779564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.972351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:04:12.779665Z","caller":"traceutil/trace.go:171","msg":"trace[1101926358] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:5034; }","duration":"179.120654ms","start":"2026-09-18T23:04:12.600522Z","end":"2026-09-18T23:04:12.779643Z","steps":["trace[1101926358] 'count revisions from in-memory index tree' (duration: 178.819187ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:13.02686Z","caller":"traceutil/trace.go:171","msg":"trace[816581511] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5231; }","duration":"117.305705ms","start":"2026-09-18T23:04:12.909526Z","end":"2026-09-18T23:04:13.026832Z","steps":["trace[816581511] 'read index received' (duration: 117.054029ms)","trace[816581511] 'applied index is now lower than readState.Index' (duration: 250.496µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:13.026996Z","caller":"traceutil/trace.go:171","msg":"trace[389332796] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"143.532033ms","start":"2026-09-18T23:04:12.883452Z","end":"2026-09-18T23:04:13.026984Z","steps":["trace[389332796] 'process raft request' (duration: 143.208845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:13.027165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.624552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:04:13.027227Z","caller":"traceutil/trace.go:171","msg":"trace[1171297399] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5035; }","duration":"117.741435ms","start":"2026-09-18T23:04:12.909468Z","end":"2026-09-18T23:04:13.02721Z","steps":["trace[1171297399] 'agreement among raft nodes before linearized reading' (duration: 117.631542ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:19.771154Z","caller":"traceutil/trace.go:171","msg":"trace[56128231] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5332; }","duration":"246.015145ms","start":"2026-09-18T23:04:19.525115Z","end":"2026-09-18T23:04:19.77113Z","steps":["trace[56128231] 'read index received' (duration: 245.851852ms)","trace[56128231] 'applied index is now lower than readState.Index' (duration: 162.143µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:19.771216Z","caller":"traceutil/trace.go:171","msg":"trace[686644206] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"246.719293ms","start":"2026-09-18T23:04:19.524464Z","end":"2026-09-18T23:04:19.771183Z","steps":["trace[686644206] 'process raft request' (duration: 246.511068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:19.771337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.20355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2325d023-eb15-4845-accc-638e322f61ba\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-09-18T23:04:19.771373Z","caller":"traceutil/trace.go:171","msg":"trace[1598138567] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2325d023-eb15-4845-accc-638e322f61ba; range_end:; response_count:1; response_revision:5134; }","duration":"246.277302ms","start":"2026-09-18T23:04:19.525085Z","end":"2026-09-18T23:04:19.771362Z","steps":["trace[1598138567] 'agreement among raft nodes before linearized reading' (duration: 246.19193ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:19.801242Z","caller":"traceutil/trace.go:171","msg":"trace[270909707] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"272.776246ms","start":"2026-09-18T23:04:19.528433Z","end":"2026-09-18T23:04:19.80121Z","steps":["trace[270909707] 'process raft request' (duration: 272.598862ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:20.303066Z","caller":"traceutil/trace.go:171","msg":"trace[992778507] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"360.182187ms","start":"2026-09-18T23:04:19.942856Z","end":"2026-09-18T23:04:20.303038Z","steps":["trace[992778507] 'process raft request' (duration: 291.337909ms)","trace[992778507] 'compare' (duration: 68.714875ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:04:20.303206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:19.942843Z","time spent":"360.27916ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T23:04:20.303264Z","caller":"traceutil/trace.go:171","msg":"trace[1696541397] linearizableReadLoop","detail":"{readStateIndex:5344; appliedIndex:5342; }","duration":"356.466823ms","start":"2026-09-18T23:04:19.946777Z","end":"2026-09-18T23:04:20.303244Z","steps":["trace[1696541397] 'read index received' (duration: 12.772331ms)","trace[1696541397] 'applied index is now lower than readState.Index' (duration: 343.692992ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:04:20.303475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.696038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-18T23:04:20.30353Z","caller":"traceutil/trace.go:171","msg":"trace[984642268] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5145; }","duration":"356.77964ms","start":"2026-09-18T23:04:19.946736Z","end":"2026-09-18T23:04:20.303516Z","steps":["trace[984642268] 'agreement among raft nodes before linearized reading' (duration: 356.582775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:20.303578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:19.946722Z","time spent":"356.843712ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-18T23:04:26.307673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2829} {"level":"info","ts":"2026-09-18T23:04:26.397035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2829,"took":"88.078336ms","hash":3648093999,"current-db-size-bytes":50810880,"current-db-size":"51 MB","current-db-size-in-use-bytes":27049984,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-18T23:04:26.397085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3648093999,"revision":2829,"compact-revision":-1} {"level":"warn","ts":"2026-09-18T23:04:33.524017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.994199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:04:33.524328Z","caller":"traceutil/trace.go:171","msg":"trace[1317531871] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"432.861375ms","start":"2026-09-18T23:04:33.091426Z","end":"2026-09-18T23:04:33.524287Z","steps":["trace[1317531871] 'process raft request' (duration: 35.494809ms)","trace[1317531871] 'compare' (duration: 396.796014ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:33.524137Z","caller":"traceutil/trace.go:171","msg":"trace[1983009119] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"380.038213ms","start":"2026-09-18T23:04:33.14408Z","end":"2026-09-18T23:04:33.524118Z","steps":["trace[1983009119] 'read index received' (duration: 29.141µs)","trace[1983009119] 'applied index is now lower than readState.Index' (duration: 380.007202ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:04:33.52452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:33.091398Z","time spent":"432.997028ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T23:04:33.524533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.453842ms","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-09-18T23:04:33.524597Z","caller":"traceutil/trace.go:171","msg":"trace[1732329735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5321; }","duration":"380.520003ms","start":"2026-09-18T23:04:33.144059Z","end":"2026-09-18T23:04:33.524579Z","steps":["trace[1732329735] 'agreement among raft nodes before linearized reading' (duration: 380.359679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.524651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:33.144006Z","time spent":"380.632716ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-18T23:04:33.525068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.195369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-658c4f9664-c6vxm\" ","response":"range_response_count:1 size:11844"} {"level":"info","ts":"2026-09-18T23:04:33.525148Z","caller":"traceutil/trace.go:171","msg":"trace[407906606] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-658c4f9664-c6vxm; range_end:; response_count:1; response_revision:5320; }","duration":"443.367774ms","start":"2026-09-18T23:04:33.081747Z","end":"2026-09-18T23:04:33.525115Z","steps":["trace[407906606] 'range keys from in-memory index tree' (duration: 441.719477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.525192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:33.081722Z","time spent":"443.456425ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11868,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-658c4f9664-c6vxm\" "} {"level":"info","ts":"2026-09-18T23:04:33.668159Z","caller":"traceutil/trace.go:171","msg":"trace[1094381369] linearizableReadLoop","detail":"{readStateIndex:5529; appliedIndex:5528; }","duration":"143.63002ms","start":"2026-09-18T23:04:33.524495Z","end":"2026-09-18T23:04:33.668125Z","steps":["trace[1094381369] 'read index received' (duration: 100.066589ms)","trace[1094381369] 'applied index is now lower than readState.Index' (duration: 43.561941ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:33.668289Z","caller":"traceutil/trace.go:171","msg":"trace[772713202] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"283.349341ms","start":"2026-09-18T23:04:33.384848Z","end":"2026-09-18T23:04:33.668198Z","steps":["trace[772713202] 'process raft request' (duration: 239.733138ms)","trace[772713202] 'compare' (duration: 43.389028ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:04:33.6684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.087108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"warn","ts":"2026-09-18T23:04:33.668425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.167769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-09-18T23:04:33.668475Z","caller":"traceutil/trace.go:171","msg":"trace[1264795621] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5322; }","duration":"439.279321ms","start":"2026-09-18T23:04:33.229181Z","end":"2026-09-18T23:04:33.66846Z","steps":["trace[1264795621] 'agreement among raft nodes before linearized reading' (duration: 439.053346ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:33.668476Z","caller":"traceutil/trace.go:171","msg":"trace[857112659] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5322; }","duration":"190.21296ms","start":"2026-09-18T23:04:33.478242Z","end":"2026-09-18T23:04:33.668455Z","steps":["trace[857112659] 'agreement among raft nodes before linearized reading' (duration: 189.951455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.66851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:33.229155Z","time spent":"439.346382ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-09-18T23:04:33.668505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.080531ms","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-09-18T23:04:33.668563Z","caller":"traceutil/trace.go:171","msg":"trace[811087534] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5322; }","duration":"394.171503ms","start":"2026-09-18T23:04:33.274373Z","end":"2026-09-18T23:04:33.668544Z","steps":["trace[811087534] 'agreement among raft nodes before linearized reading' (duration: 394.02536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.668595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:33.274349Z","time spent":"394.237774ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-18T23:04:33.705823Z","caller":"traceutil/trace.go:171","msg":"trace[1699281301] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"136.333584ms","start":"2026-09-18T23:04:33.569468Z","end":"2026-09-18T23:04:33.705802Z","steps":["trace[1699281301] 'process raft request' (duration: 136.267192ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:33.705903Z","caller":"traceutil/trace.go:171","msg":"trace[1496649506] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"167.844431ms","start":"2026-09-18T23:04:33.538046Z","end":"2026-09-18T23:04:33.705891Z","steps":["trace[1496649506] 'process raft request' (duration: 167.633996ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:33.705825Z","caller":"traceutil/trace.go:171","msg":"trace[614763760] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"176.97339ms","start":"2026-09-18T23:04:33.528795Z","end":"2026-09-18T23:04:33.705769Z","steps":["trace[614763760] 'process raft request' (duration: 176.760635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.915608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.46173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:04:33.915844Z","caller":"traceutil/trace.go:171","msg":"trace[464379456] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"203.005192ms","start":"2026-09-18T23:04:33.712809Z","end":"2026-09-18T23:04:33.915815Z","steps":["trace[464379456] 'process raft request' (duration: 101.244035ms)","trace[464379456] 'compare' (duration: 101.335287ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:33.916027Z","caller":"traceutil/trace.go:171","msg":"trace[1201191742] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"202.88671ms","start":"2026-09-18T23:04:33.71312Z","end":"2026-09-18T23:04:33.916007Z","steps":["trace[1201191742] 'process raft request' (duration: 202.596433ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:33.916198Z","caller":"traceutil/trace.go:171","msg":"trace[1637457396] linearizableReadLoop","detail":"{readStateIndex:5536; appliedIndex:5533; }","duration":"192.256608ms","start":"2026-09-18T23:04:33.723923Z","end":"2026-09-18T23:04:33.91618Z","steps":["trace[1637457396] 'read index received' (duration: 90.157632ms)","trace[1637457396] 'applied index is now lower than readState.Index' (duration: 102.097786ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:33.916458Z","caller":"traceutil/trace.go:171","msg":"trace[1407709860] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"197.335953ms","start":"2026-09-18T23:04:33.7191Z","end":"2026-09-18T23:04:33.916436Z","steps":["trace[1407709860] 'process raft request' (duration: 196.807002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.916571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.232356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-09-18T23:04:33.91662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.853747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-09-18T23:04:33.916634Z","caller":"traceutil/trace.go:171","msg":"trace[567441218] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5329; }","duration":"206.325248ms","start":"2026-09-18T23:04:33.71029Z","end":"2026-09-18T23:04:33.916615Z","steps":["trace[567441218] 'agreement among raft nodes before linearized reading' (duration: 206.177985ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:33.916679Z","caller":"traceutil/trace.go:171","msg":"trace[1502410828] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5329; }","duration":"205.936459ms","start":"2026-09-18T23:04:33.710728Z","end":"2026-09-18T23:04:33.916664Z","steps":["trace[1502410828] 'agreement among raft nodes before linearized reading' (duration: 205.690584ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:33.925713Z","caller":"traceutil/trace.go:171","msg":"trace[2072273027] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"177.493881ms","start":"2026-09-18T23:04:33.748194Z","end":"2026-09-18T23:04:33.925688Z","steps":["trace[2072273027] 'process raft request' (duration: 177.349627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.926296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.408354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-09-18T23:04:33.926384Z","caller":"traceutil/trace.go:171","msg":"trace[2036406157] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5330; }","duration":"197.525027ms","start":"2026-09-18T23:04:33.728838Z","end":"2026-09-18T23:04:33.926363Z","steps":["trace[2036406157] 'agreement among raft nodes before linearized reading' (duration: 197.252761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.926659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.647141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:04:33.926707Z","caller":"traceutil/trace.go:171","msg":"trace[764047467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5330; }","duration":"122.747034ms","start":"2026-09-18T23:04:33.80395Z","end":"2026-09-18T23:04:33.926697Z","steps":["trace[764047467] 'agreement among raft nodes before linearized reading' (duration: 122.624312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:33.927306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.275857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-18T23:04:33.927373Z","caller":"traceutil/trace.go:171","msg":"trace[1199206073] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5330; }","duration":"177.356729ms","start":"2026-09-18T23:04:33.750001Z","end":"2026-09-18T23:04:33.927358Z","steps":["trace[1199206073] 'agreement among raft nodes before linearized reading' (duration: 176.977251ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.206546Z","caller":"traceutil/trace.go:171","msg":"trace[161188440] linearizableReadLoop","detail":"{readStateIndex:5539; appliedIndex:5537; }","duration":"273.554666ms","start":"2026-09-18T23:04:33.93296Z","end":"2026-09-18T23:04:34.206514Z","steps":["trace[161188440] 'read index received' (duration: 70.068705ms)","trace[161188440] 'applied index is now lower than readState.Index' (duration: 203.484991ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:34.206567Z","caller":"traceutil/trace.go:171","msg":"trace[712129619] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"273.785202ms","start":"2026-09-18T23:04:33.932724Z","end":"2026-09-18T23:04:34.206509Z","steps":["trace[712129619] 'process raft request' (duration: 272.7993ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.206765Z","caller":"traceutil/trace.go:171","msg":"trace[968095105] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"272.296948ms","start":"2026-09-18T23:04:33.934444Z","end":"2026-09-18T23:04:34.206741Z","steps":["trace[968095105] 'process raft request' (duration: 272.005602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:34.207005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.984535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"warn","ts":"2026-09-18T23:04:34.207023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.042888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18d68cc012d3ce78\" ","response":"range_response_count:1 size:746"} {"level":"info","ts":"2026-09-18T23:04:34.207051Z","caller":"traceutil/trace.go:171","msg":"trace[1567187523] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5334; }","duration":"273.052506ms","start":"2026-09-18T23:04:33.933985Z","end":"2026-09-18T23:04:34.207037Z","steps":["trace[1567187523] 'agreement among raft nodes before linearized reading' (duration: 272.891781ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.20705Z","caller":"traceutil/trace.go:171","msg":"trace[291502238] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"273.953667ms","start":"2026-09-18T23:04:33.933082Z","end":"2026-09-18T23:04:34.207035Z","steps":["trace[291502238] 'process raft request' (duration: 273.286391ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.207064Z","caller":"traceutil/trace.go:171","msg":"trace[312869731] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18d68cc012d3ce78; range_end:; response_count:1; response_revision:5334; }","duration":"274.1134ms","start":"2026-09-18T23:04:33.932937Z","end":"2026-09-18T23:04:34.207051Z","steps":["trace[312869731] 'agreement among raft nodes before linearized reading' (duration: 273.963856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:34.207136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.227892ms","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-09-18T23:04:34.207175Z","caller":"traceutil/trace.go:171","msg":"trace[240299297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5334; }","duration":"183.307574ms","start":"2026-09-18T23:04:34.023857Z","end":"2026-09-18T23:04:34.207165Z","steps":["trace[240299297] 'agreement among raft nodes before linearized reading' (duration: 183.159901ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.330103Z","caller":"traceutil/trace.go:171","msg":"trace[336117325] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"120.36522ms","start":"2026-09-18T23:04:34.209714Z","end":"2026-09-18T23:04:34.330079Z","steps":["trace[336117325] 'process raft request' (duration: 46.623292ms)","trace[336117325] 'compare' (duration: 73.531104ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:34.361334Z","caller":"traceutil/trace.go:171","msg":"trace[1787376884] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"147.828195ms","start":"2026-09-18T23:04:34.213454Z","end":"2026-09-18T23:04:34.361282Z","steps":["trace[1787376884] 'process raft request' (duration: 147.59643ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.361634Z","caller":"traceutil/trace.go:171","msg":"trace[1434036516] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"147.994699ms","start":"2026-09-18T23:04:34.213621Z","end":"2026-09-18T23:04:34.361616Z","steps":["trace[1434036516] 'process raft request' (duration: 147.58931ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.362327Z","caller":"traceutil/trace.go:171","msg":"trace[306762634] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"147.558539ms","start":"2026-09-18T23:04:34.214755Z","end":"2026-09-18T23:04:34.362314Z","steps":["trace[306762634] 'process raft request' (duration: 146.67065ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.363799Z","caller":"traceutil/trace.go:171","msg":"trace[1525218276] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"148.93184ms","start":"2026-09-18T23:04:34.214825Z","end":"2026-09-18T23:04:34.363757Z","steps":["trace[1525218276] 'process raft request' (duration: 147.413556ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.364089Z","caller":"traceutil/trace.go:171","msg":"trace[1104000864] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"149.066774ms","start":"2026-09-18T23:04:34.21501Z","end":"2026-09-18T23:04:34.364077Z","steps":["trace[1104000864] 'process raft request' (duration: 147.299073ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:34.364096Z","caller":"traceutil/trace.go:171","msg":"trace[365053091] linearizableReadLoop","detail":"{readStateIndex:5544; appliedIndex:5541; }","duration":"150.172159ms","start":"2026-09-18T23:04:34.213898Z","end":"2026-09-18T23:04:34.36407Z","steps":["trace[365053091] 'read index received' (duration: 42.451456ms)","trace[365053091] 'applied index is now lower than readState.Index' (duration: 107.719583ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:04:34.364331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.413884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-09-18T23:04:34.364371Z","caller":"traceutil/trace.go:171","msg":"trace[2123536998] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5340; }","duration":"150.499416ms","start":"2026-09-18T23:04:34.213861Z","end":"2026-09-18T23:04:34.364361Z","steps":["trace[2123536998] 'agreement among raft nodes before linearized reading' (duration: 150.321722ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:24.513046Z","caller":"traceutil/trace.go:171","msg":"trace[1759237738] transaction","detail":"{read_only:false; response_revision:6983; number_of_response:1; }","duration":"115.43299ms","start":"2026-09-18T23:08:24.397577Z","end":"2026-09-18T23:08:24.51301Z","steps":["trace[1759237738] 'process raft request' (duration: 115.061492ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:35.866052Z","caller":"traceutil/trace.go:171","msg":"trace[1782184088] linearizableReadLoop","detail":"{readStateIndex:7330; appliedIndex:7328; }","duration":"110.082152ms","start":"2026-09-18T23:08:35.755954Z","end":"2026-09-18T23:08:35.866036Z","steps":["trace[1782184088] 'read index received' (duration: 13.365107ms)","trace[1782184088] 'applied index is now lower than readState.Index' (duration: 96.716525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:08:35.86622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.240765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-09-18T23:08:35.866247Z","caller":"traceutil/trace.go:171","msg":"trace[1003123470] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:7066; }","duration":"110.302397ms","start":"2026-09-18T23:08:35.755933Z","end":"2026-09-18T23:08:35.866235Z","steps":["trace[1003123470] 'agreement among raft nodes before linearized reading' (duration: 110.160264ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:35.866371Z","caller":"traceutil/trace.go:171","msg":"trace[1146398901] transaction","detail":"{read_only:false; response_revision:7066; number_of_response:1; }","duration":"103.138968ms","start":"2026-09-18T23:08:35.763226Z","end":"2026-09-18T23:08:35.866365Z","steps":["trace[1146398901] 'process raft request' (duration: 102.77077ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:35.866541Z","caller":"traceutil/trace.go:171","msg":"trace[1699061014] transaction","detail":"{read_only:false; response_revision:7065; number_of_response:1; }","duration":"114.575003ms","start":"2026-09-18T23:08:35.751961Z","end":"2026-09-18T23:08:35.866536Z","steps":["trace[1699061014] 'process raft request' (duration: 113.952809ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:37.609215Z","caller":"traceutil/trace.go:171","msg":"trace[2141145915] transaction","detail":"{read_only:false; response_revision:7091; number_of_response:1; }","duration":"116.903794ms","start":"2026-09-18T23:08:37.492289Z","end":"2026-09-18T23:08:37.609193Z","steps":["trace[2141145915] 'process raft request' (duration: 74.202583ms)","trace[2141145915] 'compare' (duration: 42.610599ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:08:37.610407Z","caller":"traceutil/trace.go:171","msg":"trace[245518006] transaction","detail":"{read_only:false; response_revision:7092; number_of_response:1; }","duration":"100.863707ms","start":"2026-09-18T23:08:37.509528Z","end":"2026-09-18T23:08:37.610392Z","steps":["trace[245518006] 'process raft request' (duration: 100.635362ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:38.216338Z","caller":"traceutil/trace.go:171","msg":"trace[1548990589] transaction","detail":"{read_only:false; response_revision:7101; number_of_response:1; }","duration":"102.683739ms","start":"2026-09-18T23:08:38.113604Z","end":"2026-09-18T23:08:38.216288Z","steps":["trace[1548990589] 'process raft request' (duration: 51.772515ms)","trace[1548990589] 'compare' (duration: 50.72303ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:08:38.812577Z","caller":"traceutil/trace.go:171","msg":"trace[1350785748] transaction","detail":"{read_only:false; response_revision:7110; number_of_response:1; }","duration":"217.70246ms","start":"2026-09-18T23:08:38.594793Z","end":"2026-09-18T23:08:38.812495Z","steps":["trace[1350785748] 'process raft request' (duration: 180.205314ms)","trace[1350785748] 'compare' (duration: 36.962635ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:08:38.812823Z","caller":"traceutil/trace.go:171","msg":"trace[2092535757] transaction","detail":"{read_only:false; response_revision:7111; number_of_response:1; }","duration":"132.294877ms","start":"2026-09-18T23:08:38.680496Z","end":"2026-09-18T23:08:38.812791Z","steps":["trace[2092535757] 'process raft request' (duration: 131.724364ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:38.962048Z","caller":"traceutil/trace.go:171","msg":"trace[587160329] transaction","detail":"{read_only:false; response_revision:7113; number_of_response:1; }","duration":"118.334476ms","start":"2026-09-18T23:08:38.843684Z","end":"2026-09-18T23:08:38.962018Z","steps":["trace[587160329] 'process raft request' (duration: 118.123612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:41.539335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.589435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T23:08:41.539457Z","caller":"traceutil/trace.go:171","msg":"trace[352674645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7126; }","duration":"172.755588ms","start":"2026-09-18T23:08:41.366683Z","end":"2026-09-18T23:08:41.539438Z","steps":["trace[352674645] 'range keys from in-memory index tree' (duration: 172.433531ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:41.735277Z","caller":"traceutil/trace.go:171","msg":"trace[1165151608] transaction","detail":"{read_only:false; response_revision:7128; number_of_response:1; }","duration":"122.206113ms","start":"2026-09-18T23:08:41.61305Z","end":"2026-09-18T23:08:41.735256Z","steps":["trace[1165151608] 'process raft request' (duration: 121.891026ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:42.990327Z","caller":"traceutil/trace.go:171","msg":"trace[1319412514] transaction","detail":"{read_only:false; response_revision:7134; number_of_response:1; }","duration":"116.284411ms","start":"2026-09-18T23:08:42.874025Z","end":"2026-09-18T23:08:42.99031Z","steps":["trace[1319412514] 'process raft request' (duration: 116.178979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:48.35481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.481852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:08:48.354899Z","caller":"traceutil/trace.go:171","msg":"trace[1585695368] linearizableReadLoop","detail":"{readStateIndex:7428; appliedIndex:7427; }","duration":"506.178842ms","start":"2026-09-18T23:08:47.848706Z","end":"2026-09-18T23:08:48.354885Z","steps":["trace[1585695368] 'read index received' (duration: 173.539748ms)","trace[1585695368] 'applied index is now lower than readState.Index' (duration: 332.637754ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:08:48.355232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.971519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:08:48.35527Z","caller":"traceutil/trace.go:171","msg":"trace[1387296096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:7159; }","duration":"201.05322ms","start":"2026-09-18T23:08:48.154206Z","end":"2026-09-18T23:08:48.355259Z","steps":["trace[1387296096] 'agreement among raft nodes before linearized reading' (duration: 200.964479ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:48.354971Z","caller":"traceutil/trace.go:171","msg":"trace[1662965948] transaction","detail":"{read_only:false; response_revision:7159; number_of_response:1; }","duration":"539.093936ms","start":"2026-09-18T23:08:47.815868Z","end":"2026-09-18T23:08:48.354962Z","steps":["trace[1662965948] 'process raft request' (duration: 206.37367ms)","trace[1662965948] 'compare' (duration: 332.171984ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:08:48.355745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:47.81583Z","time spent":"539.746149ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T23:08:48.355716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.97557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-18T23:08:48.355258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.136251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T23:08:48.355899Z","caller":"traceutil/trace.go:171","msg":"trace[79215963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7159; }","duration":"507.209575ms","start":"2026-09-18T23:08:47.848665Z","end":"2026-09-18T23:08:48.355874Z","steps":["trace[79215963] 'agreement among raft nodes before linearized reading' (duration: 506.732835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:48.356048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:47.848643Z","time spent":"507.38819ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T23:08:48.356287Z","caller":"traceutil/trace.go:171","msg":"trace[132887140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7159; }","duration":"366.855307ms","start":"2026-09-18T23:08:47.989052Z","end":"2026-09-18T23:08:48.355907Z","steps":["trace[132887140] 'agreement among raft nodes before linearized reading' (duration: 365.994137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:48.35641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.664227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T23:08:48.360307Z","caller":"traceutil/trace.go:171","msg":"trace[391089259] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:7159; }","duration":"350.972823ms","start":"2026-09-18T23:08:48.009312Z","end":"2026-09-18T23:08:48.360284Z","steps":["trace[391089259] 'agreement among raft nodes before linearized reading' (duration: 346.030022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:48.360478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:48.009294Z","time spent":"351.171427ms","remote":"127.0.0.1:44316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":26,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-09-18T23:08:48.36038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:47.989034Z","time spent":"371.195724ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T23:08:49.277953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.940132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:08:49.278102Z","caller":"traceutil/trace.go:171","msg":"trace[357385983] linearizableReadLoop","detail":"{readStateIndex:7430; appliedIndex:7428; }","duration":"829.55216ms","start":"2026-09-18T23:08:48.448536Z","end":"2026-09-18T23:08:49.278089Z","steps":["trace[357385983] 'read index received' (duration: 116.507946ms)","trace[357385983] 'applied index is now lower than readState.Index' (duration: 713.043074ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:08:49.279375Z","caller":"traceutil/trace.go:171","msg":"trace[714484568] transaction","detail":"{read_only:false; response_revision:7160; number_of_response:1; }","duration":"913.938801ms","start":"2026-09-18T23:08:48.365421Z","end":"2026-09-18T23:08:49.27936Z","steps":["trace[714484568] 'process raft request' (duration: 199.517287ms)","trace[714484568] 'compare' (duration: 711.794786ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:08:49.279443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:48.365396Z","time spent":"914.013532ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T23:08:49.279581Z","caller":"traceutil/trace.go:171","msg":"trace[701987856] transaction","detail":"{read_only:false; response_revision:7161; number_of_response:1; }","duration":"914.140205ms","start":"2026-09-18T23:08:48.36543Z","end":"2026-09-18T23:08:49.279571Z","steps":["trace[701987856] 'process raft request' (duration: 912.610381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:49.279633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:48.365398Z","time spent":"914.208957ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T23:08:49.280694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.118908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T23:08:49.280966Z","caller":"traceutil/trace.go:171","msg":"trace[31758433] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:7161; }","duration":"832.296521ms","start":"2026-09-18T23:08:48.448472Z","end":"2026-09-18T23:08:49.280769Z","steps":["trace[31758433] 'agreement among raft nodes before linearized reading' (duration: 830.946721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:49.281075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:48.448454Z","time spent":"832.599168ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-09-18T23:08:49.779952Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1122135963840716884,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T23:08:50.146827Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.171636557s","expected-duration":"1s"} {"level":"info","ts":"2026-09-18T23:08:50.147257Z","caller":"traceutil/trace.go:171","msg":"trace[419436890] transaction","detail":"{read_only:false; response_revision:7162; number_of_response:1; }","duration":"1.172146328s","start":"2026-09-18T23:08:48.975096Z","end":"2026-09-18T23:08:50.147242Z","steps":["trace[419436890] 'process raft request' (duration: 1.172016535s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.147717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:48.975074Z","time spent":"1.17224045s","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T23:08:50.257872Z","caller":"traceutil/trace.go:171","msg":"trace[506592153] transaction","detail":"{read_only:false; response_revision:7163; number_of_response:1; }","duration":"1.212697311s","start":"2026-09-18T23:08:49.045153Z","end":"2026-09-18T23:08:50.25785Z","steps":["trace[506592153] 'process raft request' (duration: 1.129971087s)","trace[506592153] 'compare' (duration: 82.420037ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:08:50.257975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.045132Z","time spent":"1.212787964s","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T23:08:50.258148Z","caller":"traceutil/trace.go:171","msg":"trace[2070069562] transaction","detail":"{read_only:false; response_revision:7164; number_of_response:1; }","duration":"635.623899ms","start":"2026-09-18T23:08:49.622516Z","end":"2026-09-18T23:08:50.25814Z","steps":["trace[2070069562] 'process raft request' (duration: 635.140068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.258192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.622483Z","time spent":"635.686951ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T23:08:50.2583Z","caller":"traceutil/trace.go:171","msg":"trace[774705883] linearizableReadLoop","detail":"{readStateIndex:7433; appliedIndex:7430; }","duration":"979.192798ms","start":"2026-09-18T23:08:49.279099Z","end":"2026-09-18T23:08:50.258291Z","steps":["trace[774705883] 'read index received' (duration: 867.966288ms)","trace[774705883] 'applied index is now lower than readState.Index' (duration: 111.22594ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:08:50.258357Z","caller":"traceutil/trace.go:171","msg":"trace[1897366726] transaction","detail":"{read_only:false; response_revision:7165; number_of_response:1; }","duration":"447.631529ms","start":"2026-09-18T23:08:49.810716Z","end":"2026-09-18T23:08:50.258347Z","steps":["trace[1897366726] 'process raft request' (duration: 447.107806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.258395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.8107Z","time spent":"447.67384ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T23:08:50.258574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049899554s","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-09-18T23:08:50.258603Z","caller":"traceutil/trace.go:171","msg":"trace[373092640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7165; }","duration":"1.049965905s","start":"2026-09-18T23:08:49.208631Z","end":"2026-09-18T23:08:50.258597Z","steps":["trace[373092640] 'agreement among raft nodes before linearized reading' (duration: 1.049808562s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.258623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.208611Z","time spent":"1.050007016s","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-18T23:08:50.258733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516648278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107267"} {"level":"info","ts":"2026-09-18T23:08:50.258761Z","caller":"traceutil/trace.go:171","msg":"trace[935722786] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:7165; }","duration":"1.51671132s","start":"2026-09-18T23:08:48.74204Z","end":"2026-09-18T23:08:50.258752Z","steps":["trace[935722786] 'agreement among raft nodes before linearized reading' (duration: 1.516629558s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.258733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498871181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T23:08:50.258814Z","caller":"traceutil/trace.go:171","msg":"trace[680608336] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:7165; }","duration":"1.498990884s","start":"2026-09-18T23:08:48.759809Z","end":"2026-09-18T23:08:50.2588Z","steps":["trace[680608336] 'agreement among raft nodes before linearized reading' (duration: 1.498771509s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.258867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:48.759753Z","time spent":"1.499087706s","remote":"127.0.0.1:44076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":4,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-09-18T23:08:50.25878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:48.742021Z","time spent":"1.51675451s","remote":"127.0.0.1:44034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":2,"response size":107291,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"warn","ts":"2026-09-18T23:08:50.25918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.678414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.82\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-18T23:08:50.259228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.085184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T23:08:50.259235Z","caller":"traceutil/trace.go:171","msg":"trace[1736505710] range","detail":"{range_begin:/registry/masterleases/162.253.55.82; range_end:; response_count:1; response_revision:7165; }","duration":"510.770346ms","start":"2026-09-18T23:08:49.748452Z","end":"2026-09-18T23:08:50.259222Z","steps":["trace[1736505710] 'agreement among raft nodes before linearized reading' (duration: 510.610953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.259264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.748438Z","time spent":"510.820107ms","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.82\" "} {"level":"info","ts":"2026-09-18T23:08:50.259402Z","caller":"traceutil/trace.go:171","msg":"trace[342164407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7165; }","duration":"638.284849ms","start":"2026-09-18T23:08:49.621107Z","end":"2026-09-18T23:08:50.259392Z","steps":["trace[342164407] 'agreement among raft nodes before linearized reading' (duration: 638.052344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.259444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.837462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:08:50.259469Z","caller":"traceutil/trace.go:171","msg":"trace[1678847572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7165; }","duration":"455.885933ms","start":"2026-09-18T23:08:49.803576Z","end":"2026-09-18T23:08:50.259462Z","steps":["trace[1678847572] 'agreement among raft nodes before linearized reading' (duration: 455.847583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.259502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.803561Z","time spent":"455.920264ms","remote":"127.0.0.1:43940","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-09-18T23:08:50.259578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.621091Z","time spent":"638.3331ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-18T23:08:50.259587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.851195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-18T23:08:50.259612Z","caller":"traceutil/trace.go:171","msg":"trace[1879315114] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7165; }","duration":"871.899896ms","start":"2026-09-18T23:08:49.387705Z","end":"2026-09-18T23:08:50.259605Z","steps":["trace[1879315114] 'agreement among raft nodes before linearized reading' (duration: 871.831624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.259635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.387686Z","time spent":"871.943567ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T23:08:50.25979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.609634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T23:08:50.25982Z","caller":"traceutil/trace.go:171","msg":"trace[625827643] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:7165; }","duration":"930.683876ms","start":"2026-09-18T23:08:49.329127Z","end":"2026-09-18T23:08:50.259811Z","steps":["trace[625827643] 'agreement among raft nodes before linearized reading' (duration: 929.764256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:50.25984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:08:49.329108Z","time spent":"930.727248ms","remote":"127.0.0.1:56600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-09-18T23:09:26.315332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5243} {"level":"info","ts":"2026-09-18T23:09:26.428756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5243,"took":"110.904365ms","hash":1375782414,"current-db-size-bytes":50810880,"current-db-size":"51 MB","current-db-size-in-use-bytes":19329024,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T23:09:26.428829Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1375782414,"revision":5243,"compact-revision":2829} {"level":"info","ts":"2026-09-18T23:10:36.993331Z","caller":"traceutil/trace.go:171","msg":"trace[1712168388] transaction","detail":"{read_only:false; response_revision:7729; number_of_response:1; }","duration":"104.442207ms","start":"2026-09-18T23:10:36.888852Z","end":"2026-09-18T23:10:36.993294Z","steps":["trace[1712168388] 'process raft request' (duration: 104.225961ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:11:31.628493Z","caller":"traceutil/trace.go:171","msg":"trace[333768860] transaction","detail":"{read_only:false; response_revision:8008; number_of_response:1; }","duration":"102.464505ms","start":"2026-09-18T23:11:31.526006Z","end":"2026-09-18T23:11:31.62847Z","steps":["trace[333768860] 'process raft request' (duration: 73.68012ms)","trace[333768860] 'compare' (duration: 28.580291ms)"],"step_count":2}