{"level":"warn","ts":"2026-04-11T17:40:47.700965Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:40:47.701104Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.188:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.188:2380","--initial-cluster=instance=https://199.19.213.188:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.188:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.188:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-11T17:40:47.702116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:40:47.702236Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.188:2380"]} {"level":"info","ts":"2026-04-11T17:40:47.702319Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-11T17:40:47.703273Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"]} {"level":"info","ts":"2026-04-11T17:40:47.703442Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.188:2380"],"listen-peer-urls":["https://199.19.213.188:2380"],"advertise-client-urls":["https://199.19.213.188:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.188:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-11T17:40:47.707404Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.560745ms"} {"level":"info","ts":"2026-04-11T17:40:47.714377Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"201fe5d2d7e0161a","cluster-id":"92c0fc3a33fabd38"} {"level":"info","ts":"2026-04-11T17:40:47.714576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:40:47.71471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became follower at term 0"} {"level":"info","ts":"2026-04-11T17:40:47.714831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 201fe5d2d7e0161a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:40:47.714907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became follower at term 1"} {"level":"info","ts":"2026-04-11T17:40:47.715013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=(2314821427219404314)"} {"level":"warn","ts":"2026-04-11T17:40:47.721048Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:40:47.72403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:40:47.72554Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-11T17:40:47.728592Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"201fe5d2d7e0161a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:40:47.728767Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:40:47.728849Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:40:47.728857Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:40:47.729146Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"201fe5d2d7e0161a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:40:47.732671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=(2314821427219404314)"} {"level":"info","ts":"2026-04-11T17:40:47.7328Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92c0fc3a33fabd38","local-member-id":"201fe5d2d7e0161a","added-peer-id":"201fe5d2d7e0161a","added-peer-peer-urls":["https://199.19.213.188:2380"]} {"level":"info","ts":"2026-04-11T17:40:47.737125Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-11T17:40:47.737288Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.188:2380"} {"level":"info","ts":"2026-04-11T17:40:47.73735Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.188:2380"} {"level":"info","ts":"2026-04-11T17:40:47.738131Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"201fe5d2d7e0161a","initial-advertise-peer-urls":["https://199.19.213.188:2380"],"listen-peer-urls":["https://199.19.213.188:2380"],"advertise-client-urls":["https://199.19.213.188:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:40:47.738193Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:40:48.416286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:40:48.416342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:40:48.416386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a received MsgPreVoteResp from 201fe5d2d7e0161a at term 1"} {"level":"info","ts":"2026-04-11T17:40:48.416405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:40:48.416422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a received MsgVoteResp from 201fe5d2d7e0161a at term 2"} {"level":"info","ts":"2026-04-11T17:40:48.416438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became leader at term 2"} {"level":"info","ts":"2026-04-11T17:40:48.416457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 201fe5d2d7e0161a elected leader 201fe5d2d7e0161a at term 2"} {"level":"info","ts":"2026-04-11T17:40:48.417568Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:40:48.418427Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"201fe5d2d7e0161a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.188:2379]}","request-path":"/0/members/201fe5d2d7e0161a/attributes","cluster-id":"92c0fc3a33fabd38","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:40:48.418465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:40:48.418616Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:40:48.418719Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:40:48.418789Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:40:48.419128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92c0fc3a33fabd38","local-member-id":"201fe5d2d7e0161a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:40:48.419231Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:40:48.419263Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:40:48.421484Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.188:2379"} {"level":"info","ts":"2026-04-11T17:40:48.422566Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:40:52.937621Z","caller":"traceutil/trace.go:171","msg":"trace[148399679] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"151.074097ms","start":"2026-04-11T17:40:52.786519Z","end":"2026-04-11T17:40:52.937593Z","steps":["trace[148399679] 'process raft request' (duration: 150.908733ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:53.08177Z","caller":"traceutil/trace.go:171","msg":"trace[570468721] linearizableReadLoop","detail":"{readStateIndex:228; appliedIndex:226; }","duration":"242.648029ms","start":"2026-04-11T17:40:52.839083Z","end":"2026-04-11T17:40:53.081731Z","steps":["trace[570468721] 'read index received' (duration: 98.353952ms)","trace[570468721] 'applied index is now lower than readState.Index' (duration: 144.292617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:40:53.082001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.845134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:40:53.082067Z","caller":"traceutil/trace.go:171","msg":"trace[1261961866] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:222; }","duration":"243.019739ms","start":"2026-04-11T17:40:52.839034Z","end":"2026-04-11T17:40:53.082054Z","steps":["trace[1261961866] 'agreement among raft nodes before linearized reading' (duration: 242.837704ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:53.086567Z","caller":"traceutil/trace.go:171","msg":"trace[1394758213] transaction","detail":"{read_only:false; number_of_response:0; response_revision:222; }","duration":"145.805587ms","start":"2026-04-11T17:40:52.940727Z","end":"2026-04-11T17:40:53.086533Z","steps":["trace[1394758213] 'process raft request' (duration: 145.5608ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:53.086635Z","caller":"traceutil/trace.go:171","msg":"trace[2071612137] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"143.67077ms","start":"2026-04-11T17:40:52.942945Z","end":"2026-04-11T17:40:53.086616Z","steps":["trace[2071612137] 'process raft request' (duration: 143.450484ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:53.086711Z","caller":"traceutil/trace.go:171","msg":"trace[268903366] transaction","detail":"{read_only:false; number_of_response:0; response_revision:223; }","duration":"131.384233ms","start":"2026-04-11T17:40:52.95529Z","end":"2026-04-11T17:40:53.086675Z","steps":["trace[268903366] 'process raft request' (duration: 131.237049ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:53.086797Z","caller":"traceutil/trace.go:171","msg":"trace[870342300] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"131.035313ms","start":"2026-04-11T17:40:52.955707Z","end":"2026-04-11T17:40:53.086742Z","steps":["trace[870342300] 'process raft request' (duration: 130.850528ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:53.086848Z","caller":"traceutil/trace.go:171","msg":"trace[2046845601] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"129.934864ms","start":"2026-04-11T17:40:52.956865Z","end":"2026-04-11T17:40:53.0868Z","steps":["trace[2046845601] 'process raft request' (duration: 129.759189ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:53.087086Z","caller":"traceutil/trace.go:171","msg":"trace[305617428] transaction","detail":"{read_only:false; number_of_response:0; response_revision:225; }","duration":"130.128169ms","start":"2026-04-11T17:40:52.956939Z","end":"2026-04-11T17:40:53.087067Z","steps":["trace[305617428] 'process raft request' (duration: 129.752589ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:40:53.087334Z","caller":"traceutil/trace.go:171","msg":"trace[2061428108] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"130.021356ms","start":"2026-04-11T17:40:52.957293Z","end":"2026-04-11T17:40:53.087314Z","steps":["trace[2061428108] 'process raft request' (duration: 129.8219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:40:53.087724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.603957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-11T17:40:53.087765Z","caller":"traceutil/trace.go:171","msg":"trace[1405919429] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:226; }","duration":"248.70285ms","start":"2026-04-11T17:40:52.839051Z","end":"2026-04-11T17:40:53.087754Z","steps":["trace[1405919429] 'agreement among raft nodes before linearized reading' (duration: 248.529966ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:18.279672Z","caller":"traceutil/trace.go:171","msg":"trace[495460047] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"157.911088ms","start":"2026-04-11T17:41:18.121737Z","end":"2026-04-11T17:41:18.279649Z","steps":["trace[495460047] 'process raft request' (duration: 157.557406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:19.137481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.693833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:41:19.137784Z","caller":"traceutil/trace.go:171","msg":"trace[1007224260] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"314.426589ms","start":"2026-04-11T17:41:18.823334Z","end":"2026-04-11T17:41:19.137761Z","steps":["trace[1007224260] 'process raft request' (duration: 52.854974ms)","trace[1007224260] 'compare' (duration: 260.118343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:19.137858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:18.8233Z","time spent":"314.528952ms","remote":"127.0.0.1:59716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:19.151628Z","caller":"traceutil/trace.go:171","msg":"trace[96042448] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"280.208874ms","start":"2026-04-11T17:41:18.871387Z","end":"2026-04-11T17:41:19.151596Z","steps":["trace[96042448] 'process raft request' (duration: 279.893953ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:41:19.156078Z","caller":"traceutil/trace.go:171","msg":"trace[302515942] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"275.630618ms","start":"2026-04-11T17:41:18.880424Z","end":"2026-04-11T17:41:19.156054Z","steps":["trace[302515942] 'process raft request' (duration: 275.454322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:25.344556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.058725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:41:25.345221Z","caller":"traceutil/trace.go:171","msg":"trace[2067531851] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"358.730525ms","start":"2026-04-11T17:41:24.986401Z","end":"2026-04-11T17:41:25.345132Z","steps":["trace[2067531851] 'process raft request' (duration: 87.949935ms)","trace[2067531851] 'compare' (duration: 269.835807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:41:25.34533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:24.986377Z","time spent":"358.919272ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:41:25.374427Z","caller":"traceutil/trace.go:171","msg":"trace[1010585734] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"379.446512ms","start":"2026-04-11T17:41:24.994921Z","end":"2026-04-11T17:41:25.374367Z","steps":["trace[1010585734] 'process raft request' (duration: 379.128731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:41:25.374944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:41:24.994887Z","time spent":"379.66697ms","remote":"127.0.0.1:59850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:41:48.290779Z","caller":"traceutil/trace.go:171","msg":"trace[1493836815] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"134.758796ms","start":"2026-04-11T17:41:48.15599Z","end":"2026-04-11T17:41:48.290749Z","steps":["trace[1493836815] 'process raft request' (duration: 134.55443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:09.100435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.311057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:43:09.100523Z","caller":"traceutil/trace.go:171","msg":"trace[1553150957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1833; }","duration":"251.488592ms","start":"2026-04-11T17:43:08.849017Z","end":"2026-04-11T17:43:09.100506Z","steps":["trace[1553150957] 'range keys from in-memory index tree' (duration: 251.077471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:09.1007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.32395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-11T17:43:09.100793Z","caller":"traceutil/trace.go:171","msg":"trace[1904557304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1833; }","duration":"115.451734ms","start":"2026-04-11T17:43:08.985318Z","end":"2026-04-11T17:43:09.10077Z","steps":["trace[1904557304] 'range keys from in-memory index tree' (duration: 114.814895ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.294445Z","caller":"traceutil/trace.go:171","msg":"trace[2011631015] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"104.933456ms","start":"2026-04-11T17:43:09.189478Z","end":"2026-04-11T17:43:09.294411Z","steps":["trace[2011631015] 'read index received' (duration: 25.051741ms)","trace[2011631015] 'applied index is now lower than readState.Index' (duration: 79.880435ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:43:09.294712Z","caller":"traceutil/trace.go:171","msg":"trace[1277625067] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"112.019954ms","start":"2026-04-11T17:43:09.182555Z","end":"2026-04-11T17:43:09.294575Z","steps":["trace[1277625067] 'process raft request' (duration: 32.075887ms)","trace[1277625067] 'compare' (duration: 79.647638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:43:09.295026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.541964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-11T17:43:09.295101Z","caller":"traceutil/trace.go:171","msg":"trace[1097516792] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1837; }","duration":"105.676908ms","start":"2026-04-11T17:43:09.189404Z","end":"2026-04-11T17:43:09.295081Z","steps":["trace[1097516792] 'agreement among raft nodes before linearized reading' (duration: 105.543264ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:49.428401Z","caller":"traceutil/trace.go:171","msg":"trace[615076374] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"101.869979ms","start":"2026-04-11T17:43:49.326506Z","end":"2026-04-11T17:43:49.428376Z","steps":["trace[615076374] 'process raft request' (duration: 101.688674ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:49.822085Z","caller":"traceutil/trace.go:171","msg":"trace[1895826918] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2195; }","duration":"171.413214ms","start":"2026-04-11T17:43:49.650633Z","end":"2026-04-11T17:43:49.822046Z","steps":["trace[1895826918] 'read index received' (duration: 110.966598ms)","trace[1895826918] 'applied index is now lower than readState.Index' (duration: 60.444976ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:43:49.822428Z","caller":"traceutil/trace.go:171","msg":"trace[898355214] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"277.662908ms","start":"2026-04-11T17:43:49.54459Z","end":"2026-04-11T17:43:49.822253Z","steps":["trace[898355214] 'process raft request' (duration: 216.952415ms)","trace[898355214] 'compare' (duration: 60.196299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:43:49.822711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.014981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127609"} {"level":"info","ts":"2026-04-11T17:43:49.8228Z","caller":"traceutil/trace.go:171","msg":"trace[969886304] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2113; }","duration":"172.200427ms","start":"2026-04-11T17:43:49.650581Z","end":"2026-04-11T17:43:49.822781Z","steps":["trace[969886304] 'agreement among raft nodes before linearized reading' (duration: 171.861648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:49.824871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.332802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-11T17:43:49.824968Z","caller":"traceutil/trace.go:171","msg":"trace[509301700] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2114; }","duration":"171.486896ms","start":"2026-04-11T17:43:49.653458Z","end":"2026-04-11T17:43:49.824945Z","steps":["trace[509301700] 'agreement among raft nodes before linearized reading' (duration: 171.27238ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:49.826115Z","caller":"traceutil/trace.go:171","msg":"trace[352000828] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"141.820029ms","start":"2026-04-11T17:43:49.684277Z","end":"2026-04-11T17:43:49.826097Z","steps":["trace[352000828] 'process raft request' (duration: 140.251214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:45:24.270438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.295153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-11T17:45:24.27053Z","caller":"traceutil/trace.go:171","msg":"trace[1225677324] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2722; }","duration":"145.421376ms","start":"2026-04-11T17:45:24.125089Z","end":"2026-04-11T17:45:24.27051Z","steps":["trace[1225677324] 'range keys from in-memory index tree' (duration: 145.132878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.641618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.595062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:49:39.641973Z","caller":"traceutil/trace.go:171","msg":"trace[999281397] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"294.851788ms","start":"2026-04-11T17:49:39.347062Z","end":"2026-04-11T17:49:39.641914Z","steps":["trace[999281397] 'process raft request' (duration: 136.788294ms)","trace[999281397] 'compare' (duration: 157.275644ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:39.642297Z","caller":"traceutil/trace.go:171","msg":"trace[2019171831] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"294.966681ms","start":"2026-04-11T17:49:39.34731Z","end":"2026-04-11T17:49:39.642277Z","steps":["trace[2019171831] 'process raft request' (duration: 294.464968ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.642726Z","caller":"traceutil/trace.go:171","msg":"trace[1067292075] linearizableReadLoop","detail":"{readStateIndex:5008; appliedIndex:5005; }","duration":"293.696098ms","start":"2026-04-11T17:49:39.348949Z","end":"2026-04-11T17:49:39.642645Z","steps":["trace[1067292075] 'read index received' (duration: 15.896002ms)","trace[1067292075] 'applied index is now lower than readState.Index' (duration: 277.798366ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:39.642854Z","caller":"traceutil/trace.go:171","msg":"trace[1037056557] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"284.028643ms","start":"2026-04-11T17:49:39.358812Z","end":"2026-04-11T17:49:39.642841Z","steps":["trace[1037056557] 'process raft request' (duration: 283.676694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.64305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.074458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-04-11T17:49:39.644761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.344258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-11T17:49:39.645125Z","caller":"traceutil/trace.go:171","msg":"trace[2011606536] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4819; }","duration":"137.753739ms","start":"2026-04-11T17:49:39.507352Z","end":"2026-04-11T17:49:39.645106Z","steps":["trace[2011606536] 'agreement among raft nodes before linearized reading' (duration: 135.697197ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.642809Z","caller":"traceutil/trace.go:171","msg":"trace[412082375] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"294.53744ms","start":"2026-04-11T17:49:39.348257Z","end":"2026-04-11T17:49:39.642794Z","steps":["trace[412082375] 'process raft request' (duration: 293.880803ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.645474Z","caller":"traceutil/trace.go:171","msg":"trace[1317248442] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4819; }","duration":"294.206651ms","start":"2026-04-11T17:49:39.348911Z","end":"2026-04-11T17:49:39.643118Z","steps":["trace[1317248442] 'agreement among raft nodes before linearized reading' (duration: 293.960755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.645035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.927811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:39.645712Z","caller":"traceutil/trace.go:171","msg":"trace[1890349078] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4819; }","duration":"194.637479ms","start":"2026-04-11T17:49:39.451057Z","end":"2026-04-11T17:49:39.645694Z","steps":["trace[1890349078] 'agreement among raft nodes before linearized reading' (duration: 193.926621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.644937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.582522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-11T17:49:39.646946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.002312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:39.647013Z","caller":"traceutil/trace.go:171","msg":"trace[316964610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4819; }","duration":"148.691746ms","start":"2026-04-11T17:49:39.498294Z","end":"2026-04-11T17:49:39.646986Z","steps":["trace[316964610] 'agreement among raft nodes before linearized reading' (duration: 146.579363ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.647165Z","caller":"traceutil/trace.go:171","msg":"trace[1736988085] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4819; }","duration":"237.124065ms","start":"2026-04-11T17:49:39.409938Z","end":"2026-04-11T17:49:39.647062Z","steps":["trace[1736988085] 'agreement among raft nodes before linearized reading' (duration: 232.95441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.650918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.746148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348486"} {"level":"info","ts":"2026-04-11T17:49:39.651Z","caller":"traceutil/trace.go:171","msg":"trace[1842692997] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4819; }","duration":"120.860271ms","start":"2026-04-11T17:49:39.530109Z","end":"2026-04-11T17:49:39.65097Z","steps":["trace[1842692997] 'agreement among raft nodes before linearized reading' (duration: 114.88522ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.758057Z","caller":"traceutil/trace.go:171","msg":"trace[657810337] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"102.914197ms","start":"2026-04-11T17:49:39.65512Z","end":"2026-04-11T17:49:39.758034Z","steps":["trace[657810337] 'process raft request' (duration: 87.441665ms)","trace[657810337] 'compare' (duration: 15.035581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:39.758254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.703792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-11T17:49:39.758323Z","caller":"traceutil/trace.go:171","msg":"trace[944291665] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4822; }","duration":"104.797235ms","start":"2026-04-11T17:49:39.653512Z","end":"2026-04-11T17:49:39.758309Z","steps":["trace[944291665] 'agreement among raft nodes before linearized reading' (duration: 104.587539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.758371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.0525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87341522-837c-46ff-8a9f-86bf1b107ec2\" ","response":"range_response_count:1 size:2580"} {"level":"info","ts":"2026-04-11T17:49:39.758423Z","caller":"traceutil/trace.go:171","msg":"trace[76171833] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87341522-837c-46ff-8a9f-86bf1b107ec2; range_end:; response_count:1; response_revision:4822; }","duration":"105.980814ms","start":"2026-04-11T17:49:39.652428Z","end":"2026-04-11T17:49:39.758408Z","steps":["trace[76171833] 'agreement among raft nodes before linearized reading' (duration: 105.82068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.758678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.398529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:39.758717Z","caller":"traceutil/trace.go:171","msg":"trace[1275785079] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4822; }","duration":"105.466581ms","start":"2026-04-11T17:49:39.653241Z","end":"2026-04-11T17:49:39.758708Z","steps":["trace[1275785079] 'agreement among raft nodes before linearized reading' (duration: 105.377828ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.758753Z","caller":"traceutil/trace.go:171","msg":"trace[1117813492] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"102.23261ms","start":"2026-04-11T17:49:39.656509Z","end":"2026-04-11T17:49:39.758741Z","steps":["trace[1117813492] 'process raft request' (duration: 101.225554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.75909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.020298ms","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-04-11T17:49:39.75913Z","caller":"traceutil/trace.go:171","msg":"trace[1322399630] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4822; }","duration":"101.070579ms","start":"2026-04-11T17:49:39.658048Z","end":"2026-04-11T17:49:39.759119Z","steps":["trace[1322399630] 'agreement among raft nodes before linearized reading' (duration: 100.970587ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:40.06357Z","caller":"traceutil/trace.go:171","msg":"trace[1974853228] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"126.952726ms","start":"2026-04-11T17:49:39.936572Z","end":"2026-04-11T17:49:40.063524Z","steps":["trace[1974853228] 'process raft request' (duration: 126.605507ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:40.063627Z","caller":"traceutil/trace.go:171","msg":"trace[497254386] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"126.674579ms","start":"2026-04-11T17:49:39.936899Z","end":"2026-04-11T17:49:40.063574Z","steps":["trace[497254386] 'process raft request' (duration: 126.510925ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:40.063778Z","caller":"traceutil/trace.go:171","msg":"trace[1706333583] linearizableReadLoop","detail":"{readStateIndex:5021; appliedIndex:5019; }","duration":"126.957096ms","start":"2026-04-11T17:49:39.936787Z","end":"2026-04-11T17:49:40.063744Z","steps":["trace[1706333583] 'read index received' (duration: 15.366619ms)","trace[1706333583] 'applied index is now lower than readState.Index' (duration: 111.587737ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:40.063893Z","caller":"traceutil/trace.go:171","msg":"trace[1152192607] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"126.943926ms","start":"2026-04-11T17:49:39.936935Z","end":"2026-04-11T17:49:40.063879Z","steps":["trace[1152192607] 'process raft request' (duration: 126.524465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:40.064072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.257564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"warn","ts":"2026-04-11T17:49:40.064095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.73227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-04-11T17:49:40.064132Z","caller":"traceutil/trace.go:171","msg":"trace[1286165133] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4833; }","duration":"124.807062ms","start":"2026-04-11T17:49:39.939315Z","end":"2026-04-11T17:49:40.064122Z","steps":["trace[1286165133] 'agreement among raft nodes before linearized reading' (duration: 124.612807ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:40.064137Z","caller":"traceutil/trace.go:171","msg":"trace[849187567] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4833; }","duration":"127.373447ms","start":"2026-04-11T17:49:39.936746Z","end":"2026-04-11T17:49:40.06412Z","steps":["trace[849187567] 'agreement among raft nodes before linearized reading' (duration: 127.158121ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:40.293446Z","caller":"traceutil/trace.go:171","msg":"trace[543669320] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"182.411472ms","start":"2026-04-11T17:49:40.110984Z","end":"2026-04-11T17:49:40.293396Z","steps":["trace[543669320] 'process raft request' (duration: 166.654253ms)","trace[543669320] 'compare' (duration: 15.558564ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:40.293487Z","caller":"traceutil/trace.go:171","msg":"trace[491868158] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5024; }","duration":"181.203211ms","start":"2026-04-11T17:49:40.112253Z","end":"2026-04-11T17:49:40.293456Z","steps":["trace[491868158] 'read index received' (duration: 165.36678ms)","trace[491868158] 'applied index is now lower than readState.Index' (duration: 15.833441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:40.293777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.502499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-04-11T17:49:40.293811Z","caller":"traceutil/trace.go:171","msg":"trace[141670343] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4838; }","duration":"181.56604ms","start":"2026-04-11T17:49:40.112235Z","end":"2026-04-11T17:49:40.293801Z","steps":["trace[141670343] 'agreement among raft nodes before linearized reading' (duration: 181.366245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:40.293871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.303568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-11T17:49:40.293963Z","caller":"traceutil/trace.go:171","msg":"trace[1346808260] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4838; }","duration":"178.427991ms","start":"2026-04-11T17:49:40.115514Z","end":"2026-04-11T17:49:40.293942Z","steps":["trace[1346808260] 'agreement among raft nodes before linearized reading' (duration: 178.142724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:40.29395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.018758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:49:40.294031Z","caller":"traceutil/trace.go:171","msg":"trace[1195085979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4838; }","duration":"155.141672ms","start":"2026-04-11T17:49:40.13887Z","end":"2026-04-11T17:49:40.294011Z","steps":["trace[1195085979] 'agreement among raft nodes before linearized reading' (duration: 154.863224ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:40.294027Z","caller":"traceutil/trace.go:171","msg":"trace[1849337833] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"179.465637ms","start":"2026-04-11T17:49:40.11453Z","end":"2026-04-11T17:49:40.293996Z","steps":["trace[1849337833] 'process raft request' (duration: 178.860482ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:40.294094Z","caller":"traceutil/trace.go:171","msg":"trace[2117553224] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"113.390703ms","start":"2026-04-11T17:49:40.180662Z","end":"2026-04-11T17:49:40.294053Z","steps":["trace[2117553224] 'process raft request' (duration: 112.847279ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:40.294131Z","caller":"traceutil/trace.go:171","msg":"trace[1741395741] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"123.169861ms","start":"2026-04-11T17:49:40.170941Z","end":"2026-04-11T17:49:40.29411Z","steps":["trace[1741395741] 'process raft request' (duration: 122.516584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:40.294207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.564944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61347"} {"level":"info","ts":"2026-04-11T17:49:40.294304Z","caller":"traceutil/trace.go:171","msg":"trace[832898259] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4838; }","duration":"178.696637ms","start":"2026-04-11T17:49:40.11558Z","end":"2026-04-11T17:49:40.294277Z","steps":["trace[832898259] 'agreement among raft nodes before linearized reading' (duration: 178.314428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.024815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.174506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-11T17:49:52.024932Z","caller":"traceutil/trace.go:171","msg":"trace[1835328677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5004; }","duration":"195.380212ms","start":"2026-04-11T17:49:51.829529Z","end":"2026-04-11T17:49:52.024909Z","steps":["trace[1835328677] 'range keys from in-memory index tree' (duration: 194.922231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.024912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.078429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-11T17:49:52.025021Z","caller":"traceutil/trace.go:171","msg":"trace[1355523093] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5004; }","duration":"115.249143ms","start":"2026-04-11T17:49:51.909741Z","end":"2026-04-11T17:49:52.02499Z","steps":["trace[1355523093] 'range keys from in-memory index tree' (duration: 114.846592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.025543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.994489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-11T17:49:52.025596Z","caller":"traceutil/trace.go:171","msg":"trace[327710904] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5004; }","duration":"143.094552ms","start":"2026-04-11T17:49:51.882489Z","end":"2026-04-11T17:49:52.025583Z","steps":["trace[327710904] 'range keys from in-memory index tree' (duration: 142.856395ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:52.916442Z","caller":"traceutil/trace.go:171","msg":"trace[2109241434] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5201; }","duration":"882.230657ms","start":"2026-04-11T17:49:52.034179Z","end":"2026-04-11T17:49:52.91641Z","steps":["trace[2109241434] 'read index received' (duration: 5.558191ms)","trace[2109241434] 'applied index is now lower than readState.Index' (duration: 876.670916ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:52.916601Z","caller":"traceutil/trace.go:171","msg":"trace[1255850254] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"884.918405ms","start":"2026-04-11T17:49:52.031629Z","end":"2026-04-11T17:49:52.916547Z","steps":["trace[1255850254] 'process raft request' (duration: 884.504895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.916789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.031613Z","time spent":"885.07159ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:52.916824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.603666ms","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":"info","ts":"2026-04-11T17:49:52.916861Z","caller":"traceutil/trace.go:171","msg":"trace[1512335090] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5007; }","duration":"882.676628ms","start":"2026-04-11T17:49:52.034174Z","end":"2026-04-11T17:49:52.916851Z","steps":["trace[1512335090] 'agreement among raft nodes before linearized reading' (duration: 882.407171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.916891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.034131Z","time spent":"882.753811ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-11T17:49:52.917222Z","caller":"traceutil/trace.go:171","msg":"trace[2060595162] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"878.545713ms","start":"2026-04-11T17:49:52.037996Z","end":"2026-04-11T17:49:52.916541Z","steps":["trace[2060595162] 'process raft request' (duration: 878.358358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.920316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.037985Z","time spent":"879.367964ms","remote":"127.0.0.1:59850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:52.938452Z","caller":"traceutil/trace.go:171","msg":"trace[632342134] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"885.93075ms","start":"2026-04-11T17:49:52.052493Z","end":"2026-04-11T17:49:52.938424Z","steps":["trace[632342134] 'process raft request' (duration: 885.728195ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:52.938492Z","caller":"traceutil/trace.go:171","msg":"trace[125982948] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"593.007674ms","start":"2026-04-11T17:49:52.345465Z","end":"2026-04-11T17:49:52.938473Z","steps":["trace[125982948] 'process raft request' (duration: 592.875511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.93872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.406523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:49:52.938722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.345433Z","time spent":"593.109217ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:52.938624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.052479Z","time spent":"886.056694ms","remote":"127.0.0.1:59744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":806,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:49:52.938796Z","caller":"traceutil/trace.go:171","msg":"trace[1491267368] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5010; }","duration":"283.564257ms","start":"2026-04-11T17:49:52.655222Z","end":"2026-04-11T17:49:52.938786Z","steps":["trace[1491267368] 'agreement among raft nodes before linearized reading' (duration: 283.405543ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:52.938788Z","caller":"traceutil/trace.go:171","msg":"trace[1008553281] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"341.256236ms","start":"2026-04-11T17:49:52.597505Z","end":"2026-04-11T17:49:52.938762Z","steps":["trace[1008553281] 'process raft request' (duration: 340.885876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.938892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.597474Z","time spent":"341.355428ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:52.970766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.611055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:49:52.97079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.523206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:52.970862Z","caller":"traceutil/trace.go:171","msg":"trace[96976351] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:5010; }","duration":"561.8118ms","start":"2026-04-11T17:49:52.409028Z","end":"2026-04-11T17:49:52.97084Z","steps":["trace[96976351] 'agreement among raft nodes before linearized reading' (duration: 529.715153ms)","trace[96976351] 'count revisions from in-memory index tree' (duration: 31.939113ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:52.970911Z","caller":"traceutil/trace.go:171","msg":"trace[222566304] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5010; }","duration":"855.823234ms","start":"2026-04-11T17:49:52.11504Z","end":"2026-04-11T17:49:52.970863Z","steps":["trace[222566304] 'agreement among raft nodes before linearized reading' (duration: 823.719836ms)","trace[222566304] 'count revisions from in-memory index tree' (duration: 31.79988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:52.970915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.408999Z","time spent":"561.902763ms","remote":"127.0.0.1:36944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:49:52.970925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.930777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-11T17:49:52.971012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.115017Z","time spent":"855.956527ms","remote":"127.0.0.1:36822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:49:52.971018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.835062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-11T17:49:52.971121Z","caller":"traceutil/trace.go:171","msg":"trace[138977128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5010; }","duration":"585.998825ms","start":"2026-04-11T17:49:52.385095Z","end":"2026-04-11T17:49:52.971094Z","steps":["trace[138977128] 'agreement among raft nodes before linearized reading' (duration: 553.611541ms)","trace[138977128] 'range keys from in-memory index tree' (duration: 32.119957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:52.971174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.385066Z","time spent":"586.099188ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-11T17:49:52.971134Z","caller":"traceutil/trace.go:171","msg":"trace[1189805990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5010; }","duration":"152.182422ms","start":"2026-04-11T17:49:52.818868Z","end":"2026-04-11T17:49:52.97105Z","steps":["trace[1189805990] 'agreement among raft nodes before linearized reading' (duration: 119.89218ms)","trace[1189805990] 'range keys from in-memory index tree' (duration: 31.950963ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:48.4504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2852} {"level":"info","ts":"2026-04-11T17:50:48.515358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2852,"took":"63.531174ms","hash":4179304282,"current-db-size-bytes":54185984,"current-db-size":"54 MB","current-db-size-in-use-bytes":29282304,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-11T17:50:48.515417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4179304282,"revision":2852,"compact-revision":-1} {"level":"info","ts":"2026-04-11T17:51:31.970444Z","caller":"traceutil/trace.go:171","msg":"trace[1799242796] linearizableReadLoop","detail":"{readStateIndex:6012; appliedIndex:6011; }","duration":"183.560541ms","start":"2026-04-11T17:51:31.786861Z","end":"2026-04-11T17:51:31.970422Z","steps":["trace[1799242796] 'read index received' (duration: 183.326974ms)","trace[1799242796] 'applied index is now lower than readState.Index' (duration: 232.547µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:31.970593Z","caller":"traceutil/trace.go:171","msg":"trace[540351876] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"210.429581ms","start":"2026-04-11T17:51:31.760114Z","end":"2026-04-11T17:51:31.970544Z","steps":["trace[540351876] 'process raft request' (duration: 210.129843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:31.970902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.988181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:31.971052Z","caller":"traceutil/trace.go:171","msg":"trace[927666296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5793; }","duration":"184.217017ms","start":"2026-04-11T17:51:31.786788Z","end":"2026-04-11T17:51:31.971005Z","steps":["trace[927666296] 'agreement among raft nodes before linearized reading' (duration: 183.815756ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:32.211899Z","caller":"traceutil/trace.go:171","msg":"trace[749104421] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"236.180934ms","start":"2026-04-11T17:51:31.975691Z","end":"2026-04-11T17:51:32.211872Z","steps":["trace[749104421] 'process raft request' (duration: 235.312371ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:32.212261Z","caller":"traceutil/trace.go:171","msg":"trace[914072405] linearizableReadLoop","detail":"{readStateIndex:6014; appliedIndex:6012; }","duration":"102.647519ms","start":"2026-04-11T17:51:32.109552Z","end":"2026-04-11T17:51:32.2122Z","steps":["trace[914072405] 'read index received' (duration: 101.449477ms)","trace[914072405] 'applied index is now lower than readState.Index' (duration: 1.196852ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:32.212457Z","caller":"traceutil/trace.go:171","msg":"trace[667561833] transaction","detail":"{read_only:false; response_revision:5795; number_of_response:1; }","duration":"236.625286ms","start":"2026-04-11T17:51:31.975812Z","end":"2026-04-11T17:51:32.212438Z","steps":["trace[667561833] 'process raft request' (duration: 236.207595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:32.212557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.992158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-11T17:51:32.212631Z","caller":"traceutil/trace.go:171","msg":"trace[763677818] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5795; }","duration":"103.08016ms","start":"2026-04-11T17:51:32.109537Z","end":"2026-04-11T17:51:32.212618Z","steps":["trace[763677818] 'agreement among raft nodes before linearized reading' (duration: 102.800983ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:53:01.381652Z","caller":"traceutil/trace.go:171","msg":"trace[204957590] linearizableReadLoop","detail":"{readStateIndex:6630; appliedIndex:6629; }","duration":"132.861794ms","start":"2026-04-11T17:53:01.24876Z","end":"2026-04-11T17:53:01.381621Z","steps":["trace[204957590] 'read index received' (duration: 132.70884ms)","trace[204957590] 'applied index is now lower than readState.Index' (duration: 151.464µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:53:01.381891Z","caller":"traceutil/trace.go:171","msg":"trace[297302781] transaction","detail":"{read_only:false; response_revision:6389; number_of_response:1; }","duration":"134.22867ms","start":"2026-04-11T17:53:01.247625Z","end":"2026-04-11T17:53:01.381853Z","steps":["trace[297302781] 'process raft request' (duration: 133.821009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:53:01.382002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.212283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:53:01.382068Z","caller":"traceutil/trace.go:171","msg":"trace[1205735108] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6389; }","duration":"133.338766ms","start":"2026-04-11T17:53:01.248716Z","end":"2026-04-11T17:53:01.382054Z","steps":["trace[1205735108] 'agreement among raft nodes before linearized reading' (duration: 133.166022ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:53:41.798455Z","caller":"traceutil/trace.go:171","msg":"trace[57179044] transaction","detail":"{read_only:false; response_revision:6705; number_of_response:1; }","duration":"130.410679ms","start":"2026-04-11T17:53:41.667899Z","end":"2026-04-11T17:53:41.79831Z","steps":["trace[57179044] 'process raft request' (duration: 121.588046ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:55:48.462368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5492} {"level":"info","ts":"2026-04-11T17:55:48.543983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5492,"took":"79.899676ms","hash":4072659438,"current-db-size-bytes":54185984,"current-db-size":"54 MB","current-db-size-in-use-bytes":18599936,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-11T17:55:48.544071Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4072659438,"revision":5492,"compact-revision":2852}