{"level":"warn","ts":"2026-10-02T04:46:30.824174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-10-02T04:46:30.824246Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.149: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.149:2380","--initial-cluster=instance=https://162.253.55.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.149:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-10-02T04:46:30.824297Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-10-02T04:46:30.824311Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.149:2380"]}
{"level":"info","ts":"2026-10-02T04:46:30.824326Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-10-02T04:46:30.825053Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.149:2379"]}
{"level":"info","ts":"2026-10-02T04:46:30.825196Z","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.149:2380"],"listen-peer-urls":["https://162.253.55.149:2380"],"advertise-client-urls":["https://162.253.55.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.149:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-10-02T04:46:30.829961Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.572179ms"}
{"level":"info","ts":"2026-10-02T04:46:30.837206Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab61124f07c17f20","cluster-id":"c5f62e84ad801ad2"}
{"level":"info","ts":"2026-10-02T04:46:30.837262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 switched to configuration voters=()"}
{"level":"info","ts":"2026-10-02T04:46:30.837288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became follower at term 0"}
{"level":"info","ts":"2026-10-02T04:46:30.837299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab61124f07c17f20 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-10-02T04:46:30.837306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became follower at term 1"}
{"level":"info","ts":"2026-10-02T04:46:30.83734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 switched to configuration voters=(12349171783868448544)"}
{"level":"warn","ts":"2026-10-02T04:46:30.84586Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-10-02T04:46:30.847303Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-10-02T04:46:30.848613Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-10-02T04:46:30.850545Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab61124f07c17f20","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-10-02T04:46:30.850654Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-10-02T04:46:30.850724Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-10-02T04:46:30.850733Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-10-02T04:46:30.851Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab61124f07c17f20","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-10-02T04:46:30.852238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 switched to configuration voters=(12349171783868448544)"}
{"level":"info","ts":"2026-10-02T04:46:30.852366Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5f62e84ad801ad2","local-member-id":"ab61124f07c17f20","added-peer-id":"ab61124f07c17f20","added-peer-peer-urls":["https://162.253.55.149:2380"]}
{"level":"info","ts":"2026-10-02T04:46:30.853527Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-10-02T04:46:30.85362Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.149:2380"}
{"level":"info","ts":"2026-10-02T04:46:30.853646Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.149:2380"}
{"level":"info","ts":"2026-10-02T04:46:30.853736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab61124f07c17f20","initial-advertise-peer-urls":["https://162.253.55.149:2380"],"listen-peer-urls":["https://162.253.55.149:2380"],"advertise-client-urls":["https://162.253.55.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-10-02T04:46:30.853772Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-10-02T04:46:31.837749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 is starting a new election at term 1"}
{"level":"info","ts":"2026-10-02T04:46:31.837916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became pre-candidate at term 1"}
{"level":"info","ts":"2026-10-02T04:46:31.837952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 received MsgPreVoteResp from ab61124f07c17f20 at term 1"}
{"level":"info","ts":"2026-10-02T04:46:31.837973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became candidate at term 2"}
{"level":"info","ts":"2026-10-02T04:46:31.837984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 received MsgVoteResp from ab61124f07c17f20 at term 2"}
{"level":"info","ts":"2026-10-02T04:46:31.838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab61124f07c17f20 became leader at term 2"}
{"level":"info","ts":"2026-10-02T04:46:31.838025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab61124f07c17f20 elected leader ab61124f07c17f20 at term 2"}
{"level":"info","ts":"2026-10-02T04:46:31.839187Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-02T04:46:31.840256Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-10-02T04:46:31.840256Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab61124f07c17f20","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.149:2379]}","request-path":"/0/members/ab61124f07c17f20/attributes","cluster-id":"c5f62e84ad801ad2","publish-timeout":"7s"}
{"level":"info","ts":"2026-10-02T04:46:31.840429Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-10-02T04:46:31.840657Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-10-02T04:46:31.840702Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-10-02T04:46:31.841166Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5f62e84ad801ad2","local-member-id":"ab61124f07c17f20","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-02T04:46:31.841295Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-02T04:46:31.841338Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-02T04:46:31.843893Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-10-02T04:46:31.844082Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.149:2379"}
{"level":"info","ts":"2026-10-02T04:46:34.513924Z","caller":"traceutil/trace.go:171","msg":"trace[485545079] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:120; }","duration":"112.232472ms","start":"2026-10-02T04:46:34.401666Z","end":"2026-10-02T04:46:34.513898Z","steps":["trace[485545079] 'read index received'  (duration: 112.015917ms)","trace[485545079] 'applied index is now lower than readState.Index'  (duration: 215.315µs)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:46:34.514086Z","caller":"traceutil/trace.go:171","msg":"trace[1339744129] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"113.85429ms","start":"2026-10-02T04:46:34.40022Z","end":"2026-10-02T04:46:34.514074Z","steps":["trace[1339744129] 'process raft request'  (duration: 113.531033ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:46:34.514472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.780505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-10-02T04:46:34.514558Z","caller":"traceutil/trace.go:171","msg":"trace[586107142] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:116; }","duration":"112.896937ms","start":"2026-10-02T04:46:34.401644Z","end":"2026-10-02T04:46:34.514541Z","steps":["trace[586107142] 'agreement among raft nodes before linearized reading'  (duration: 112.764694ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:46:34.889959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.765943ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9160498641690932764 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/priorityclasses/system-cluster-critical\" mod_revision:0 > success:<request_put:<key:\"/registry/priorityclasses/system-cluster-critical\" value_size:407 >> failure:<>>","response":"size:14"}
{"level":"info","ts":"2026-10-02T04:46:34.890067Z","caller":"traceutil/trace.go:171","msg":"trace[905613591] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"368.288728ms","start":"2026-10-02T04:46:34.521762Z","end":"2026-10-02T04:46:34.890051Z","steps":["trace[905613591] 'read index received'  (duration: 179.89053ms)","trace[905613591] 'applied index is now lower than readState.Index'  (duration: 188.396928ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:46:34.890139Z","caller":"traceutil/trace.go:171","msg":"trace[1970043698] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"370.460319ms","start":"2026-10-02T04:46:34.519612Z","end":"2026-10-02T04:46:34.890072Z","steps":["trace[1970043698] 'process raft request'  (duration: 182.099872ms)","trace[1970043698] 'compare'  (duration: 187.646531ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:46:34.890243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.436552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-10-02T04:46:34.89032Z","caller":"traceutil/trace.go:171","msg":"trace[145541462] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:117; }","duration":"368.574565ms","start":"2026-10-02T04:46:34.521735Z","end":"2026-10-02T04:46:34.890309Z","steps":["trace[145541462] 'agreement among raft nodes before linearized reading'  (duration: 368.35746ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:46:34.890358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:34.519594Z","time spent":"370.638804ms","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/priorityclasses/system-cluster-critical\" mod_revision:0 > success:<request_put:<key:\"/registry/priorityclasses/system-cluster-critical\" value_size:407 >> failure:<>"}
{"level":"warn","ts":"2026-10-02T04:46:34.890426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:34.521723Z","time spent":"368.691408ms","remote":"127.0.0.1:35042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/edit\" "}
{"level":"info","ts":"2026-10-02T04:47:09.047791Z","caller":"traceutil/trace.go:171","msg":"trace[2016287383] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"140.850042ms","start":"2026-10-02T04:47:08.90692Z","end":"2026-10-02T04:47:09.04777Z","steps":["trace[2016287383] 'process raft request'  (duration: 140.800381ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.047845Z","caller":"traceutil/trace.go:171","msg":"trace[1357789532] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"143.516764ms","start":"2026-10-02T04:47:08.90429Z","end":"2026-10-02T04:47:09.047807Z","steps":["trace[1357789532] 'process raft request'  (duration: 117.392606ms)","trace[1357789532] 'compare'  (duration: 25.895013ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:47:09.050896Z","caller":"traceutil/trace.go:171","msg":"trace[997873071] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"105.702967ms","start":"2026-10-02T04:47:08.945178Z","end":"2026-10-02T04:47:09.050881Z","steps":["trace[997873071] 'process raft request'  (duration: 105.667356ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.051195Z","caller":"traceutil/trace.go:171","msg":"trace[668930626] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"137.882154ms","start":"2026-10-02T04:47:08.913305Z","end":"2026-10-02T04:47:09.051187Z","steps":["trace[668930626] 'process raft request'  (duration: 137.18657ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.051567Z","caller":"traceutil/trace.go:171","msg":"trace[1155269151] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"129.541471ms","start":"2026-10-02T04:47:08.922Z","end":"2026-10-02T04:47:09.051542Z","steps":["trace[1155269151] 'process raft request'  (duration: 128.775667ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.051629Z","caller":"traceutil/trace.go:171","msg":"trace[726717328] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"138.184039ms","start":"2026-10-02T04:47:08.913436Z","end":"2026-10-02T04:47:09.05162Z","steps":["trace[726717328] 'process raft request'  (duration: 137.18024ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.051642Z","caller":"traceutil/trace.go:171","msg":"trace[1049079540] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"138.22306ms","start":"2026-10-02T04:47:08.913405Z","end":"2026-10-02T04:47:09.051628Z","steps":["trace[1049079540] 'process raft request'  (duration: 137.17616ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.051706Z","caller":"traceutil/trace.go:171","msg":"trace[468122130] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"138.20748ms","start":"2026-10-02T04:47:08.913476Z","end":"2026-10-02T04:47:09.051683Z","steps":["trace[468122130] 'process raft request'  (duration: 137.16453ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.051722Z","caller":"traceutil/trace.go:171","msg":"trace[1919404406] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"132.645752ms","start":"2026-10-02T04:47:08.919066Z","end":"2026-10-02T04:47:09.051712Z","steps":["trace[1919404406] 'process raft request'  (duration: 131.657063ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.051793Z","caller":"traceutil/trace.go:171","msg":"trace[2089506270] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"125.301199ms","start":"2026-10-02T04:47:08.926471Z","end":"2026-10-02T04:47:09.051772Z","steps":["trace[2089506270] 'process raft request'  (duration: 124.33371ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.051882Z","caller":"traceutil/trace.go:171","msg":"trace[2107428997] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"138.532656ms","start":"2026-10-02T04:47:08.91334Z","end":"2026-10-02T04:47:09.051873Z","steps":["trace[2107428997] 'process raft request'  (duration: 137.216741ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.052021Z","caller":"traceutil/trace.go:171","msg":"trace[292923124] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"135.195771ms","start":"2026-10-02T04:47:08.916807Z","end":"2026-10-02T04:47:09.052003Z","steps":["trace[292923124] 'process raft request'  (duration: 133.884176ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:09.052205Z","caller":"traceutil/trace.go:171","msg":"trace[1693334189] transaction","detail":"{read_only:false; number_of_response:1; response_revision:544; }","duration":"135.411735ms","start":"2026-10-02T04:47:08.916778Z","end":"2026-10-02T04:47:09.05219Z","steps":["trace[1693334189] 'process raft request'  (duration: 133.891746ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:15.356617Z","caller":"traceutil/trace.go:171","msg":"trace[1612808140] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"167.916042ms","start":"2026-10-02T04:47:15.18861Z","end":"2026-10-02T04:47:15.356526Z","steps":["trace[1612808140] 'process raft request'  (duration: 159.072864ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:47:15.557483Z","caller":"traceutil/trace.go:171","msg":"trace[1352656874] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"122.225386ms","start":"2026-10-02T04:47:15.435233Z","end":"2026-10-02T04:47:15.557458Z","steps":["trace[1352656874] 'process raft request'  (duration: 88.821343ms)","trace[1352656874] 'compare'  (duration: 33.315431ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:47:16.159027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.26955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.149\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-10-02T04:47:16.15913Z","caller":"traceutil/trace.go:171","msg":"trace[1338807063] range","detail":"{range_begin:/registry/masterleases/162.253.55.149; range_end:; response_count:1; response_revision:636; }","duration":"355.389522ms","start":"2026-10-02T04:47:15.803699Z","end":"2026-10-02T04:47:16.159089Z","steps":["trace[1338807063] 'range keys from in-memory index tree'  (duration: 355.165368ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:47:16.159172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:47:15.803688Z","time spent":"355.474844ms","remote":"127.0.0.1:34744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.149\" "}
{"level":"warn","ts":"2026-10-02T04:47:16.159507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.475944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-10-02T04:47:16.159536Z","caller":"traceutil/trace.go:171","msg":"trace[1596294643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"403.539755ms","start":"2026-10-02T04:47:15.755987Z","end":"2026-10-02T04:47:16.159527Z","steps":["trace[1596294643] 'range keys from in-memory index tree'  (duration: 403.324021ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:47:16.15956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:47:15.755968Z","time spent":"403.585686ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-10-02T04:47:16.159539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.470265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mvscb\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-10-02T04:47:16.159626Z","caller":"traceutil/trace.go:171","msg":"trace[54282685] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mvscb; range_end:; response_count:1; response_revision:636; }","duration":"221.602568ms","start":"2026-10-02T04:47:15.938001Z","end":"2026-10-02T04:47:16.159603Z","steps":["trace[54282685] 'range keys from in-memory index tree'  (duration: 221.23377ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:47:16.159643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.857073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-10-02T04:47:16.159665Z","caller":"traceutil/trace.go:171","msg":"trace[282011775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"381.910625ms","start":"2026-10-02T04:47:15.777747Z","end":"2026-10-02T04:47:16.159658Z","steps":["trace[282011775] 'range keys from in-memory index tree'  (duration: 381.736172ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:47:16.159701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:47:15.777729Z","time spent":"381.966455ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-10-02T04:47:16.163332Z","caller":"traceutil/trace.go:171","msg":"trace[867809762] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"138.643027ms","start":"2026-10-02T04:47:16.02467Z","end":"2026-10-02T04:47:16.163313Z","steps":["trace[867809762] 'process raft request'  (duration: 138.059856ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:48:03.137939Z","caller":"traceutil/trace.go:171","msg":"trace[532478943] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"193.901595ms","start":"2026-10-02T04:48:02.94401Z","end":"2026-10-02T04:48:03.137912Z","steps":["trace[532478943] 'process raft request'  (duration: 193.763953ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:48:53.847298Z","caller":"traceutil/trace.go:171","msg":"trace[69277947] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"378.874397ms","start":"2026-10-02T04:48:53.468399Z","end":"2026-10-02T04:48:53.847274Z","steps":["trace[69277947] 'read index received'  (duration: 371.328091ms)","trace[69277947] 'applied index is now lower than readState.Index'  (duration: 7.545296ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:48:53.847535Z","caller":"traceutil/trace.go:171","msg":"trace[1416767916] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"411.06018ms","start":"2026-10-02T04:48:53.436463Z","end":"2026-10-02T04:48:53.847523Z","steps":["trace[1416767916] 'process raft request'  (duration: 403.24217ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:53.84781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.436435Z","time spent":"411.155271ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" mod_revision:1830 > success:<request_put:<key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" value_size:10913 >> failure:<request_range:<key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" > >"}
{"level":"warn","ts":"2026-10-02T04:48:53.847841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.404525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-304a23a4-1cbd-4959-aa78-c23c6cdfa9d7\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-10-02T04:48:53.847926Z","caller":"traceutil/trace.go:171","msg":"trace[213698576] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-304a23a4-1cbd-4959-aa78-c23c6cdfa9d7; range_end:; response_count:1; response_revision:1889; }","duration":"379.535647ms","start":"2026-10-02T04:48:53.468368Z","end":"2026-10-02T04:48:53.847903Z","steps":["trace[213698576] 'agreement among raft nodes before linearized reading'  (duration: 379.230953ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:53.84798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.468351Z","time spent":"379.619198ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-304a23a4-1cbd-4959-aa78-c23c6cdfa9d7\" "}
{"level":"info","ts":"2026-10-02T04:48:53.90689Z","caller":"traceutil/trace.go:171","msg":"trace[557157759] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"437.567784ms","start":"2026-10-02T04:48:53.469294Z","end":"2026-10-02T04:48:53.906861Z","steps":["trace[557157759] 'process raft request'  (duration: 437.395021ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:53.90703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.570971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-10-02T04:48:53.907106Z","caller":"traceutil/trace.go:171","msg":"trace[680820151] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"211.681623ms","start":"2026-10-02T04:48:53.695405Z","end":"2026-10-02T04:48:53.907087Z","steps":["trace[680820151] 'agreement among raft nodes before linearized reading'  (duration: 211.444719ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:53.907044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.469272Z","time spent":"437.692915ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:1851 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-10-02T04:48:53.90761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.832956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-10-02T04:48:53.90766Z","caller":"traceutil/trace.go:171","msg":"trace[1539787867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1890; }","duration":"435.919557ms","start":"2026-10-02T04:48:53.471729Z","end":"2026-10-02T04:48:53.907648Z","steps":["trace[1539787867] 'agreement among raft nodes before linearized reading'  (duration: 435.499231ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:53.907811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.471718Z","time spent":"436.07921ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-10-02T04:48:54.213377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.309929ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9160498641690936341 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-6ghm5\" mod_revision:1862 > success:<request_put:<key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-6ghm5\" value_size:1459 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-6ghm5\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-02T04:48:54.213559Z","caller":"traceutil/trace.go:171","msg":"trace[1864430685] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"356.169197ms","start":"2026-10-02T04:48:53.857374Z","end":"2026-10-02T04:48:54.213543Z","steps":["trace[1864430685] 'process raft request'  (duration: 107.615704ms)","trace[1864430685] 'compare'  (duration: 248.209777ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:48:54.213614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.857353Z","time spent":"356.235359ms","remote":"127.0.0.1:34966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-6ghm5\" mod_revision:1862 > success:<request_put:<key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-6ghm5\" value_size:1459 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-6ghm5\" > >"}
{"level":"info","ts":"2026-10-02T04:48:54.213761Z","caller":"traceutil/trace.go:171","msg":"trace[262708134] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"272.229835ms","start":"2026-10-02T04:48:53.941513Z","end":"2026-10-02T04:48:54.213743Z","steps":["trace[262708134] 'process raft request'  (duration: 272.184735ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:48:54.213876Z","caller":"traceutil/trace.go:171","msg":"trace[1901917264] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1965; }","duration":"307.042447ms","start":"2026-10-02T04:48:53.906817Z","end":"2026-10-02T04:48:54.21386Z","steps":["trace[1901917264] 'read index received'  (duration: 58.342222ms)","trace[1901917264] 'applied index is now lower than readState.Index'  (duration: 248.698355ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:48:54.213871Z","caller":"traceutil/trace.go:171","msg":"trace[967433849] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"356.206398ms","start":"2026-10-02T04:48:53.857637Z","end":"2026-10-02T04:48:54.213844Z","steps":["trace[967433849] 'process raft request'  (duration: 355.911583ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:48:54.213929Z","caller":"traceutil/trace.go:171","msg":"trace[218847725] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"355.507477ms","start":"2026-10-02T04:48:53.858396Z","end":"2026-10-02T04:48:54.213903Z","steps":["trace[218847725] 'process raft request'  (duration: 355.198692ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:54.214018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.238188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-304a23a4-1cbd-4959-aa78-c23c6cdfa9d7\" ","response":"range_response_count:1 size:3627"}
{"level":"warn","ts":"2026-10-02T04:48:54.21402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.857628Z","time spent":"356.313119ms","remote":"127.0.0.1:34966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-replicas-d4krq\" mod_revision:1865 > success:<request_put:<key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-replicas-d4krq\" value_size:1392 >> failure:<request_range:<key:\"/registry/endpointslices/openstack/percona-xtradb-haproxy-replicas-d4krq\" > >"}
{"level":"info","ts":"2026-10-02T04:48:54.214042Z","caller":"traceutil/trace.go:171","msg":"trace[880378334] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-304a23a4-1cbd-4959-aa78-c23c6cdfa9d7; range_end:; response_count:1; response_revision:1896; }","duration":"356.321309ms","start":"2026-10-02T04:48:53.857713Z","end":"2026-10-02T04:48:54.214034Z","steps":["trace[880378334] 'agreement among raft nodes before linearized reading'  (duration: 356.212597ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:54.214062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.857703Z","time spent":"356.35363ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-304a23a4-1cbd-4959-aa78-c23c6cdfa9d7\" "}
{"level":"warn","ts":"2026-10-02T04:48:54.214043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.858377Z","time spent":"355.608789ms","remote":"127.0.0.1:34874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy-replicas\" mod_revision:1866 > success:<request_put:<key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy-replicas\" value_size:816 >> failure:<request_range:<key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy-replicas\" > >"}
{"level":"info","ts":"2026-10-02T04:48:54.214042Z","caller":"traceutil/trace.go:171","msg":"trace[1764196844] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"356.41346ms","start":"2026-10-02T04:48:53.85758Z","end":"2026-10-02T04:48:54.213994Z","steps":["trace[1764196844] 'process raft request'  (duration: 355.901372ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:54.214224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.716688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-10-02T04:48:54.214283Z","caller":"traceutil/trace.go:171","msg":"trace[1175167116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1896; }","duration":"142.802459ms","start":"2026-10-02T04:48:54.071465Z","end":"2026-10-02T04:48:54.214268Z","steps":["trace[1175167116] 'agreement among raft nodes before linearized reading'  (duration: 142.641367ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:54.214252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.857552Z","time spent":"356.600383ms","remote":"127.0.0.1:34874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" mod_revision:1861 > success:<request_put:<key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" value_size:909 >> failure:<request_range:<key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" > >"}
{"level":"info","ts":"2026-10-02T04:48:54.214818Z","caller":"traceutil/trace.go:171","msg":"trace[1426114633] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"301.046434ms","start":"2026-10-02T04:48:53.913686Z","end":"2026-10-02T04:48:54.214732Z","steps":["trace[1426114633] 'process raft request'  (duration: 299.963418ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:48:54.214928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:53.913659Z","time spent":"301.209297ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1852 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-10-02T04:49:05.579075Z","caller":"traceutil/trace.go:171","msg":"trace[450361494] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"135.726501ms","start":"2026-10-02T04:49:05.443323Z","end":"2026-10-02T04:49:05.579049Z","steps":["trace[450361494] 'process raft request'  (duration: 135.513418ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:05.581214Z","caller":"traceutil/trace.go:171","msg":"trace[1753520392] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"135.719759ms","start":"2026-10-02T04:49:05.445467Z","end":"2026-10-02T04:49:05.581187Z","steps":["trace[1753520392] 'process raft request'  (duration: 135.582257ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:06.151795Z","caller":"traceutil/trace.go:171","msg":"trace[1537877668] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2075; }","duration":"169.200283ms","start":"2026-10-02T04:49:05.982571Z","end":"2026-10-02T04:49:06.151771Z","steps":["trace[1537877668] 'read index received'  (duration: 169.045391ms)","trace[1537877668] 'applied index is now lower than readState.Index'  (duration: 153.752µs)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:49:06.151929Z","caller":"traceutil/trace.go:171","msg":"trace[1041384242] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"206.325464ms","start":"2026-10-02T04:49:05.945573Z","end":"2026-10-02T04:49:06.151898Z","steps":["trace[1041384242] 'process raft request'  (duration: 206.05527ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:49:06.152046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.448487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-10-02T04:49:06.15208Z","caller":"traceutil/trace.go:171","msg":"trace[1637460495] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1997; }","duration":"169.544978ms","start":"2026-10-02T04:49:05.982523Z","end":"2026-10-02T04:49:06.152068Z","steps":["trace[1637460495] 'agreement among raft nodes before linearized reading'  (duration: 169.339735ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:28.361539Z","caller":"traceutil/trace.go:171","msg":"trace[1080341855] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"103.824441ms","start":"2026-10-02T04:49:28.257686Z","end":"2026-10-02T04:49:28.36151Z","steps":["trace[1080341855] 'process raft request'  (duration: 103.386245ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:29.105439Z","caller":"traceutil/trace.go:171","msg":"trace[786754687] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"194.530533ms","start":"2026-10-02T04:49:28.910884Z","end":"2026-10-02T04:49:29.105414Z","steps":["trace[786754687] 'process raft request'  (duration: 194.343286ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:29.133443Z","caller":"traceutil/trace.go:171","msg":"trace[1747762177] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2221; }","duration":"158.683713ms","start":"2026-10-02T04:49:28.97473Z","end":"2026-10-02T04:49:29.133414Z","steps":["trace[1747762177] 'read index received'  (duration: 130.427781ms)","trace[1747762177] 'applied index is now lower than readState.Index'  (duration: 28.254892ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:49:29.133577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.828239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-10-02T04:49:29.133604Z","caller":"traceutil/trace.go:171","msg":"trace[865588855] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2140; }","duration":"158.892301ms","start":"2026-10-02T04:49:28.974703Z","end":"2026-10-02T04:49:29.133596Z","steps":["trace[865588855] 'agreement among raft nodes before linearized reading'  (duration: 158.813058ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:29.133618Z","caller":"traceutil/trace.go:171","msg":"trace[1113632366] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"166.149985ms","start":"2026-10-02T04:49:28.96743Z","end":"2026-10-02T04:49:29.13358Z","steps":["trace[1113632366] 'process raft request'  (duration: 165.895595ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:29.283693Z","caller":"traceutil/trace.go:171","msg":"trace[402516331] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"145.659268ms","start":"2026-10-02T04:49:29.138006Z","end":"2026-10-02T04:49:29.283666Z","steps":["trace[402516331] 'process raft request'  (duration: 143.592961ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:29.4881Z","caller":"traceutil/trace.go:171","msg":"trace[667521996] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2224; }","duration":"186.110589ms","start":"2026-10-02T04:49:29.301963Z","end":"2026-10-02T04:49:29.488073Z","steps":["trace[667521996] 'read index received'  (duration: 131.06481ms)","trace[667521996] 'applied index is now lower than readState.Index'  (duration: 55.044539ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:49:29.488307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.304946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-10-02T04:49:29.488441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.816707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-10-02T04:49:29.488461Z","caller":"traceutil/trace.go:171","msg":"trace[390641240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2142; }","duration":"186.518765ms","start":"2026-10-02T04:49:29.301921Z","end":"2026-10-02T04:49:29.48844Z","steps":["trace[390641240] 'agreement among raft nodes before linearized reading'  (duration: 186.302146ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:29.488498Z","caller":"traceutil/trace.go:171","msg":"trace[432445151] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2142; }","duration":"119.910179ms","start":"2026-10-02T04:49:29.368569Z","end":"2026-10-02T04:49:29.488479Z","steps":["trace[432445151] 'agreement among raft nodes before linearized reading'  (duration: 119.701611ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:49:29.488424Z","caller":"traceutil/trace.go:171","msg":"trace[24789743] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"350.122178ms","start":"2026-10-02T04:49:29.138022Z","end":"2026-10-02T04:49:29.488144Z","steps":["trace[24789743] 'process raft request'  (duration: 294.998255ms)","trace[24789743] 'compare'  (duration: 54.922315ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:49:29.489251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:49:29.138005Z","time spent":"350.780663ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:2133 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-10-02T04:50:40.776025Z","caller":"traceutil/trace.go:171","msg":"trace[1980826915] linearizableReadLoop","detail":"{readStateIndex:2723; appliedIndex:2722; }","duration":"156.809423ms","start":"2026-10-02T04:50:40.619192Z","end":"2026-10-02T04:50:40.776002Z","steps":["trace[1980826915] 'read index received'  (duration: 156.665409ms)","trace[1980826915] 'applied index is now lower than readState.Index'  (duration: 142.924µs)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:50:40.776226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.010158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"}
{"level":"info","ts":"2026-10-02T04:50:40.776265Z","caller":"traceutil/trace.go:171","msg":"trace[121647661] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2624; }","duration":"157.091051ms","start":"2026-10-02T04:50:40.619163Z","end":"2026-10-02T04:50:40.776254Z","steps":["trace[121647661] 'agreement among raft nodes before linearized reading'  (duration: 156.934857ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:50:40.776081Z","caller":"traceutil/trace.go:171","msg":"trace[2016253610] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"157.37081ms","start":"2026-10-02T04:50:40.618681Z","end":"2026-10-02T04:50:40.776052Z","steps":["trace[2016253610] 'process raft request'  (duration: 157.148134ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:51:18.380896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.308142ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9160498641690938568 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2821 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:476 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-02T04:51:18.381366Z","caller":"traceutil/trace.go:171","msg":"trace[1501873435] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"263.273098ms","start":"2026-10-02T04:51:18.118059Z","end":"2026-10-02T04:51:18.381332Z","steps":["trace[1501873435] 'process raft request'  (duration: 263.164576ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:51:18.381372Z","caller":"traceutil/trace.go:171","msg":"trace[1095115310] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"299.734153ms","start":"2026-10-02T04:51:18.081608Z","end":"2026-10-02T04:51:18.381342Z","steps":["trace[1095115310] 'process raft request'  (duration: 109.866426ms)","trace[1095115310] 'compare'  (duration: 188.949493ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:55:33.379705Z","caller":"traceutil/trace.go:171","msg":"trace[1240191555] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4963; }","duration":"124.768327ms","start":"2026-10-02T04:55:33.254913Z","end":"2026-10-02T04:55:33.379681Z","steps":["trace[1240191555] 'read index received'  (duration: 92.839799ms)","trace[1240191555] 'applied index is now lower than readState.Index'  (duration: 31.927418ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:55:33.379791Z","caller":"traceutil/trace.go:171","msg":"trace[436395104] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"125.046252ms","start":"2026-10-02T04:55:33.254676Z","end":"2026-10-02T04:55:33.379722Z","steps":["trace[436395104] 'process raft request'  (duration: 93.062343ms)","trace[436395104] 'compare'  (duration: 31.752905ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:55:33.380078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.139064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e85483ca-8e40-4490-aaa7-aeb3770e9bd4\" ","response":"range_response_count:1 size:3774"}
{"level":"info","ts":"2026-10-02T04:55:33.380184Z","caller":"traceutil/trace.go:171","msg":"trace[2070042747] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e85483ca-8e40-4490-aaa7-aeb3770e9bd4; range_end:; response_count:1; response_revision:4779; }","duration":"125.267126ms","start":"2026-10-02T04:55:33.25489Z","end":"2026-10-02T04:55:33.380157Z","steps":["trace[2070042747] 'agreement among raft nodes before linearized reading'  (duration: 124.91541ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:55:33.380377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.419759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"}
{"level":"info","ts":"2026-10-02T04:55:33.380423Z","caller":"traceutil/trace.go:171","msg":"trace[726593072] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4780; }","duration":"125.505271ms","start":"2026-10-02T04:55:33.254907Z","end":"2026-10-02T04:55:33.380413Z","steps":["trace[726593072] 'agreement among raft nodes before linearized reading'  (duration: 125.341498ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:55:33.380124Z","caller":"traceutil/trace.go:171","msg":"trace[98806598] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"123.575254ms","start":"2026-10-02T04:55:33.256537Z","end":"2026-10-02T04:55:33.380112Z","steps":["trace[98806598] 'process raft request'  (duration: 123.110486ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:55:37.637244Z","caller":"traceutil/trace.go:171","msg":"trace[1915544874] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"176.674396ms","start":"2026-10-02T04:55:37.460541Z","end":"2026-10-02T04:55:37.637215Z","steps":["trace[1915544874] 'process raft request'  (duration: 176.481482ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:55:37.637805Z","caller":"traceutil/trace.go:171","msg":"trace[1591275406] linearizableReadLoop","detail":"{readStateIndex:5049; appliedIndex:5049; }","duration":"176.636714ms","start":"2026-10-02T04:55:37.461076Z","end":"2026-10-02T04:55:37.637712Z","steps":["trace[1591275406] 'read index received'  (duration: 176.581533ms)","trace[1591275406] 'applied index is now lower than readState.Index'  (duration: 52.791µs)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:55:37.637924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.820228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-02T04:55:37.638133Z","caller":"traceutil/trace.go:171","msg":"trace[1084196156] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4860; }","duration":"176.988551ms","start":"2026-10-02T04:55:37.461071Z","end":"2026-10-02T04:55:37.63806Z","steps":["trace[1084196156] 'agreement among raft nodes before linearized reading'  (duration: 176.733616ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:55:37.638259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.916332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"}
{"level":"info","ts":"2026-10-02T04:55:37.638347Z","caller":"traceutil/trace.go:171","msg":"trace[1878750918] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4860; }","duration":"164.029314ms","start":"2026-10-02T04:55:37.474302Z","end":"2026-10-02T04:55:37.638331Z","steps":["trace[1878750918] 'agreement among raft nodes before linearized reading'  (duration: 163.673617ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:55:43.045508Z","caller":"traceutil/trace.go:171","msg":"trace[420271383] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"146.82427ms","start":"2026-10-02T04:55:42.898663Z","end":"2026-10-02T04:55:43.045487Z","steps":["trace[420271383] 'process raft request'  (duration: 146.710228ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:55:43.327343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.454152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-10-02T04:55:43.327372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.904317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-10-02T04:55:43.327567Z","caller":"traceutil/trace.go:171","msg":"trace[860691112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4985; }","duration":"215.152971ms","start":"2026-10-02T04:55:43.112394Z","end":"2026-10-02T04:55:43.327547Z","steps":["trace[860691112] 'range keys from in-memory index tree'  (duration: 214.675813ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:55:43.327493Z","caller":"traceutil/trace.go:171","msg":"trace[909588049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4985; }","duration":"218.648095ms","start":"2026-10-02T04:55:43.108827Z","end":"2026-10-02T04:55:43.327475Z","steps":["trace[909588049] 'range keys from in-memory index tree'  (duration: 218.288439ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:55:56.556428Z","caller":"traceutil/trace.go:171","msg":"trace[493550379] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"191.807252ms","start":"2026-10-02T04:55:56.3646Z","end":"2026-10-02T04:55:56.556407Z","steps":["trace[493550379] 'process raft request'  (duration: 191.641089ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:55:56.906479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.516883ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9160498641690944247 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:5139 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-02T04:55:56.906581Z","caller":"traceutil/trace.go:171","msg":"trace[429593487] linearizableReadLoop","detail":"{readStateIndex:5354; appliedIndex:5353; }","duration":"350.188397ms","start":"2026-10-02T04:55:56.556376Z","end":"2026-10-02T04:55:56.906564Z","steps":["trace[429593487] 'read index received'  (duration: 161.407979ms)","trace[429593487] 'applied index is now lower than readState.Index'  (duration: 188.779418ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:55:56.906746Z","caller":"traceutil/trace.go:171","msg":"trace[764272778] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"378.472442ms","start":"2026-10-02T04:55:56.528203Z","end":"2026-10-02T04:55:56.906675Z","steps":["trace[764272778] 'process raft request'  (duration: 189.663524ms)","trace[764272778] 'compare'  (duration: 188.375801ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:55:56.90693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:55:56.528178Z","time spent":"378.663636ms","remote":"127.0.0.1:34954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:5139 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-10-02T04:55:56.907128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.037715ms","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-10-02T04:55:56.907215Z","caller":"traceutil/trace.go:171","msg":"trace[981541113] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5154; }","duration":"434.158217ms","start":"2026-10-02T04:55:56.47304Z","end":"2026-10-02T04:55:56.907198Z","steps":["trace[981541113] 'agreement among raft nodes before linearized reading'  (duration: 433.724959ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:55:56.907253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:55:56.472988Z","time spent":"434.256198ms","remote":"127.0.0.1:34858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "}
{"level":"info","ts":"2026-10-02T04:55:56.917229Z","caller":"traceutil/trace.go:171","msg":"trace[1846487360] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"347.734723ms","start":"2026-10-02T04:55:56.569471Z","end":"2026-10-02T04:55:56.917206Z","steps":["trace[1846487360] 'process raft request'  (duration: 347.54843ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:55:56.917349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.43074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"}
{"level":"info","ts":"2026-10-02T04:55:56.917431Z","caller":"traceutil/trace.go:171","msg":"trace[574808130] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5155; }","duration":"166.559843ms","start":"2026-10-02T04:55:56.750853Z","end":"2026-10-02T04:55:56.917413Z","steps":["trace[574808130] 'agreement among raft nodes before linearized reading'  (duration: 166.338949ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-02T04:55:56.917721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:55:56.569453Z","time spent":"347.863505ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" mod_revision:5054 > success:<request_put:<key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" value_size:10608 >> failure:<request_range:<key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" > >"}
{"level":"info","ts":"2026-10-02T04:55:57.23642Z","caller":"traceutil/trace.go:171","msg":"trace[444110216] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"139.905227ms","start":"2026-10-02T04:55:57.096491Z","end":"2026-10-02T04:55:57.236396Z","steps":["trace[444110216] 'process raft request'  (duration: 139.745244ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:55:57.236379Z","caller":"traceutil/trace.go:171","msg":"trace[1459425844] linearizableReadLoop","detail":"{readStateIndex:5361; appliedIndex:5359; }","duration":"139.381668ms","start":"2026-10-02T04:55:57.096971Z","end":"2026-10-02T04:55:57.236353Z","steps":["trace[1459425844] 'read index received'  (duration: 87.141037ms)","trace[1459425844] 'applied index is now lower than readState.Index'  (duration: 52.239631ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:55:57.236619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.633002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"}
{"level":"info","ts":"2026-10-02T04:55:57.236667Z","caller":"traceutil/trace.go:171","msg":"trace[2033169170] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5161; }","duration":"139.719903ms","start":"2026-10-02T04:55:57.096933Z","end":"2026-10-02T04:55:57.236652Z","steps":["trace[2033169170] 'agreement among raft nodes before linearized reading'  (duration: 139.53169ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:55:57.353893Z","caller":"traceutil/trace.go:171","msg":"trace[319522596] linearizableReadLoop","detail":"{readStateIndex:5362; appliedIndex:5361; }","duration":"109.159177ms","start":"2026-10-02T04:55:57.244711Z","end":"2026-10-02T04:55:57.353871Z","steps":["trace[319522596] 'read index received'  (duration: 81.815149ms)","trace[319522596] 'applied index is now lower than readState.Index'  (duration: 27.343208ms)"],"step_count":2}
{"level":"info","ts":"2026-10-02T04:55:57.354171Z","caller":"traceutil/trace.go:171","msg":"trace[166495356] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"111.654612ms","start":"2026-10-02T04:55:57.242503Z","end":"2026-10-02T04:55:57.354157Z","steps":["trace[166495356] 'process raft request'  (duration: 84.123761ms)","trace[166495356] 'compare'  (duration: 27.144874ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-02T04:55:57.354466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.728037ms","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-10-02T04:55:57.354565Z","caller":"traceutil/trace.go:171","msg":"trace[1221204724] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5162; }","duration":"109.836859ms","start":"2026-10-02T04:55:57.244706Z","end":"2026-10-02T04:55:57.354542Z","steps":["trace[1221204724] 'agreement among raft nodes before linearized reading'  (duration: 109.565514ms)"],"step_count":1}
{"level":"info","ts":"2026-10-02T04:56:31.872289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2902}
{"level":"info","ts":"2026-10-02T04:56:31.958563Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2902,"took":"84.774355ms","hash":1653209929,"current-db-size-bytes":52875264,"current-db-size":"53 MB","current-db-size-in-use-bytes":28368896,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-10-02T04:56:31.958626Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1653209929,"revision":2902,"compact-revision":-1}
{"level":"info","ts":"2026-10-02T05:01:31.879101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5489}
{"level":"info","ts":"2026-10-02T05:01:31.958195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5489,"took":"77.761648ms","hash":818011376,"current-db-size-bytes":52875264,"current-db-size":"53 MB","current-db-size-in-use-bytes":18763776,"current-db-size-in-use":"19 MB"}
{"level":"info","ts":"2026-10-02T05:01:31.958271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":818011376,"revision":5489,"compact-revision":2902}