{"level":"warn","ts":"2026-10-14T15:44:54.624333Z","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-14T15:44:54.624414Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.113: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.113:2380","--initial-cluster=instance=https://162.253.55.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.113: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-14T15:44:54.62448Z","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-14T15:44:54.62449Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-14T15:44:54.624513Z","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-14T15:44:54.625848Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"]} {"level":"info","ts":"2026-10-14T15:44:54.625937Z","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":16,"max-cpu-available":16,"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.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.113: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-14T15:44:54.642562Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.404426ms"} {"level":"info","ts":"2026-10-14T15:44:54.683272Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3b717bddf98d48e3","cluster-id":"cef0f7c69f0576fc"} {"level":"info","ts":"2026-10-14T15:44:54.683399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T15:44:54.683425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 0"} {"level":"info","ts":"2026-10-14T15:44:54.683439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3b717bddf98d48e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T15:44:54.683457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 1"} {"level":"info","ts":"2026-10-14T15:44:54.683508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"warn","ts":"2026-10-14T15:44:54.736737Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T15:44:54.774918Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T15:44:54.810302Z","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-14T15:44:54.827349Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3b717bddf98d48e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T15:44:54.828014Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3b717bddf98d48e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T15:44:54.828027Z","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-14T15:44:54.828116Z","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-14T15:44:54.828133Z","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-14T15:44:54.833531Z","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-14T15:44:54.833819Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3b717bddf98d48e3","initial-advertise-peer-urls":["https://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T15:44:54.833866Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T15:44:54.833976Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-14T15:44:54.834004Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-14T15:44:54.835572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"info","ts":"2026-10-14T15:44:54.835781Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","added-peer-id":"3b717bddf98d48e3","added-peer-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-14T15:44:54.884209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T15:44:54.884288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T15:44:54.884319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgPreVoteResp from 3b717bddf98d48e3 at term 1"} {"level":"info","ts":"2026-10-14T15:44:54.884339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became candidate at term 2"} {"level":"info","ts":"2026-10-14T15:44:54.88435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgVoteResp from 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-14T15:44:54.884371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became leader at term 2"} {"level":"info","ts":"2026-10-14T15:44:54.884383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3b717bddf98d48e3 elected leader 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-14T15:44:54.887099Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:44:54.891289Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3b717bddf98d48e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.113:2379]}","request-path":"/0/members/3b717bddf98d48e3/attributes","cluster-id":"cef0f7c69f0576fc","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T15:44:54.891454Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:44:54.891503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:44:54.894317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.113:2379"} {"level":"info","ts":"2026-10-14T15:44:54.894504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T15:44:54.900444Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:44:54.900595Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:44:54.900638Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:44:54.901636Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T15:44:54.901684Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-10-14T15:45:11.052791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.929336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T15:45:11.052971Z","caller":"traceutil/trace.go:171","msg":"trace[1689726317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"128.179641ms","start":"2026-10-14T15:45:10.92476Z","end":"2026-10-14T15:45:11.052939Z","steps":["trace[1689726317] 'range keys from in-memory index tree' (duration: 127.724933ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:39.344489Z","caller":"traceutil/trace.go:171","msg":"trace[2037546373] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"115.040339ms","start":"2026-10-14T15:45:39.229427Z","end":"2026-10-14T15:45:39.344467Z","steps":["trace[2037546373] 'process raft request' (duration: 114.915847ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:39.36337Z","caller":"traceutil/trace.go:171","msg":"trace[769531587] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"133.257036ms","start":"2026-10-14T15:45:39.230068Z","end":"2026-10-14T15:45:39.363325Z","steps":["trace[769531587] 'process raft request' (duration: 133.087363ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:39.363413Z","caller":"traceutil/trace.go:171","msg":"trace[480546672] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"133.232376ms","start":"2026-10-14T15:45:39.230151Z","end":"2026-10-14T15:45:39.363384Z","steps":["trace[480546672] 'process raft request' (duration: 133.109974ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:39.363557Z","caller":"traceutil/trace.go:171","msg":"trace[1439275303] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"128.542317ms","start":"2026-10-14T15:45:39.234955Z","end":"2026-10-14T15:45:39.363497Z","steps":["trace[1439275303] 'process raft request' (duration: 128.359574ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:39.363594Z","caller":"traceutil/trace.go:171","msg":"trace[946363405] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"117.280247ms","start":"2026-10-14T15:45:39.246285Z","end":"2026-10-14T15:45:39.363565Z","steps":["trace[946363405] 'process raft request' (duration: 117.119295ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:39.363837Z","caller":"traceutil/trace.go:171","msg":"trace[1454082593] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"113.817109ms","start":"2026-10-14T15:45:39.25Z","end":"2026-10-14T15:45:39.363817Z","steps":["trace[1454082593] 'process raft request' (duration: 113.444922ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:39.363851Z","caller":"traceutil/trace.go:171","msg":"trace[282068062] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"119.984383ms","start":"2026-10-14T15:45:39.243853Z","end":"2026-10-14T15:45:39.363837Z","steps":["trace[282068062] 'process raft request' (duration: 119.514245ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:39.363887Z","caller":"traceutil/trace.go:171","msg":"trace[375200360] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"106.618517ms","start":"2026-10-14T15:45:39.257255Z","end":"2026-10-14T15:45:39.363873Z","steps":["trace[375200360] 'process raft request' (duration: 106.229211ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:45.612372Z","caller":"traceutil/trace.go:171","msg":"trace[1897019707] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"125.228843ms","start":"2026-10-14T15:45:45.487096Z","end":"2026-10-14T15:45:45.612325Z","steps":["trace[1897019707] 'process raft request' (duration: 124.928966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:45.908132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.297994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:45:45.908228Z","caller":"traceutil/trace.go:171","msg":"trace[1361716069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"118.458338ms","start":"2026-10-14T15:45:45.789751Z","end":"2026-10-14T15:45:45.90821Z","steps":["trace[1361716069] 'range keys from in-memory index tree' (duration: 118.159212ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:46.327898Z","caller":"traceutil/trace.go:171","msg":"trace[1298383177] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"244.189242ms","start":"2026-10-14T15:45:46.083676Z","end":"2026-10-14T15:45:46.327865Z","steps":["trace[1298383177] 'process raft request' (duration: 238.05044ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:46.938532Z","caller":"traceutil/trace.go:171","msg":"trace[984055971] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"516.20093ms","start":"2026-10-14T15:45:46.422305Z","end":"2026-10-14T15:45:46.938506Z","steps":["trace[984055971] 'process raft request' (duration: 516.089258ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:46.939129Z","caller":"traceutil/trace.go:171","msg":"trace[144190035] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"148.413911ms","start":"2026-10-14T15:45:46.79065Z","end":"2026-10-14T15:45:46.939064Z","steps":["trace[144190035] 'read index received' (duration: 147.670555ms)","trace[144190035] 'applied index is now lower than readState.Index' (duration: 737.906µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:45:46.939206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:45:46.422278Z","time spent":"516.322123ms","remote":"127.0.0.1:53238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:45:46.939987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.889471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:45:46.940159Z","caller":"traceutil/trace.go:171","msg":"trace[725005299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"149.549096ms","start":"2026-10-14T15:45:46.790588Z","end":"2026-10-14T15:45:46.940137Z","steps":["trace[725005299] 'agreement among raft nodes before linearized reading' (duration: 148.633976ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:48.699502Z","caller":"traceutil/trace.go:171","msg":"trace[667553925] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"101.913531ms","start":"2026-10-14T15:45:48.597561Z","end":"2026-10-14T15:45:48.699474Z","steps":["trace[667553925] 'process raft request' (duration: 100.860589ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:03.445502Z","caller":"traceutil/trace.go:171","msg":"trace[1523886358] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"281.755499ms","start":"2026-10-14T15:46:03.163721Z","end":"2026-10-14T15:46:03.445476Z","steps":["trace[1523886358] 'process raft request' (duration: 281.604476ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:27.899038Z","caller":"traceutil/trace.go:171","msg":"trace[670796688] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"107.152296ms","start":"2026-10-14T15:47:27.791856Z","end":"2026-10-14T15:47:27.899008Z","steps":["trace[670796688] 'read index received' (duration: 106.86102ms)","trace[670796688] 'applied index is now lower than readState.Index' (duration: 289.786µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:47:27.899132Z","caller":"traceutil/trace.go:171","msg":"trace[889794132] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"191.578641ms","start":"2026-10-14T15:47:27.707508Z","end":"2026-10-14T15:47:27.899087Z","steps":["trace[889794132] 'process raft request' (duration: 191.304555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:27.899295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.415961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:47:27.899374Z","caller":"traceutil/trace.go:171","msg":"trace[1863595329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"107.572274ms","start":"2026-10-14T15:47:27.791785Z","end":"2026-10-14T15:47:27.899357Z","steps":["trace[1863595329] 'agreement among raft nodes before linearized reading' (duration: 107.406411ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:28.627593Z","caller":"traceutil/trace.go:171","msg":"trace[1362335774] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"281.331047ms","start":"2026-10-14T15:47:28.346232Z","end":"2026-10-14T15:47:28.627563Z","steps":["trace[1362335774] 'read index received' (duration: 239.321528ms)","trace[1362335774] 'applied index is now lower than readState.Index' (duration: 42.007769ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:47:28.627656Z","caller":"traceutil/trace.go:171","msg":"trace[1771635363] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"294.044209ms","start":"2026-10-14T15:47:28.333584Z","end":"2026-10-14T15:47:28.627628Z","steps":["trace[1771635363] 'process raft request' (duration: 251.872937ms)","trace[1771635363] 'compare' (duration: 41.904596ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:47:28.627812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.558791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T15:47:28.627921Z","caller":"traceutil/trace.go:171","msg":"trace[1044248344] range","detail":"{range_begin:/registry/masterleases/162.253.55.113; range_end:; response_count:1; response_revision:1879; }","duration":"281.683744ms","start":"2026-10-14T15:47:28.346188Z","end":"2026-10-14T15:47:28.627872Z","steps":["trace[1044248344] 'agreement among raft nodes before linearized reading' (duration: 281.49092ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:28.672772Z","caller":"traceutil/trace.go:171","msg":"trace[1850482179] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"279.523299ms","start":"2026-10-14T15:47:28.393222Z","end":"2026-10-14T15:47:28.672745Z","steps":["trace[1850482179] 'process raft request' (duration: 279.453437ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:28.672963Z","caller":"traceutil/trace.go:171","msg":"trace[292258263] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"294.841135ms","start":"2026-10-14T15:47:28.37811Z","end":"2026-10-14T15:47:28.672951Z","steps":["trace[292258263] 'process raft request' (duration: 294.435277ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:34.790636Z","caller":"traceutil/trace.go:171","msg":"trace[1913355047] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"129.814914ms","start":"2026-10-14T15:47:34.660783Z","end":"2026-10-14T15:47:34.790597Z","steps":["trace[1913355047] 'process raft request' (duration: 129.585149ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:34.810396Z","caller":"traceutil/trace.go:171","msg":"trace[321384851] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"115.14742ms","start":"2026-10-14T15:47:34.695213Z","end":"2026-10-14T15:47:34.81036Z","steps":["trace[321384851] 'process raft request' (duration: 114.897455ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:34.810683Z","caller":"traceutil/trace.go:171","msg":"trace[2117498557] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"115.397056ms","start":"2026-10-14T15:47:34.695261Z","end":"2026-10-14T15:47:34.810658Z","steps":["trace[2117498557] 'process raft request' (duration: 115.053089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:41.607401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.077264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:47:41.609288Z","caller":"traceutil/trace.go:171","msg":"trace[239370979] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1993; }","duration":"184.006114ms","start":"2026-10-14T15:47:41.425252Z","end":"2026-10-14T15:47:41.609258Z","steps":["trace[239370979] 'count revisions from in-memory index tree' (duration: 181.941521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:41.607445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.875438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-14T15:47:41.609482Z","caller":"traceutil/trace.go:171","msg":"trace[1593478788] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1993; }","duration":"166.931551ms","start":"2026-10-14T15:47:41.442485Z","end":"2026-10-14T15:47:41.609417Z","steps":["trace[1593478788] 'range keys from in-memory index tree' (duration: 164.640354ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:13.062648Z","caller":"traceutil/trace.go:171","msg":"trace[657927339] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"104.991251ms","start":"2026-10-14T15:48:12.957631Z","end":"2026-10-14T15:48:13.062622Z","steps":["trace[657927339] 'process raft request' (duration: 104.825827ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:13.06293Z","caller":"traceutil/trace.go:171","msg":"trace[1513716432] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"102.307361ms","start":"2026-10-14T15:48:12.960599Z","end":"2026-10-14T15:48:13.062907Z","steps":["trace[1513716432] 'process raft request' (duration: 101.971744ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:18.662667Z","caller":"traceutil/trace.go:171","msg":"trace[1767843476] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"186.055609ms","start":"2026-10-14T15:48:18.47658Z","end":"2026-10-14T15:48:18.662636Z","steps":["trace[1767843476] 'process raft request' (duration: 169.277985ms)","trace[1767843476] 'compare' (duration: 16.653321ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:31.505521Z","caller":"traceutil/trace.go:171","msg":"trace[130883761] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"130.580146ms","start":"2026-10-14T15:49:31.374921Z","end":"2026-10-14T15:49:31.505501Z","steps":["trace[130883761] 'process raft request' (duration: 129.522142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:31.666415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.551031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:49:31.666588Z","caller":"traceutil/trace.go:171","msg":"trace[1961734209] linearizableReadLoop","detail":"{readStateIndex:2895; appliedIndex:2892; }","duration":"163.169312ms","start":"2026-10-14T15:49:31.503404Z","end":"2026-10-14T15:49:31.666573Z","steps":["trace[1961734209] 'read index received' (duration: 1.167168ms)","trace[1961734209] 'applied index is now lower than readState.Index' (duration: 162.001234ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:31.666638Z","caller":"traceutil/trace.go:171","msg":"trace[1669639773] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"280.740903ms","start":"2026-10-14T15:49:31.385858Z","end":"2026-10-14T15:49:31.666599Z","steps":["trace[1669639773] 'process raft request' (duration: 156.676879ms)","trace[1669639773] 'compare' (duration: 123.440649ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:31.666881Z","caller":"traceutil/trace.go:171","msg":"trace[1389805176] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"278.189583ms","start":"2026-10-14T15:49:31.388665Z","end":"2026-10-14T15:49:31.666854Z","steps":["trace[1389805176] 'process raft request' (duration: 277.849445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:49:31.667048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.695705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-14T15:49:31.667072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.668533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T15:49:31.667107Z","caller":"traceutil/trace.go:171","msg":"trace[300063153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2786; }","duration":"120.790887ms","start":"2026-10-14T15:49:31.5463Z","end":"2026-10-14T15:49:31.667091Z","steps":["trace[300063153] 'agreement among raft nodes before linearized reading' (duration: 120.651414ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:31.667123Z","caller":"traceutil/trace.go:171","msg":"trace[1349809912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2786; }","duration":"163.758036ms","start":"2026-10-14T15:49:31.503351Z","end":"2026-10-14T15:49:31.667109Z","steps":["trace[1349809912] 'agreement among raft nodes before linearized reading' (duration: 163.53195ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:31.804725Z","caller":"traceutil/trace.go:171","msg":"trace[812164721] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"132.684896ms","start":"2026-10-14T15:49:31.672011Z","end":"2026-10-14T15:49:31.804696Z","steps":["trace[812164721] 'process raft request' (duration: 105.583109ms)","trace[812164721] 'compare' (duration: 26.914512ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:49:31.804934Z","caller":"traceutil/trace.go:171","msg":"trace[1490172843] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"132.83282ms","start":"2026-10-14T15:49:31.672079Z","end":"2026-10-14T15:49:31.804911Z","steps":["trace[1490172843] 'process raft request' (duration: 132.543593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:52:54.732592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.688207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:52:54.732703Z","caller":"traceutil/trace.go:171","msg":"trace[363375476] transaction","detail":"{read_only:false; response_revision:4057; number_of_response:1; }","duration":"297.819899ms","start":"2026-10-14T15:52:54.434867Z","end":"2026-10-14T15:52:54.732687Z","steps":["trace[363375476] 'process raft request' (duration: 193.969888ms)","trace[363375476] 'compare' (duration: 103.567065ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:52:54.74992Z","caller":"traceutil/trace.go:171","msg":"trace[933058601] transaction","detail":"{read_only:false; response_revision:4058; number_of_response:1; }","duration":"313.878849ms","start":"2026-10-14T15:52:54.436009Z","end":"2026-10-14T15:52:54.749888Z","steps":["trace[933058601] 'process raft request' (duration: 313.739246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:52:54.750172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:52:54.435984Z","time spent":"314.033192ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:54:25.300914Z","caller":"traceutil/trace.go:171","msg":"trace[1499298725] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"185.794611ms","start":"2026-10-14T15:54:25.115086Z","end":"2026-10-14T15:54:25.300881Z","steps":["trace[1499298725] 'process raft request' (duration: 182.523065ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:25.301202Z","caller":"traceutil/trace.go:171","msg":"trace[705978732] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"161.710659ms","start":"2026-10-14T15:54:25.139465Z","end":"2026-10-14T15:54:25.301176Z","steps":["trace[705978732] 'process raft request' (duration: 161.405092ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:25.301227Z","caller":"traceutil/trace.go:171","msg":"trace[1261171777] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"183.421965ms","start":"2026-10-14T15:54:25.117771Z","end":"2026-10-14T15:54:25.301193Z","steps":["trace[1261171777] 'process raft request' (duration: 182.993435ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:29.574293Z","caller":"traceutil/trace.go:171","msg":"trace[1811329938] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"146.428923ms","start":"2026-10-14T15:54:29.427819Z","end":"2026-10-14T15:54:29.574248Z","steps":["trace[1811329938] 'process raft request' (duration: 95.115047ms)","trace[1811329938] 'compare' (duration: 51.04895ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:38.523417Z","caller":"traceutil/trace.go:171","msg":"trace[222122754] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"229.635901ms","start":"2026-10-14T15:54:38.293751Z","end":"2026-10-14T15:54:38.523387Z","steps":["trace[222122754] 'process raft request' (duration: 220.419277ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:38.523774Z","caller":"traceutil/trace.go:171","msg":"trace[1521198776] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"229.99557ms","start":"2026-10-14T15:54:38.29376Z","end":"2026-10-14T15:54:38.523756Z","steps":["trace[1521198776] 'process raft request' (duration: 229.56035ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:38.523871Z","caller":"traceutil/trace.go:171","msg":"trace[35295174] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"229.699332ms","start":"2026-10-14T15:54:38.294154Z","end":"2026-10-14T15:54:38.523853Z","steps":["trace[35295174] 'process raft request' (duration: 229.503608ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:38.523828Z","caller":"traceutil/trace.go:171","msg":"trace[1026682054] linearizableReadLoop","detail":"{readStateIndex:5671; appliedIndex:5667; }","duration":"177.475106ms","start":"2026-10-14T15:54:38.346329Z","end":"2026-10-14T15:54:38.523804Z","steps":["trace[1026682054] 'read index received' (duration: 33.139163ms)","trace[1026682054] 'applied index is now lower than readState.Index' (duration: 144.334563ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:54:38.524103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.749492ms","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":"warn","ts":"2026-10-14T15:54:38.524178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.012276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T15:54:38.524264Z","caller":"traceutil/trace.go:171","msg":"trace[878912865] range","detail":"{range_begin:/registry/masterleases/162.253.55.113; range_end:; response_count:1; response_revision:5462; }","duration":"153.147199ms","start":"2026-10-14T15:54:38.37109Z","end":"2026-10-14T15:54:38.524237Z","steps":["trace[878912865] 'agreement among raft nodes before linearized reading' (duration: 152.933354ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:38.524175Z","caller":"traceutil/trace.go:171","msg":"trace[980636975] 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:5462; }","duration":"177.860404ms","start":"2026-10-14T15:54:38.346293Z","end":"2026-10-14T15:54:38.524154Z","steps":["trace[980636975] 'agreement among raft nodes before linearized reading' (duration: 177.617128ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:42.404238Z","caller":"traceutil/trace.go:171","msg":"trace[991094525] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"104.288031ms","start":"2026-10-14T15:54:42.299921Z","end":"2026-10-14T15:54:42.404209Z","steps":["trace[991094525] 'process raft request' (duration: 104.117657ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:50.602275Z","caller":"traceutil/trace.go:171","msg":"trace[506385534] linearizableReadLoop","detail":"{readStateIndex:5892; appliedIndex:5891; }","duration":"102.819876ms","start":"2026-10-14T15:54:50.499414Z","end":"2026-10-14T15:54:50.602234Z","steps":["trace[506385534] 'read index received' (duration: 48.875369ms)","trace[506385534] 'applied index is now lower than readState.Index' (duration: 53.943097ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:50.602354Z","caller":"traceutil/trace.go:171","msg":"trace[22690895] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"175.350817ms","start":"2026-10-14T15:54:50.426975Z","end":"2026-10-14T15:54:50.602325Z","steps":["trace[22690895] 'process raft request' (duration: 121.40454ms)","trace[22690895] 'compare' (duration: 53.691031ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:54:50.602567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.133504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08835e94-13a5-4939-acc8-b11ad4dda929\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-10-14T15:54:50.602617Z","caller":"traceutil/trace.go:171","msg":"trace[1791005889] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08835e94-13a5-4939-acc8-b11ad4dda929; range_end:; response_count:1; response_revision:5679; }","duration":"103.263947ms","start":"2026-10-14T15:54:50.499339Z","end":"2026-10-14T15:54:50.602603Z","steps":["trace[1791005889] 'agreement among raft nodes before linearized reading' (duration: 103.031911ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:50.841999Z","caller":"traceutil/trace.go:171","msg":"trace[1328458995] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"230.022841ms","start":"2026-10-14T15:54:50.611929Z","end":"2026-10-14T15:54:50.841952Z","steps":["trace[1328458995] 'process raft request' (duration: 193.052239ms)","trace[1328458995] 'compare' (duration: 36.378298ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:50.843153Z","caller":"traceutil/trace.go:171","msg":"trace[488284510] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"230.954683ms","start":"2026-10-14T15:54:50.612126Z","end":"2026-10-14T15:54:50.843081Z","steps":["trace[488284510] 'process raft request' (duration: 229.679743ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:50.843282Z","caller":"traceutil/trace.go:171","msg":"trace[184898497] transaction","detail":"{read_only:false; response_revision:5683; number_of_response:1; }","duration":"230.941273ms","start":"2026-10-14T15:54:50.612318Z","end":"2026-10-14T15:54:50.843259Z","steps":["trace[184898497] 'process raft request' (duration: 230.505413ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:50.843688Z","caller":"traceutil/trace.go:171","msg":"trace[1713352656] transaction","detail":"{read_only:false; response_revision:5685; number_of_response:1; }","duration":"226.790536ms","start":"2026-10-14T15:54:50.616881Z","end":"2026-10-14T15:54:50.843671Z","steps":["trace[1713352656] 'process raft request' (duration: 226.473369ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:50.843813Z","caller":"traceutil/trace.go:171","msg":"trace[1897294511] transaction","detail":"{read_only:false; response_revision:5684; number_of_response:1; }","duration":"230.100143ms","start":"2026-10-14T15:54:50.6137Z","end":"2026-10-14T15:54:50.8438Z","steps":["trace[1897294511] 'process raft request' (duration: 229.585501ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:50.843901Z","caller":"traceutil/trace.go:171","msg":"trace[133823770] transaction","detail":"{read_only:false; response_revision:5686; number_of_response:1; }","duration":"227.008101ms","start":"2026-10-14T15:54:50.616884Z","end":"2026-10-14T15:54:50.843893Z","steps":["trace[133823770] 'process raft request' (duration: 226.52136ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:55.080906Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2914} {"level":"info","ts":"2026-10-14T15:54:55.159747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2914,"took":"76.869542ms","hash":1669263197,"current-db-size-bytes":56385536,"current-db-size":"56 MB","current-db-size-in-use-bytes":31776768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T15:54:55.159836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1669263197,"revision":2914,"compact-revision":-1} {"level":"info","ts":"2026-10-14T15:54:59.971119Z","caller":"traceutil/trace.go:171","msg":"trace[178078959] linearizableReadLoop","detail":"{readStateIndex:6049; appliedIndex:6048; }","duration":"124.246146ms","start":"2026-10-14T15:54:59.846848Z","end":"2026-10-14T15:54:59.971095Z","steps":["trace[178078959] 'read index received' (duration: 124.098732ms)","trace[178078959] 'applied index is now lower than readState.Index' (duration: 146.304µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:59.97118Z","caller":"traceutil/trace.go:171","msg":"trace[1924111156] transaction","detail":"{read_only:false; response_revision:5829; number_of_response:1; }","duration":"134.484504ms","start":"2026-10-14T15:54:59.836661Z","end":"2026-10-14T15:54:59.971145Z","steps":["trace[1924111156] 'process raft request' (duration: 134.263219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:59.971358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.498252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T15:54:59.971402Z","caller":"traceutil/trace.go:171","msg":"trace[357099962] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5829; }","duration":"124.634595ms","start":"2026-10-14T15:54:59.846756Z","end":"2026-10-14T15:54:59.971391Z","steps":["trace[357099962] 'agreement among raft nodes before linearized reading' (duration: 124.44428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:04.935475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218865597272098,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T15:55:04.974237Z","caller":"traceutil/trace.go:171","msg":"trace[1573919350] transaction","detail":"{read_only:false; response_revision:5856; number_of_response:1; }","duration":"542.086815ms","start":"2026-10-14T15:55:04.432135Z","end":"2026-10-14T15:55:04.974222Z","steps":["trace[1573919350] 'process raft request' (duration: 541.929382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:04.974346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:04.432113Z","time spent":"542.171967ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:05.171374Z","caller":"traceutil/trace.go:171","msg":"trace[176560535] linearizableReadLoop","detail":"{readStateIndex:6078; appliedIndex:6076; }","duration":"736.2348ms","start":"2026-10-14T15:55:04.435099Z","end":"2026-10-14T15:55:05.171334Z","steps":["trace[176560535] 'read index received' (duration: 539.069185ms)","trace[176560535] 'applied index is now lower than readState.Index' (duration: 197.164255ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:05.171478Z","caller":"traceutil/trace.go:171","msg":"trace[1491089511] transaction","detail":"{read_only:false; response_revision:5857; number_of_response:1; }","duration":"243.953786ms","start":"2026-10-14T15:55:04.927491Z","end":"2026-10-14T15:55:05.171445Z","steps":["trace[1491089511] 'process raft request' (duration: 221.990304ms)","trace[1491089511] 'compare' (duration: 21.663055ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:05.171659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.535457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-10-14T15:55:05.171735Z","caller":"traceutil/trace.go:171","msg":"trace[1177833973] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5857; }","duration":"736.640449ms","start":"2026-10-14T15:55:04.435075Z","end":"2026-10-14T15:55:05.171716Z","steps":["trace[1177833973] 'agreement among raft nodes before linearized reading' (duration: 736.399603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.171778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:04.435065Z","time spent":"736.70559ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9660,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-10-14T15:55:05.171875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.953766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-10-14T15:55:05.171892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.741479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-14T15:55:05.171998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.375527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18de706703f9c057\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-10-14T15:55:05.172043Z","caller":"traceutil/trace.go:171","msg":"trace[1758661335] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18de706703f9c057; range_end:; response_count:1; response_revision:5857; }","duration":"193.460599ms","start":"2026-10-14T15:55:04.978569Z","end":"2026-10-14T15:55:05.172029Z","steps":["trace[1758661335] 'agreement among raft nodes before linearized reading' (duration: 193.300046ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.172007Z","caller":"traceutil/trace.go:171","msg":"trace[1151868276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5857; }","duration":"203.901262ms","start":"2026-10-14T15:55:04.968087Z","end":"2026-10-14T15:55:05.171988Z","steps":["trace[1151868276] 'agreement among raft nodes before linearized reading' (duration: 203.602155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.172075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.024142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T15:55:05.17213Z","caller":"traceutil/trace.go:171","msg":"trace[14352860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5857; }","duration":"200.110294ms","start":"2026-10-14T15:55:04.972002Z","end":"2026-10-14T15:55:05.172112Z","steps":["trace[14352860] 'agreement among raft nodes before linearized reading' (duration: 199.94399ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.171977Z","caller":"traceutil/trace.go:171","msg":"trace[1288764827] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5857; }","duration":"616.10317ms","start":"2026-10-14T15:55:04.555852Z","end":"2026-10-14T15:55:05.171956Z","steps":["trace[1288764827] 'agreement among raft nodes before linearized reading' (duration: 615.773302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.172297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:04.555825Z","time spent":"616.452758ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-14T15:55:05.171853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.32335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:55:05.172554Z","caller":"traceutil/trace.go:171","msg":"trace[1491005855] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5857; }","duration":"437.097218ms","start":"2026-10-14T15:55:04.735435Z","end":"2026-10-14T15:55:05.172532Z","steps":["trace[1491005855] 'agreement among raft nodes before linearized reading' (duration: 436.280749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.172225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.266743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-10-14T15:55:05.172612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:04.73541Z","time spent":"437.1821ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-14T15:55:05.172612Z","caller":"traceutil/trace.go:171","msg":"trace[648518131] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5857; }","duration":"519.691803ms","start":"2026-10-14T15:55:04.652903Z","end":"2026-10-14T15:55:05.172595Z","steps":["trace[648518131] 'agreement among raft nodes before linearized reading' (duration: 519.245592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.17267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:04.652879Z","time spent":"519.773314ms","remote":"127.0.0.1:53394","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-10-14T15:55:05.541842Z","caller":"traceutil/trace.go:171","msg":"trace[298363297] transaction","detail":"{read_only:false; response_revision:5861; number_of_response:1; }","duration":"352.141197ms","start":"2026-10-14T15:55:05.189665Z","end":"2026-10-14T15:55:05.541806Z","steps":["trace[298363297] 'process raft request' (duration: 352.024694ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.541887Z","caller":"traceutil/trace.go:171","msg":"trace[188440607] transaction","detail":"{read_only:false; response_revision:5860; number_of_response:1; }","duration":"363.309678ms","start":"2026-10-14T15:55:05.178554Z","end":"2026-10-14T15:55:05.541864Z","steps":["trace[188440607] 'process raft request' (duration: 363.071093ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.541889Z","caller":"traceutil/trace.go:171","msg":"trace[71724432] transaction","detail":"{read_only:false; response_revision:5859; number_of_response:1; }","duration":"364.375773ms","start":"2026-10-14T15:55:05.17747Z","end":"2026-10-14T15:55:05.541846Z","steps":["trace[71724432] 'process raft request' (duration: 363.713478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.541995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.178538Z","time spent":"363.39735ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:05.541997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.189649Z","time spent":"352.262811ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:05.542131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.177451Z","time spent":"364.538056ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:05.542156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.42441ms","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-14T15:55:05.542243Z","caller":"traceutil/trace.go:171","msg":"trace[1237260988] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5861; }","duration":"363.552214ms","start":"2026-10-14T15:55:05.178668Z","end":"2026-10-14T15:55:05.54222Z","steps":["trace[1237260988] 'agreement among raft nodes before linearized reading' (duration: 363.272447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.542298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.178655Z","time spent":"363.634635ms","remote":"127.0.0.1:53394","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":"warn","ts":"2026-10-14T15:55:05.542342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.419577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:55:05.542386Z","caller":"traceutil/trace.go:171","msg":"trace[985183638] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:5861; }","duration":"365.483128ms","start":"2026-10-14T15:55:05.176891Z","end":"2026-10-14T15:55:05.542374Z","steps":["trace[985183638] 'agreement among raft nodes before linearized reading' (duration: 365.378196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.542416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.176882Z","time spent":"365.526369ms","remote":"127.0.0.1:35710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":11,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-10-14T15:55:05.542538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.639103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-10-14T15:55:05.542648Z","caller":"traceutil/trace.go:171","msg":"trace[528861906] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5861; }","duration":"359.794646ms","start":"2026-10-14T15:55:05.182814Z","end":"2026-10-14T15:55:05.542609Z","steps":["trace[528861906] 'agreement among raft nodes before linearized reading' (duration: 359.09759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.542702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.182805Z","time spent":"359.881768ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-10-14T15:55:05.541804Z","caller":"traceutil/trace.go:171","msg":"trace[2131166535] linearizableReadLoop","detail":"{readStateIndex:6082; appliedIndex:6079; }","duration":"321.125034ms","start":"2026-10-14T15:55:05.220652Z","end":"2026-10-14T15:55:05.541777Z","steps":["trace[2131166535] 'read index received' (duration: 320.620963ms)","trace[2131166535] 'applied index is now lower than readState.Index' (duration: 501.921µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:05.763374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.821182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:55:05.76394Z","caller":"traceutil/trace.go:171","msg":"trace[35737756] transaction","detail":"{read_only:false; response_revision:5862; number_of_response:1; }","duration":"436.208016ms","start":"2026-10-14T15:55:05.327689Z","end":"2026-10-14T15:55:05.763897Z","steps":["trace[35737756] 'process raft request' (duration: 291.748309ms)","trace[35737756] 'compare' (duration: 143.571666ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:05.764109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.327655Z","time spent":"436.36851ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T15:55:05.764298Z","caller":"traceutil/trace.go:171","msg":"trace[1377720020] transaction","detail":"{read_only:false; response_revision:5863; number_of_response:1; }","duration":"302.019449ms","start":"2026-10-14T15:55:05.46222Z","end":"2026-10-14T15:55:05.764239Z","steps":["trace[1377720020] 'process raft request' (duration: 301.673651ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.764404Z","caller":"traceutil/trace.go:171","msg":"trace[797938851] linearizableReadLoop","detail":"{readStateIndex:6085; appliedIndex:6082; }","duration":"221.456402ms","start":"2026-10-14T15:55:05.542924Z","end":"2026-10-14T15:55:05.76438Z","steps":["trace[797938851] 'read index received' (duration: 76.656087ms)","trace[797938851] 'applied index is now lower than readState.Index' (duration: 144.798804ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:05.764536Z","caller":"traceutil/trace.go:171","msg":"trace[1194691316] transaction","detail":"{read_only:false; response_revision:5864; number_of_response:1; }","duration":"252.836243ms","start":"2026-10-14T15:55:05.511662Z","end":"2026-10-14T15:55:05.764498Z","steps":["trace[1194691316] 'process raft request' (duration: 252.400343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.764545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.462183Z","time spent":"302.219164ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:05.764622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.389309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:55:05.764674Z","caller":"traceutil/trace.go:171","msg":"trace[910101061] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5864; }","duration":"269.490831ms","start":"2026-10-14T15:55:05.495171Z","end":"2026-10-14T15:55:05.764661Z","steps":["trace[910101061] 'agreement among raft nodes before linearized reading' (duration: 269.380529ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.974032Z","caller":"traceutil/trace.go:171","msg":"trace[614797791] transaction","detail":"{read_only:false; response_revision:5866; number_of_response:1; }","duration":"424.79316ms","start":"2026-10-14T15:55:05.549209Z","end":"2026-10-14T15:55:05.974002Z","steps":["trace[614797791] 'process raft request' (duration: 424.696988ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.97405Z","caller":"traceutil/trace.go:171","msg":"trace[218163084] transaction","detail":"{read_only:false; response_revision:5865; number_of_response:1; }","duration":"425.135008ms","start":"2026-10-14T15:55:05.548873Z","end":"2026-10-14T15:55:05.974008Z","steps":["trace[218163084] 'process raft request' (duration: 341.565821ms)","trace[218163084] 'compare' (duration: 83.308681ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:05.974191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.549191Z","time spent":"424.916854ms","remote":"127.0.0.1:53396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:05.97432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.548852Z","time spent":"425.285932ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:05.97436Z","caller":"traceutil/trace.go:171","msg":"trace[920269827] transaction","detail":"{read_only:false; response_revision:5867; number_of_response:1; }","duration":"424.7655ms","start":"2026-10-14T15:55:05.549563Z","end":"2026-10-14T15:55:05.974328Z","steps":["trace[920269827] 'process raft request' (duration: 424.403011ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.97444Z","caller":"traceutil/trace.go:171","msg":"trace[597182453] linearizableReadLoop","detail":"{readStateIndex:6089; appliedIndex:6085; }","duration":"209.952184ms","start":"2026-10-14T15:55:05.764453Z","end":"2026-10-14T15:55:05.974405Z","steps":["trace[597182453] 'read index received' (duration: 125.978286ms)","trace[597182453] 'applied index is now lower than readState.Index' (duration: 83.970468ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:05.974544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.54955Z","time spent":"424.885952ms","remote":"127.0.0.1:53396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:05.9745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.279442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T15:55:05.974609Z","caller":"traceutil/trace.go:171","msg":"trace[2040211703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5868; }","duration":"413.418626ms","start":"2026-10-14T15:55:05.561171Z","end":"2026-10-14T15:55:05.974589Z","steps":["trace[2040211703] 'agreement among raft nodes before linearized reading' (duration: 413.235752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.974624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.122691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1478"} {"level":"warn","ts":"2026-10-14T15:55:05.974649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.561162Z","time spent":"413.477527ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-14T15:55:05.974674Z","caller":"traceutil/trace.go:171","msg":"trace[1242985408] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5868; }","duration":"426.191714ms","start":"2026-10-14T15:55:05.548466Z","end":"2026-10-14T15:55:05.974658Z","steps":["trace[1242985408] 'agreement among raft nodes before linearized reading' (duration: 426.002899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.974683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.697504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T15:55:05.974705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.326165ms","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":"warn","ts":"2026-10-14T15:55:05.97471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.548457Z","time spent":"426.245045ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":1501,"request content":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"info","ts":"2026-10-14T15:55:05.974739Z","caller":"traceutil/trace.go:171","msg":"trace[573673133] 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:5868; }","duration":"344.406997ms","start":"2026-10-14T15:55:05.630319Z","end":"2026-10-14T15:55:05.974726Z","steps":["trace[573673133] 'agreement among raft nodes before linearized reading' (duration: 344.298895ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.974744Z","caller":"traceutil/trace.go:171","msg":"trace[766443867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5868; }","duration":"184.821858ms","start":"2026-10-14T15:55:05.7899Z","end":"2026-10-14T15:55:05.974722Z","steps":["trace[766443867] 'agreement among raft nodes before linearized reading' (duration: 184.703495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.974766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.630297Z","time spent":"344.462059ms","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-10-14T15:55:05.974839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.177623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:1270"} {"level":"warn","ts":"2026-10-14T15:55:05.97488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.870952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T15:55:05.974968Z","caller":"traceutil/trace.go:171","msg":"trace[811417357] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:5868; }","duration":"426.433859ms","start":"2026-10-14T15:55:05.548505Z","end":"2026-10-14T15:55:05.974939Z","steps":["trace[811417357] 'agreement among raft nodes before linearized reading' (duration: 425.927067ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.974981Z","caller":"traceutil/trace.go:171","msg":"trace[1590271843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5868; }","duration":"307.036026ms","start":"2026-10-14T15:55:05.667916Z","end":"2026-10-14T15:55:05.974952Z","steps":["trace[1590271843] 'agreement among raft nodes before linearized reading' (duration: 306.712449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.975013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.953822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"warn","ts":"2026-10-14T15:55:05.975038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.667875Z","time spent":"307.154559ms","remote":"127.0.0.1:52960","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-14T15:55:05.975047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.548497Z","time spent":"426.534931ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1293,"request content":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-grafana\" "} {"level":"warn","ts":"2026-10-14T15:55:05.975067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.434724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16552"} {"level":"info","ts":"2026-10-14T15:55:05.975149Z","caller":"traceutil/trace.go:171","msg":"trace[1221383627] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5868; }","duration":"388.571297ms","start":"2026-10-14T15:55:05.586558Z","end":"2026-10-14T15:55:05.97513Z","steps":["trace[1221383627] 'agreement among raft nodes before linearized reading' (duration: 388.29049ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.975089Z","caller":"traceutil/trace.go:171","msg":"trace[745602362] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5868; }","duration":"418.070534ms","start":"2026-10-14T15:55:05.556997Z","end":"2026-10-14T15:55:05.975068Z","steps":["trace[745602362] 'agreement among raft nodes before linearized reading' (duration: 417.780377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.975234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.586499Z","time spent":"388.720401ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16575,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-10-14T15:55:05.975264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.556976Z","time spent":"418.257398ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9660,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-10-14T15:55:05.975666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.229548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1462"} {"level":"info","ts":"2026-10-14T15:55:05.975714Z","caller":"traceutil/trace.go:171","msg":"trace[810707861] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5868; }","duration":"427.321ms","start":"2026-10-14T15:55:05.548379Z","end":"2026-10-14T15:55:05.9757Z","steps":["trace[810707861] 'agreement among raft nodes before linearized reading' (duration: 426.185393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.975741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.548353Z","time spent":"427.381251ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1485,"request content":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-10-14T15:55:05.976611Z","caller":"traceutil/trace.go:171","msg":"trace[147058201] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"425.138968ms","start":"2026-10-14T15:55:05.550717Z","end":"2026-10-14T15:55:05.975855Z","steps":["trace[147058201] 'process raft request' (duration: 423.452589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.976797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.550699Z","time spent":"425.983719ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:06.21828Z","caller":"traceutil/trace.go:171","msg":"trace[1284952418] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"236.455152ms","start":"2026-10-14T15:55:05.981778Z","end":"2026-10-14T15:55:06.218233Z","steps":["trace[1284952418] 'process raft request' (duration: 139.792999ms)","trace[1284952418] 'compare' (duration: 96.442238ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:06.21839Z","caller":"traceutil/trace.go:171","msg":"trace[2009523389] transaction","detail":"{read_only:false; response_revision:5871; number_of_response:1; }","duration":"236.508033ms","start":"2026-10-14T15:55:05.98186Z","end":"2026-10-14T15:55:06.218368Z","steps":["trace[2009523389] 'process raft request' (duration: 236.279258ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.218574Z","caller":"traceutil/trace.go:171","msg":"trace[2069825069] transaction","detail":"{read_only:false; response_revision:5873; number_of_response:1; }","duration":"236.281757ms","start":"2026-10-14T15:55:05.98225Z","end":"2026-10-14T15:55:06.218532Z","steps":["trace[2069825069] 'process raft request' (duration: 235.965589ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.218691Z","caller":"traceutil/trace.go:171","msg":"trace[771758990] transaction","detail":"{read_only:false; response_revision:5872; number_of_response:1; }","duration":"236.525203ms","start":"2026-10-14T15:55:05.982143Z","end":"2026-10-14T15:55:06.218668Z","steps":["trace[771758990] 'process raft request' (duration: 236.037832ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.218713Z","caller":"traceutil/trace.go:171","msg":"trace[1524613722] transaction","detail":"{read_only:false; response_revision:5874; number_of_response:1; }","duration":"235.654932ms","start":"2026-10-14T15:55:05.983029Z","end":"2026-10-14T15:55:06.218684Z","steps":["trace[1524613722] 'process raft request' (duration: 235.224542ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.219107Z","caller":"traceutil/trace.go:171","msg":"trace[380839469] transaction","detail":"{read_only:false; response_revision:5875; number_of_response:1; }","duration":"217.678374ms","start":"2026-10-14T15:55:06.001402Z","end":"2026-10-14T15:55:06.219081Z","steps":["trace[380839469] 'process raft request' (duration: 216.907826ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.219224Z","caller":"traceutil/trace.go:171","msg":"trace[1655165523] linearizableReadLoop","detail":"{readStateIndex:6095; appliedIndex:6090; }","duration":"234.243609ms","start":"2026-10-14T15:55:05.984748Z","end":"2026-10-14T15:55:06.218991Z","steps":["trace[1655165523] 'read index received' (duration: 23.416296ms)","trace[1655165523] 'applied index is now lower than readState.Index' (duration: 210.825543ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:06.219406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.608638ms","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-14T15:55:06.219573Z","caller":"traceutil/trace.go:171","msg":"trace[135417231] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5875; }","duration":"234.835504ms","start":"2026-10-14T15:55:05.98472Z","end":"2026-10-14T15:55:06.219555Z","steps":["trace[135417231] 'agreement among raft nodes before linearized reading' (duration: 234.464495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:06.578159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.500648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:55:06.578308Z","caller":"traceutil/trace.go:171","msg":"trace[8457463] linearizableReadLoop","detail":"{readStateIndex:6097; appliedIndex:6096; }","duration":"358.969617ms","start":"2026-10-14T15:55:06.219317Z","end":"2026-10-14T15:55:06.578286Z","steps":["trace[8457463] 'read index received' (duration: 208.268805ms)","trace[8457463] 'applied index is now lower than readState.Index' (duration: 150.699042ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:06.578556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.893023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-14T15:55:06.578585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.673134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-72578\" ","response":"range_response_count:1 size:6522"} {"level":"warn","ts":"2026-10-14T15:55:06.578616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.618483ms","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-10-14T15:55:06.578607Z","caller":"traceutil/trace.go:171","msg":"trace[1406421603] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5876; }","duration":"569.982165ms","start":"2026-10-14T15:55:06.008606Z","end":"2026-10-14T15:55:06.578588Z","steps":["trace[1406421603] 'agreement among raft nodes before linearized reading' (duration: 569.78929ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.578667Z","caller":"traceutil/trace.go:171","msg":"trace[1930191539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5876; }","duration":"580.731955ms","start":"2026-10-14T15:55:05.997921Z","end":"2026-10-14T15:55:06.578653Z","steps":["trace[1930191539] 'agreement among raft nodes before linearized reading' (duration: 580.566841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:06.578704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:06.008589Z","time spent":"570.098938ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T15:55:06.57871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.997892Z","time spent":"580.805687ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T15:55:06.578762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.177306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-08835e94-13a5-4939-acc8-b11ad4dda929\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-14T15:55:06.578749Z","caller":"traceutil/trace.go:171","msg":"trace[818539387] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"523.501542ms","start":"2026-10-14T15:55:06.055229Z","end":"2026-10-14T15:55:06.578731Z","steps":["trace[818539387] 'process raft request' (duration: 372.308979ms)","trace[818539387] 'compare' (duration: 150.321963ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:06.578644Z","caller":"traceutil/trace.go:171","msg":"trace[1185768949] range","detail":"{range_begin:/registry/pods/openstack/keepalived-72578; range_end:; response_count:1; response_revision:5876; }","duration":"565.774447ms","start":"2026-10-14T15:55:06.012852Z","end":"2026-10-14T15:55:06.578626Z","steps":["trace[1185768949] 'agreement among raft nodes before linearized reading' (duration: 565.528921ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.57885Z","caller":"traceutil/trace.go:171","msg":"trace[71078270] range","detail":"{range_begin:/registry/persistentvolumes/pvc-08835e94-13a5-4939-acc8-b11ad4dda929; range_end:; response_count:1; response_revision:5876; }","duration":"354.341259ms","start":"2026-10-14T15:55:06.22449Z","end":"2026-10-14T15:55:06.578831Z","steps":["trace[71078270] 'agreement among raft nodes before linearized reading' (duration: 354.185756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:06.578893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:06.055199Z","time spent":"523.642635ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:06.578903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:06.22447Z","time spent":"354.420311ms","remote":"127.0.0.1:53378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-08835e94-13a5-4939-acc8-b11ad4dda929\" "} {"level":"warn","ts":"2026-10-14T15:55:06.578639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.720518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.18de705ed583555f\" ","response":"range_response_count:1 size:751"} {"level":"warn","ts":"2026-10-14T15:55:06.578926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:06.012827Z","time spent":"566.049684ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-72578\" "} {"level":"info","ts":"2026-10-14T15:55:06.578963Z","caller":"traceutil/trace.go:171","msg":"trace[1556549521] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-alertmanager.18de705ed583555f; range_end:; response_count:1; response_revision:5876; }","duration":"355.097297ms","start":"2026-10-14T15:55:06.223843Z","end":"2026-10-14T15:55:06.57894Z","steps":["trace[1556549521] 'agreement among raft nodes before linearized reading' (duration: 354.601385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:06.579006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:06.223824Z","time spent":"355.168428ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":774,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.18de705ed583555f\" "} {"level":"info","ts":"2026-10-14T15:55:06.877804Z","caller":"traceutil/trace.go:171","msg":"trace[1798672784] linearizableReadLoop","detail":"{readStateIndex:6103; appliedIndex:6102; }","duration":"106.074963ms","start":"2026-10-14T15:55:06.771706Z","end":"2026-10-14T15:55:06.877781Z","steps":["trace[1798672784] 'read index received' (duration: 89.057236ms)","trace[1798672784] 'applied index is now lower than readState.Index' (duration: 17.016777ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:06.877845Z","caller":"traceutil/trace.go:171","msg":"trace[1148596968] transaction","detail":"{read_only:false; response_revision:5882; number_of_response:1; }","duration":"106.170514ms","start":"2026-10-14T15:55:06.771645Z","end":"2026-10-14T15:55:06.877815Z","steps":["trace[1148596968] 'process raft request' (duration: 89.016044ms)","trace[1148596968] 'compare' (duration: 17.013747ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:06.878154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.421811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-13b38dd1-4798-46d7-9fb9-631517c7e998\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-10-14T15:55:06.878248Z","caller":"traceutil/trace.go:171","msg":"trace[1657909420] range","detail":"{range_begin:/registry/persistentvolumes/pvc-13b38dd1-4798-46d7-9fb9-631517c7e998; range_end:; response_count:1; response_revision:5882; }","duration":"106.562274ms","start":"2026-10-14T15:55:06.77166Z","end":"2026-10-14T15:55:06.878222Z","steps":["trace[1657909420] 'agreement among raft nodes before linearized reading' (duration: 106.251477ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.926304Z","caller":"traceutil/trace.go:171","msg":"trace[160095220] transaction","detail":"{read_only:false; response_revision:5883; number_of_response:1; }","duration":"152.978336ms","start":"2026-10-14T15:55:06.773292Z","end":"2026-10-14T15:55:06.92627Z","steps":["trace[160095220] 'process raft request' (duration: 152.454263ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.926498Z","caller":"traceutil/trace.go:171","msg":"trace[217234504] transaction","detail":"{read_only:false; response_revision:5884; number_of_response:1; }","duration":"152.796621ms","start":"2026-10-14T15:55:06.773668Z","end":"2026-10-14T15:55:06.926465Z","steps":["trace[217234504] 'process raft request' (duration: 152.467854ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.926737Z","caller":"traceutil/trace.go:171","msg":"trace[1855811388] transaction","detail":"{read_only:false; response_revision:5885; number_of_response:1; }","duration":"151.784497ms","start":"2026-10-14T15:55:06.774923Z","end":"2026-10-14T15:55:06.926708Z","steps":["trace[1855811388] 'process raft request' (duration: 151.287485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:06.926678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.678915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17235"} {"level":"warn","ts":"2026-10-14T15:55:06.926847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.018243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:55:06.926907Z","caller":"traceutil/trace.go:171","msg":"trace[1993775832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5885; }","duration":"134.121696ms","start":"2026-10-14T15:55:06.792768Z","end":"2026-10-14T15:55:06.92689Z","steps":["trace[1993775832] 'agreement among raft nodes before linearized reading' (duration: 133.973093ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:06.926959Z","caller":"traceutil/trace.go:171","msg":"trace[1854669118] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5885; }","duration":"154.981352ms","start":"2026-10-14T15:55:06.771889Z","end":"2026-10-14T15:55:06.92687Z","steps":["trace[1854669118] 'agreement among raft nodes before linearized reading' (duration: 154.404388ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:59:55.089302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5734} {"level":"info","ts":"2026-10-14T15:59:55.195467Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5734,"took":"104.405019ms","hash":2439544038,"current-db-size-bytes":56385536,"current-db-size":"56 MB","current-db-size-in-use-bytes":26181632,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T15:59:55.195581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2439544038,"revision":5734,"compact-revision":2914} {"level":"warn","ts":"2026-10-14T16:01:10.492594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.979184ms","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-10-14T16:01:10.492714Z","caller":"traceutil/trace.go:171","msg":"trace[1050770899] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8601; }","duration":"291.141338ms","start":"2026-10-14T16:01:10.201553Z","end":"2026-10-14T16:01:10.492695Z","steps":["trace[1050770899] 'range keys from in-memory index tree' (duration: 290.772849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:19.432548Z","caller":"traceutil/trace.go:171","msg":"trace[247231411] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"145.566327ms","start":"2026-10-14T16:01:19.286959Z","end":"2026-10-14T16:01:19.432525Z","steps":["trace[247231411] 'process raft request' (duration: 145.438124ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:20.549602Z","caller":"traceutil/trace.go:171","msg":"trace[1948900204] linearizableReadLoop","detail":"{readStateIndex:8964; appliedIndex:8963; }","duration":"395.656793ms","start":"2026-10-14T16:01:20.153917Z","end":"2026-10-14T16:01:20.549574Z","steps":["trace[1948900204] 'read index received' (duration: 395.461228ms)","trace[1948900204] 'applied index is now lower than readState.Index' (duration: 194.125µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:20.54964Z","caller":"traceutil/trace.go:171","msg":"trace[1510216565] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"582.200083ms","start":"2026-10-14T16:01:19.967404Z","end":"2026-10-14T16:01:20.549604Z","steps":["trace[1510216565] 'process raft request' (duration: 582.002699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:20.549789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:19.967381Z","time spent":"582.331927ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:01:20.549807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.884998ms","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-14T16:01:20.549834Z","caller":"traceutil/trace.go:171","msg":"trace[1972368874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8652; }","duration":"395.95726ms","start":"2026-10-14T16:01:20.15387Z","end":"2026-10-14T16:01:20.549828Z","steps":["trace[1972368874] 'agreement among raft nodes before linearized reading' (duration: 395.817146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:20.549852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:20.153852Z","time spent":"395.99521ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T16:01:21.501218Z","caller":"traceutil/trace.go:171","msg":"trace[1740875861] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"297.915217ms","start":"2026-10-14T16:01:21.203283Z","end":"2026-10-14T16:01:21.501199Z","steps":["trace[1740875861] 'process raft request' (duration: 257.692522ms)","trace[1740875861] 'compare' (duration: 39.848926ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:21.501411Z","caller":"traceutil/trace.go:171","msg":"trace[1638938175] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"290.580634ms","start":"2026-10-14T16:01:21.21082Z","end":"2026-10-14T16:01:21.501401Z","steps":["trace[1638938175] 'process raft request' (duration: 290.227396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:51.331591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.817474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:01:51.331887Z","caller":"traceutil/trace.go:171","msg":"trace[1368658470] linearizableReadLoop","detail":"{readStateIndex:9188; appliedIndex:9187; }","duration":"537.120625ms","start":"2026-10-14T16:01:50.794727Z","end":"2026-10-14T16:01:51.331848Z","steps":["trace[1368658470] 'read index received' (duration: 304.816019ms)","trace[1368658470] 'applied index is now lower than readState.Index' (duration: 232.303266ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:51.331918Z","caller":"traceutil/trace.go:171","msg":"trace[1420127987] transaction","detail":"{read_only:false; response_revision:8868; number_of_response:1; }","duration":"579.464669ms","start":"2026-10-14T16:01:50.752402Z","end":"2026-10-14T16:01:51.331866Z","steps":["trace[1420127987] 'process raft request' (duration: 347.135023ms)","trace[1420127987] 'compare' (duration: 231.533048ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:01:51.332037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.295439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:51.332096Z","caller":"traceutil/trace.go:171","msg":"trace[1265009837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8868; }","duration":"537.418012ms","start":"2026-10-14T16:01:50.794665Z","end":"2026-10-14T16:01:51.332083Z","steps":["trace[1265009837] 'agreement among raft nodes before linearized reading' (duration: 537.265588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:51.332127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:50.794614Z","time spent":"537.507013ms","remote":"127.0.0.1:53236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T16:01:51.332126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:50.752378Z","time spent":"579.647024ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:01:51.362502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.994432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-14T16:01:51.362541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.14908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-72578\" ","response":"range_response_count:1 size:6522"} {"level":"warn","ts":"2026-10-14T16:01:51.362549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.38376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:51.364859Z","caller":"traceutil/trace.go:171","msg":"trace[2047066217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8869; }","duration":"259.108335ms","start":"2026-10-14T16:01:51.103454Z","end":"2026-10-14T16:01:51.362563Z","steps":["trace[2047066217] 'agreement among raft nodes before linearized reading' (duration: 258.940441ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:51.364917Z","caller":"traceutil/trace.go:171","msg":"trace[296928549] range","detail":"{range_begin:/registry/pods/openstack/keepalived-72578; range_end:; response_count:1; response_revision:8869; }","duration":"243.218003ms","start":"2026-10-14T16:01:51.119357Z","end":"2026-10-14T16:01:51.362575Z","steps":["trace[296928549] 'agreement among raft nodes before linearized reading' (duration: 243.043028ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:51.365337Z","caller":"traceutil/trace.go:171","msg":"trace[1697117472] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8869; }","duration":"405.486013ms","start":"2026-10-14T16:01:50.957106Z","end":"2026-10-14T16:01:51.362592Z","steps":["trace[1697117472] 'agreement among raft nodes before linearized reading' (duration: 405.382451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:51.365451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:50.957088Z","time spent":"408.3083ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:01:51.365476Z","caller":"traceutil/trace.go:171","msg":"trace[1916224002] transaction","detail":"{read_only:false; response_revision:8869; number_of_response:1; }","duration":"477.035793ms","start":"2026-10-14T16:01:50.885577Z","end":"2026-10-14T16:01:51.362612Z","steps":["trace[1916224002] 'process raft request' (duration: 476.688605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:51.365599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:50.885539Z","time spent":"479.992213ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:01:57.586959Z","caller":"traceutil/trace.go:171","msg":"trace[766390872] linearizableReadLoop","detail":"{readStateIndex:9223; appliedIndex:9222; }","duration":"317.547009ms","start":"2026-10-14T16:01:57.269382Z","end":"2026-10-14T16:01:57.586929Z","steps":["trace[766390872] 'read index received' (duration: 317.372744ms)","trace[766390872] 'applied index is now lower than readState.Index' (duration: 172.915µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:57.587098Z","caller":"traceutil/trace.go:171","msg":"trace[1642470326] transaction","detail":"{read_only:false; response_revision:8902; number_of_response:1; }","duration":"327.105362ms","start":"2026-10-14T16:01:57.259979Z","end":"2026-10-14T16:01:57.587085Z","steps":["trace[1642470326] 'process raft request' (duration: 326.728813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.587238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.814665ms","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-10-14T16:01:57.58734Z","caller":"traceutil/trace.go:171","msg":"trace[1904123768] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8902; }","duration":"317.959318ms","start":"2026-10-14T16:01:57.269359Z","end":"2026-10-14T16:01:57.587318Z","steps":["trace[1904123768] 'agreement among raft nodes before linearized reading' (duration: 317.768583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.587394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.269348Z","time spent":"318.036639ms","remote":"127.0.0.1:58780","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-10-14T16:01:57.587561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.259953Z","time spent":"327.178944ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:01:57.820107Z","caller":"traceutil/trace.go:171","msg":"trace[1880819635] linearizableReadLoop","detail":"{readStateIndex:9224; appliedIndex:9223; }","duration":"233.035243ms","start":"2026-10-14T16:01:57.587048Z","end":"2026-10-14T16:01:57.820083Z","steps":["trace[1880819635] 'read index received' (duration: 187.625597ms)","trace[1880819635] 'applied index is now lower than readState.Index' (duration: 45.408706ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:57.820512Z","caller":"traceutil/trace.go:171","msg":"trace[182888802] transaction","detail":"{read_only:false; response_revision:8903; number_of_response:1; }","duration":"413.748448ms","start":"2026-10-14T16:01:57.406728Z","end":"2026-10-14T16:01:57.820477Z","steps":["trace[182888802] 'process raft request' (duration: 367.804749ms)","trace[182888802] 'compare' (duration: 45.449147ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:01:57.820543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.727552ms","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-10-14T16:01:57.820622Z","caller":"traceutil/trace.go:171","msg":"trace[2145516721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8903; }","duration":"403.859605ms","start":"2026-10-14T16:01:57.416745Z","end":"2026-10-14T16:01:57.820605Z","steps":["trace[2145516721] 'agreement among raft nodes before linearized reading' (duration: 403.625519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.820689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.416725Z","time spent":"403.955317ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T16:01:57.820687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.809813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-14T16:01:57.820782Z","caller":"traceutil/trace.go:171","msg":"trace[733469822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8903; }","duration":"463.939936ms","start":"2026-10-14T16:01:57.356822Z","end":"2026-10-14T16:01:57.820762Z","steps":["trace[733469822] 'agreement among raft nodes before linearized reading' (duration: 463.617609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.820695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.406698Z","time spent":"413.87664ms","remote":"127.0.0.1:52960","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-10-14T16:01:57.820695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.628569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-72578\" ","response":"range_response_count:1 size:6522"} {"level":"warn","ts":"2026-10-14T16:01:57.820835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.356804Z","time spent":"464.017819ms","remote":"127.0.0.1:52960","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":"info","ts":"2026-10-14T16:01:57.820899Z","caller":"traceutil/trace.go:171","msg":"trace[120649183] range","detail":"{range_begin:/registry/pods/openstack/keepalived-72578; range_end:; response_count:1; response_revision:8903; }","duration":"400.846504ms","start":"2026-10-14T16:01:57.420022Z","end":"2026-10-14T16:01:57.820868Z","steps":["trace[120649183] 'agreement among raft nodes before linearized reading' (duration: 400.486465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.820984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.420009Z","time spent":"400.942697ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-72578\" "} {"level":"warn","ts":"2026-10-14T16:01:57.821085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.106448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T16:01:57.821171Z","caller":"traceutil/trace.go:171","msg":"trace[315860793] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8903; }","duration":"406.154839ms","start":"2026-10-14T16:01:57.414962Z","end":"2026-10-14T16:01:57.821117Z","steps":["trace[315860793] 'agreement among raft nodes before linearized reading' (duration: 405.411341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.821252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.414922Z","time spent":"406.313942ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T16:01:57.895441Z","caller":"traceutil/trace.go:171","msg":"trace[667344360] transaction","detail":"{read_only:false; response_revision:8904; number_of_response:1; }","duration":"215.503972ms","start":"2026-10-14T16:01:57.679916Z","end":"2026-10-14T16:01:57.89542Z","steps":["trace[667344360] 'process raft request' (duration: 215.371458ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:57.895822Z","caller":"traceutil/trace.go:171","msg":"trace[2145351055] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"179.688211ms","start":"2026-10-14T16:01:57.716116Z","end":"2026-10-14T16:01:57.895805Z","steps":["trace[2145351055] 'process raft request' (duration: 179.270431ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.896055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.023823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:57.896212Z","caller":"traceutil/trace.go:171","msg":"trace[403137218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8905; }","duration":"104.223527ms","start":"2026-10-14T16:01:57.791952Z","end":"2026-10-14T16:01:57.896175Z","steps":["trace[403137218] 'agreement among raft nodes before linearized reading' (duration: 104.024073ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:57.999249Z","caller":"traceutil/trace.go:171","msg":"trace[203974510] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"174.029968ms","start":"2026-10-14T16:01:57.825192Z","end":"2026-10-14T16:01:57.999221Z","steps":["trace[203974510] 'process raft request' (duration: 173.843973ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:57.999571Z","caller":"traceutil/trace.go:171","msg":"trace[2015507886] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"173.751291ms","start":"2026-10-14T16:01:57.825795Z","end":"2026-10-14T16:01:57.999546Z","steps":["trace[2015507886] 'process raft request' (duration: 173.387093ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:57.999676Z","caller":"traceutil/trace.go:171","msg":"trace[1920445203] linearizableReadLoop","detail":"{readStateIndex:9229; appliedIndex:9226; }","duration":"103.673845ms","start":"2026-10-14T16:01:57.895951Z","end":"2026-10-14T16:01:57.999624Z","steps":["trace[1920445203] 'read index received' (duration: 102.988299ms)","trace[1920445203] 'applied index is now lower than readState.Index' (duration: 684.746µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:57.999852Z","caller":"traceutil/trace.go:171","msg":"trace[1246467845] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"173.785822ms","start":"2026-10-14T16:01:57.826041Z","end":"2026-10-14T16:01:57.999827Z","steps":["trace[1246467845] 'process raft request' (duration: 173.423153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:58.000036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.901337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:58.000095Z","caller":"traceutil/trace.go:171","msg":"trace[1620394098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8908; }","duration":"103.00647ms","start":"2026-10-14T16:01:57.897073Z","end":"2026-10-14T16:01:58.000079Z","steps":["trace[1620394098] 'agreement among raft nodes before linearized reading' (duration: 102.899067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:58.000466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.613387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228520"} {"level":"info","ts":"2026-10-14T16:01:58.000519Z","caller":"traceutil/trace.go:171","msg":"trace[569753146] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8908; }","duration":"173.742381ms","start":"2026-10-14T16:01:57.826764Z","end":"2026-10-14T16:01:58.000507Z","steps":["trace[569753146] 'agreement among raft nodes before linearized reading' (duration: 173.062545ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:58.144219Z","caller":"traceutil/trace.go:171","msg":"trace[775191206] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"139.699951ms","start":"2026-10-14T16:01:58.004482Z","end":"2026-10-14T16:01:58.144182Z","steps":["trace[775191206] 'process raft request' (duration: 102.375024ms)","trace[775191206] 'compare' (duration: 36.926178ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:58.902148Z","caller":"traceutil/trace.go:171","msg":"trace[1035828002] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"152.03602ms","start":"2026-10-14T16:01:58.75008Z","end":"2026-10-14T16:01:58.902116Z","steps":["trace[1035828002] 'process raft request' (duration: 151.828485ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:04.729147Z","caller":"traceutil/trace.go:171","msg":"trace[726826172] transaction","detail":"{read_only:false; response_revision:8978; number_of_response:1; }","duration":"143.327676ms","start":"2026-10-14T16:02:04.585794Z","end":"2026-10-14T16:02:04.729122Z","steps":["trace[726826172] 'process raft request' (duration: 95.80189ms)","trace[726826172] 'compare' (duration: 46.40882ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:05.144889Z","caller":"traceutil/trace.go:171","msg":"trace[58333604] transaction","detail":"{read_only:false; response_revision:8981; number_of_response:1; }","duration":"213.476884ms","start":"2026-10-14T16:02:04.93138Z","end":"2026-10-14T16:02:05.144856Z","steps":["trace[58333604] 'process raft request' (duration: 213.30062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:05.145109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.687815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-14T16:02:05.145177Z","caller":"traceutil/trace.go:171","msg":"trace[609219846] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8981; }","duration":"212.799757ms","start":"2026-10-14T16:02:04.932363Z","end":"2026-10-14T16:02:05.145163Z","steps":["trace[609219846] 'agreement among raft nodes before linearized reading' (duration: 212.591062ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:05.144896Z","caller":"traceutil/trace.go:171","msg":"trace[896216887] linearizableReadLoop","detail":"{readStateIndex:9307; appliedIndex:9306; }","duration":"212.43167ms","start":"2026-10-14T16:02:04.932402Z","end":"2026-10-14T16:02:05.144834Z","steps":["trace[896216887] 'read index received' (duration: 212.277356ms)","trace[896216887] 'applied index is now lower than readState.Index' (duration: 153.064µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:02:05.145908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.454259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-8bd6cfdf8-9zb8m.18de70bc45d5bf3f\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-10-14T16:02:05.146143Z","caller":"traceutil/trace.go:171","msg":"trace[382026709] range","detail":"{range_begin:/registry/events/openstack/barbican-api-8bd6cfdf8-9zb8m.18de70bc45d5bf3f; range_end:; response_count:1; response_revision:8981; }","duration":"189.725246ms","start":"2026-10-14T16:02:04.956396Z","end":"2026-10-14T16:02:05.146122Z","steps":["trace[382026709] 'agreement among raft nodes before linearized reading' (duration: 189.292016ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:05.477608Z","caller":"traceutil/trace.go:171","msg":"trace[867385662] transaction","detail":"{read_only:false; response_revision:8983; number_of_response:1; }","duration":"225.962567ms","start":"2026-10-14T16:02:05.251611Z","end":"2026-10-14T16:02:05.477574Z","steps":["trace[867385662] 'process raft request' (duration: 154.642992ms)","trace[867385662] 'compare' (duration: 71.052858ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:05.605078Z","caller":"traceutil/trace.go:171","msg":"trace[843315720] transaction","detail":"{read_only:false; response_revision:8984; number_of_response:1; }","duration":"351.351062ms","start":"2026-10-14T16:02:05.253692Z","end":"2026-10-14T16:02:05.605043Z","steps":["trace[843315720] 'process raft request' (duration: 339.778241ms)","trace[843315720] 'compare' (duration: 11.333206ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:05.605158Z","caller":"traceutil/trace.go:171","msg":"trace[114066820] transaction","detail":"{read_only:false; response_revision:8986; number_of_response:1; }","duration":"342.802432ms","start":"2026-10-14T16:02:05.262331Z","end":"2026-10-14T16:02:05.605134Z","steps":["trace[114066820] 'process raft request' (duration: 342.661518ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:05.605162Z","caller":"traceutil/trace.go:171","msg":"trace[633891229] transaction","detail":"{read_only:false; response_revision:8985; number_of_response:1; }","duration":"349.853947ms","start":"2026-10-14T16:02:05.255278Z","end":"2026-10-14T16:02:05.605132Z","steps":["trace[633891229] 'process raft request' (duration: 349.652732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:05.60525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:05.253677Z","time spent":"351.489385ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1100,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:02:05.605309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:05.262314Z","time spent":"342.907494ms","remote":"127.0.0.1:56786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:02:05.605395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:05.25527Z","time spent":"349.986339ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:02:15.215775Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b1.wal"} {"level":"info","ts":"2026-10-14T16:03:08.754863Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:03:08.762248Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T16:03:08.762366Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T16:04:55.096505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7972} {"level":"info","ts":"2026-10-14T16:04:55.172728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7972,"took":"73.560352ms","hash":3290161845,"current-db-size-bytes":56385536,"current-db-size":"56 MB","current-db-size-in-use-bytes":28385280,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T16:04:55.172802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3290161845,"revision":7972,"compact-revision":5734} {"level":"info","ts":"2026-10-14T16:07:06.972462Z","caller":"traceutil/trace.go:171","msg":"trace[538628144] transaction","detail":"{read_only:false; response_revision:11583; number_of_response:1; }","duration":"192.876067ms","start":"2026-10-14T16:07:06.779538Z","end":"2026-10-14T16:07:06.972414Z","steps":["trace[538628144] 'process raft request' (duration: 192.637861ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:07.215117Z","caller":"traceutil/trace.go:171","msg":"trace[552173664] transaction","detail":"{read_only:false; response_revision:11585; number_of_response:1; }","duration":"141.949977ms","start":"2026-10-14T16:07:07.073133Z","end":"2026-10-14T16:07:07.215083Z","steps":["trace[552173664] 'process raft request' (duration: 141.866455ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:07.215204Z","caller":"traceutil/trace.go:171","msg":"trace[1714427344] transaction","detail":"{read_only:false; response_revision:11584; number_of_response:1; }","duration":"217.923343ms","start":"2026-10-14T16:07:06.997223Z","end":"2026-10-14T16:07:07.215146Z","steps":["trace[1714427344] 'process raft request' (duration: 155.549475ms)","trace[1714427344] 'compare' (duration: 62.072601ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:07:07.21536Z","caller":"traceutil/trace.go:171","msg":"trace[723523970] transaction","detail":"{read_only:false; response_revision:11586; number_of_response:1; }","duration":"134.80981ms","start":"2026-10-14T16:07:07.080532Z","end":"2026-10-14T16:07:07.215342Z","steps":["trace[723523970] 'process raft request' (duration: 134.510333ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:07.546975Z","caller":"traceutil/trace.go:171","msg":"trace[1154602095] linearizableReadLoop","detail":"{readStateIndex:11988; appliedIndex:11987; }","duration":"146.703208ms","start":"2026-10-14T16:07:07.400226Z","end":"2026-10-14T16:07:07.546929Z","steps":["trace[1154602095] 'read index received' (duration: 58.045626ms)","trace[1154602095] 'applied index is now lower than readState.Index' (duration: 88.655102ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:07:07.547045Z","caller":"traceutil/trace.go:171","msg":"trace[713034235] transaction","detail":"{read_only:false; response_revision:11587; number_of_response:1; }","duration":"152.376051ms","start":"2026-10-14T16:07:07.394618Z","end":"2026-10-14T16:07:07.546994Z","steps":["trace[713034235] 'process raft request' (duration: 63.647107ms)","trace[713034235] 'compare' (duration: 88.194851ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:07:07.54718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.919913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T16:07:07.547227Z","caller":"traceutil/trace.go:171","msg":"trace[1639246604] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:11587; }","duration":"147.043816ms","start":"2026-10-14T16:07:07.400166Z","end":"2026-10-14T16:07:07.54721Z","steps":["trace[1639246604] 'agreement among raft nodes before linearized reading' (duration: 146.906032ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:09:55.103639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10389} {"level":"info","ts":"2026-10-14T16:09:55.196017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10389,"took":"90.088337ms","hash":54928166,"current-db-size-bytes":56385536,"current-db-size":"56 MB","current-db-size-in-use-bytes":27688960,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T16:09:55.196088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":54928166,"revision":10389,"compact-revision":7972} {"level":"info","ts":"2026-10-14T16:13:19.959338Z","caller":"traceutil/trace.go:171","msg":"trace[295984601] linearizableReadLoop","detail":"{readStateIndex:14584; appliedIndex:14583; }","duration":"184.355873ms","start":"2026-10-14T16:13:19.774958Z","end":"2026-10-14T16:13:19.959314Z","steps":["trace[295984601] 'read index received' (duration: 184.145498ms)","trace[295984601] 'applied index is now lower than readState.Index' (duration: 208.705µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:13:19.959401Z","caller":"traceutil/trace.go:171","msg":"trace[2033739775] transaction","detail":"{read_only:false; response_revision:14095; number_of_response:1; }","duration":"256.933325ms","start":"2026-10-14T16:13:19.702432Z","end":"2026-10-14T16:13:19.959366Z","steps":["trace[2033739775] 'process raft request' (duration: 256.676958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:19.959547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.034959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T16:13:19.959586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.622829ms","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-10-14T16:13:19.959602Z","caller":"traceutil/trace.go:171","msg":"trace[704915801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14095; }","duration":"169.179373ms","start":"2026-10-14T16:13:19.790409Z","end":"2026-10-14T16:13:19.959588Z","steps":["trace[704915801] 'agreement among raft nodes before linearized reading' (duration: 169.0476ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:19.959648Z","caller":"traceutil/trace.go:171","msg":"trace[1148119216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14095; }","duration":"184.736603ms","start":"2026-10-14T16:13:19.774894Z","end":"2026-10-14T16:13:19.959631Z","steps":["trace[1148119216] 'agreement among raft nodes before linearized reading' (duration: 184.548918ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:34.176251Z","caller":"traceutil/trace.go:171","msg":"trace[10590793] linearizableReadLoop","detail":"{readStateIndex:14677; appliedIndex:14676; }","duration":"120.070008ms","start":"2026-10-14T16:13:34.056154Z","end":"2026-10-14T16:13:34.176224Z","steps":["trace[10590793] 'read index received' (duration: 119.833283ms)","trace[10590793] 'applied index is now lower than readState.Index' (duration: 235.165µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:13:34.176322Z","caller":"traceutil/trace.go:171","msg":"trace[279160100] transaction","detail":"{read_only:false; response_revision:14184; number_of_response:1; }","duration":"144.698963ms","start":"2026-10-14T16:13:34.031593Z","end":"2026-10-14T16:13:34.176292Z","steps":["trace[279160100] 'process raft request' (duration: 144.458507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:34.176473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.298574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-14T16:13:34.176553Z","caller":"traceutil/trace.go:171","msg":"trace[1449749152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14184; }","duration":"120.414447ms","start":"2026-10-14T16:13:34.056125Z","end":"2026-10-14T16:13:34.176539Z","steps":["trace[1449749152] 'agreement among raft nodes before linearized reading' (duration: 120.233532ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:37.315067Z","caller":"traceutil/trace.go:171","msg":"trace[1251727642] transaction","detail":"{read_only:false; response_revision:14205; number_of_response:1; }","duration":"198.402476ms","start":"2026-10-14T16:13:37.116637Z","end":"2026-10-14T16:13:37.315039Z","steps":["trace[1251727642] 'process raft request' (duration: 191.39167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:37.794299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.187862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-72578\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-10-14T16:13:37.794407Z","caller":"traceutil/trace.go:171","msg":"trace[1515175076] range","detail":"{range_begin:/registry/pods/openstack/keepalived-72578; range_end:; response_count:1; response_revision:14205; }","duration":"166.334376ms","start":"2026-10-14T16:13:37.628047Z","end":"2026-10-14T16:13:37.794382Z","steps":["trace[1515175076] 'range keys from in-memory index tree' (duration: 165.998628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:37.79432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.198945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-jrx82\" ","response":"range_response_count:1 size:12599"} {"level":"info","ts":"2026-10-14T16:13:37.794745Z","caller":"traceutil/trace.go:171","msg":"trace[180477681] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-jrx82; range_end:; response_count:1; response_revision:14205; }","duration":"368.658656ms","start":"2026-10-14T16:13:37.426069Z","end":"2026-10-14T16:13:37.794728Z","steps":["trace[180477681] 'range keys from in-memory index tree' (duration: 368.063292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:37.794866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:13:37.426049Z","time spent":"368.730427ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12622,"request content":"key:\"/registry/pods/openstack/openvswitch-jrx82\" "} {"level":"info","ts":"2026-10-14T16:13:38.018888Z","caller":"traceutil/trace.go:171","msg":"trace[1711267424] linearizableReadLoop","detail":"{readStateIndex:14699; appliedIndex:14698; }","duration":"201.134172ms","start":"2026-10-14T16:13:37.817724Z","end":"2026-10-14T16:13:38.018858Z","steps":["trace[1711267424] 'read index received' (duration: 200.898847ms)","trace[1711267424] 'applied index is now lower than readState.Index' (duration: 234.675µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:13:38.018973Z","caller":"traceutil/trace.go:171","msg":"trace[1381111383] transaction","detail":"{read_only:false; response_revision:14206; number_of_response:1; }","duration":"205.427894ms","start":"2026-10-14T16:13:37.813538Z","end":"2026-10-14T16:13:38.018966Z","steps":["trace[1381111383] 'process raft request' (duration: 205.156547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:38.019289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.58679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:13:38.019388Z","caller":"traceutil/trace.go:171","msg":"trace[1399956686] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:14206; }","duration":"184.718252ms","start":"2026-10-14T16:13:37.834646Z","end":"2026-10-14T16:13:38.019364Z","steps":["trace[1399956686] 'agreement among raft nodes before linearized reading' (duration: 184.563919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:38.019461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.729926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-10-14T16:13:38.019527Z","caller":"traceutil/trace.go:171","msg":"trace[307428971] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14206; }","duration":"201.818998ms","start":"2026-10-14T16:13:37.817671Z","end":"2026-10-14T16:13:38.01949Z","steps":["trace[307428971] 'agreement among raft nodes before linearized reading' (duration: 201.673405ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:14:55.110771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12716} {"level":"info","ts":"2026-10-14T16:14:55.198131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12716,"took":"84.231659ms","hash":1666661010,"current-db-size-bytes":56385536,"current-db-size":"56 MB","current-db-size-in-use-bytes":30613504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:14:55.198253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1666661010,"revision":12716,"compact-revision":10389} {"level":"info","ts":"2026-10-14T16:15:21.828286Z","caller":"traceutil/trace.go:171","msg":"trace[384182092] linearizableReadLoop","detail":"{readStateIndex:15958; appliedIndex:15957; }","duration":"136.441311ms","start":"2026-10-14T16:15:21.691818Z","end":"2026-10-14T16:15:21.82826Z","steps":["trace[384182092] 'read index received' (duration: 136.191475ms)","trace[384182092] 'applied index is now lower than readState.Index' (duration: 248.596µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:15:21.828331Z","caller":"traceutil/trace.go:171","msg":"trace[561323021] transaction","detail":"{read_only:false; response_revision:15426; number_of_response:1; }","duration":"148.929096ms","start":"2026-10-14T16:15:21.679365Z","end":"2026-10-14T16:15:21.828294Z","steps":["trace[561323021] 'process raft request' (duration: 148.743612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:21.828613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.768449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g2t95\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-10-14T16:15:21.828666Z","caller":"traceutil/trace.go:171","msg":"trace[393576911] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g2t95; range_end:; response_count:1; response_revision:15426; }","duration":"136.864581ms","start":"2026-10-14T16:15:21.691789Z","end":"2026-10-14T16:15:21.828654Z","steps":["trace[393576911] 'agreement among raft nodes before linearized reading' (duration: 136.613005ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:22.915117Z","caller":"traceutil/trace.go:171","msg":"trace[798169058] transaction","detail":"{read_only:false; response_revision:15427; number_of_response:1; }","duration":"100.658216ms","start":"2026-10-14T16:15:22.814425Z","end":"2026-10-14T16:15:22.915083Z","steps":["trace[798169058] 'process raft request' (duration: 100.302768ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.294546Z","caller":"traceutil/trace.go:171","msg":"trace[2088559213] transaction","detail":"{read_only:false; response_revision:15428; number_of_response:1; }","duration":"375.194287ms","start":"2026-10-14T16:15:22.919325Z","end":"2026-10-14T16:15:23.294519Z","steps":["trace[2088559213] 'process raft request' (duration: 374.975962ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.294636Z","caller":"traceutil/trace.go:171","msg":"trace[2059040607] transaction","detail":"{read_only:false; response_revision:15429; number_of_response:1; }","duration":"375.276809ms","start":"2026-10-14T16:15:22.919334Z","end":"2026-10-14T16:15:23.294611Z","steps":["trace[2059040607] 'process raft request' (duration: 375.112395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.29469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:15:22.919299Z","time spent":"375.319131ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:15:23.294758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:15:22.919312Z","time spent":"375.380162ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:15:23.307258Z","caller":"traceutil/trace.go:171","msg":"trace[772308115] transaction","detail":"{read_only:false; response_revision:15431; number_of_response:1; }","duration":"166.870339ms","start":"2026-10-14T16:15:23.140364Z","end":"2026-10-14T16:15:23.307234Z","steps":["trace[772308115] 'process raft request' (duration: 166.797618ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.307308Z","caller":"traceutil/trace.go:171","msg":"trace[587312696] transaction","detail":"{read_only:false; response_revision:15430; number_of_response:1; }","duration":"387.694442ms","start":"2026-10-14T16:15:22.919581Z","end":"2026-10-14T16:15:23.307276Z","steps":["trace[587312696] 'process raft request' (duration: 387.466357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.307435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:15:22.919564Z","time spent":"387.802825ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:15:23.307561Z","caller":"traceutil/trace.go:171","msg":"trace[1481042784] transaction","detail":"{read_only:false; response_revision:15432; number_of_response:1; }","duration":"152.435319ms","start":"2026-10-14T16:15:23.155109Z","end":"2026-10-14T16:15:23.307544Z","steps":["trace[1481042784] 'process raft request' (duration: 152.095521ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.307713Z","caller":"traceutil/trace.go:171","msg":"trace[733651991] transaction","detail":"{read_only:false; response_revision:15433; number_of_response:1; }","duration":"151.336483ms","start":"2026-10-14T16:15:23.156339Z","end":"2026-10-14T16:15:23.307675Z","steps":["trace[733651991] 'process raft request' (duration: 151.079137ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.308957Z","caller":"traceutil/trace.go:171","msg":"trace[340781928] linearizableReadLoop","detail":"{readStateIndex:15962; appliedIndex:15959; }","duration":"320.379753ms","start":"2026-10-14T16:15:22.988539Z","end":"2026-10-14T16:15:23.308919Z","steps":["trace[340781928] 'read index received' (duration: 305.820559ms)","trace[340781928] 'applied index is now lower than readState.Index' (duration: 14.557944ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:15:23.309325Z","caller":"traceutil/trace.go:171","msg":"trace[1112654667] transaction","detail":"{read_only:false; response_revision:15434; number_of_response:1; }","duration":"123.756661ms","start":"2026-10-14T16:15:23.185511Z","end":"2026-10-14T16:15:23.309268Z","steps":["trace[1112654667] 'process raft request' (duration: 122.088122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.309807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.250804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T16:15:23.309876Z","caller":"traceutil/trace.go:171","msg":"trace[1943788240] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15434; }","duration":"321.356846ms","start":"2026-10-14T16:15:22.9885Z","end":"2026-10-14T16:15:23.309857Z","steps":["trace[1943788240] 'agreement among raft nodes before linearized reading' (duration: 321.172192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.309932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:15:22.988468Z","time spent":"321.447499ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T16:15:23.310407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.185728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:15:23.310468Z","caller":"traceutil/trace.go:171","msg":"trace[1260306744] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15434; }","duration":"292.28222ms","start":"2026-10-14T16:15:23.018171Z","end":"2026-10-14T16:15:23.310453Z","steps":["trace[1260306744] 'agreement among raft nodes before linearized reading' (duration: 292.190618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.311225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.339498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:15:23.311288Z","caller":"traceutil/trace.go:171","msg":"trace[477548812] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15434; }","duration":"291.42241ms","start":"2026-10-14T16:15:23.01985Z","end":"2026-10-14T16:15:23.311273Z","steps":["trace[477548812] 'agreement among raft nodes before linearized reading' (duration: 291.336698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.311354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.445878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-14T16:15:23.311377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.547023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-72578\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-10-14T16:15:23.311417Z","caller":"traceutil/trace.go:171","msg":"trace[141308127] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15434; }","duration":"271.542501ms","start":"2026-10-14T16:15:23.039858Z","end":"2026-10-14T16:15:23.311401Z","steps":["trace[141308127] 'agreement among raft nodes before linearized reading' (duration: 271.326986ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.311429Z","caller":"traceutil/trace.go:171","msg":"trace[1818188518] range","detail":"{range_begin:/registry/pods/openstack/keepalived-72578; range_end:; response_count:1; response_revision:15434; }","duration":"291.655495ms","start":"2026-10-14T16:15:23.01976Z","end":"2026-10-14T16:15:23.311415Z","steps":["trace[1818188518] 'agreement among raft nodes before linearized reading' (duration: 291.494281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.311584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.154811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:15:23.311607Z","caller":"traceutil/trace.go:171","msg":"trace[1814701734] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15434; }","duration":"293.194291ms","start":"2026-10-14T16:15:23.018408Z","end":"2026-10-14T16:15:23.311602Z","steps":["trace[1814701734] 'agreement among raft nodes before linearized reading' (duration: 293.159671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.311667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.615728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T16:15:23.311732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.933836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-14T16:15:23.311789Z","caller":"traceutil/trace.go:171","msg":"trace[1784005208] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15434; }","duration":"138.002577ms","start":"2026-10-14T16:15:23.173772Z","end":"2026-10-14T16:15:23.311775Z","steps":["trace[1784005208] 'agreement among raft nodes before linearized reading' (duration: 137.865504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.311895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.923844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-14T16:15:23.311927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.466168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-14T16:15:23.311745Z","caller":"traceutil/trace.go:171","msg":"trace[105437855] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15434; }","duration":"137.721261ms","start":"2026-10-14T16:15:23.174002Z","end":"2026-10-14T16:15:23.311723Z","steps":["trace[105437855] 'agreement among raft nodes before linearized reading' (duration: 137.575618ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.311937Z","caller":"traceutil/trace.go:171","msg":"trace[1937879996] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15434; }","duration":"131.976285ms","start":"2026-10-14T16:15:23.17995Z","end":"2026-10-14T16:15:23.311927Z","steps":["trace[1937879996] 'agreement among raft nodes before linearized reading' (duration: 131.820281ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.311947Z","caller":"traceutil/trace.go:171","msg":"trace[1080099026] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15434; }","duration":"135.505218ms","start":"2026-10-14T16:15:23.176436Z","end":"2026-10-14T16:15:23.311941Z","steps":["trace[1080099026] 'agreement among raft nodes before linearized reading' (duration: 135.444907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.311918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.82027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T16:15:23.312025Z","caller":"traceutil/trace.go:171","msg":"trace[1242454137] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:15434; }","duration":"139.976684ms","start":"2026-10-14T16:15:23.172039Z","end":"2026-10-14T16:15:23.312016Z","steps":["trace[1242454137] 'agreement among raft nodes before linearized reading' (duration: 139.697998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:23.312055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.297985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"warn","ts":"2026-10-14T16:15:23.312077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.630751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:15:23.312118Z","caller":"traceutil/trace.go:171","msg":"trace[1709533548] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15434; }","duration":"135.704933ms","start":"2026-10-14T16:15:23.1764Z","end":"2026-10-14T16:15:23.312105Z","steps":["trace[1709533548] 'agreement among raft nodes before linearized reading' (duration: 135.648532ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:23.312114Z","caller":"traceutil/trace.go:171","msg":"trace[198036384] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15434; }","duration":"138.374186ms","start":"2026-10-14T16:15:23.173725Z","end":"2026-10-14T16:15:23.312099Z","steps":["trace[198036384] 'agreement among raft nodes before linearized reading' (duration: 138.232463ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:19:55.118554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15286} {"level":"info","ts":"2026-10-14T16:19:55.224501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15286,"took":"103.062177ms","hash":3226795803,"current-db-size-bytes":56385536,"current-db-size":"56 MB","current-db-size-in-use-bytes":31330304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:19:55.224568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3226795803,"revision":15286,"compact-revision":12716} {"level":"warn","ts":"2026-10-14T16:21:05.483749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.620826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:21:05.483962Z","caller":"traceutil/trace.go:171","msg":"trace[431079039] transaction","detail":"{read_only:false; response_revision:18002; number_of_response:1; }","duration":"333.003659ms","start":"2026-10-14T16:21:05.15094Z","end":"2026-10-14T16:21:05.483943Z","steps":["trace[431079039] 'process raft request' (duration: 62.091366ms)","trace[431079039] 'compare' (duration: 270.390011ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:05.48425Z","caller":"traceutil/trace.go:171","msg":"trace[1557792275] linearizableReadLoop","detail":"{readStateIndex:18621; appliedIndex:18619; }","duration":"324.937851ms","start":"2026-10-14T16:21:05.159283Z","end":"2026-10-14T16:21:05.484221Z","steps":["trace[1557792275] 'read index received' (duration: 53.739731ms)","trace[1557792275] 'applied index is now lower than readState.Index' (duration: 271.19676ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:05.484425Z","caller":"traceutil/trace.go:171","msg":"trace[1282975803] transaction","detail":"{read_only:false; response_revision:18003; number_of_response:1; }","duration":"332.308073ms","start":"2026-10-14T16:21:05.152104Z","end":"2026-10-14T16:21:05.484412Z","steps":["trace[1282975803] 'process raft request' (duration: 331.76864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:05.484542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:05.152088Z","time spent":"332.370774ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:21:05.484603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.338028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-14T16:21:05.484636Z","caller":"traceutil/trace.go:171","msg":"trace[1537217431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18003; }","duration":"142.40564ms","start":"2026-10-14T16:21:05.342221Z","end":"2026-10-14T16:21:05.484627Z","steps":["trace[1537217431] 'agreement among raft nodes before linearized reading' (duration: 142.287057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:05.484619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.601513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"warn","ts":"2026-10-14T16:21:05.484661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.552723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-10-14T16:21:05.484668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.377491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T16:21:05.484703Z","caller":"traceutil/trace.go:171","msg":"trace[196262179] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:18003; }","duration":"301.735996ms","start":"2026-10-14T16:21:05.182945Z","end":"2026-10-14T16:21:05.484681Z","steps":["trace[196262179] 'agreement among raft nodes before linearized reading' (duration: 301.544162ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:21:05.484746Z","caller":"traceutil/trace.go:171","msg":"trace[1316420859] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18003; }","duration":"258.664866ms","start":"2026-10-14T16:21:05.226059Z","end":"2026-10-14T16:21:05.484724Z","steps":["trace[1316420859] 'agreement among raft nodes before linearized reading' (duration: 258.450331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:05.484831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:05.182931Z","time spent":"301.808078ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-10-14T16:21:05.48475Z","caller":"traceutil/trace.go:171","msg":"trace[1947746653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18003; }","duration":"325.481212ms","start":"2026-10-14T16:21:05.159249Z","end":"2026-10-14T16:21:05.48473Z","steps":["trace[1947746653] 'agreement among raft nodes before linearized reading' (duration: 325.286078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:05.484896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:05.159237Z","time spent":"325.646207ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T16:21:05.484661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.113758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T16:21:05.484999Z","caller":"traceutil/trace.go:171","msg":"trace[1379640935] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18003; }","duration":"274.459506ms","start":"2026-10-14T16:21:05.210505Z","end":"2026-10-14T16:21:05.484964Z","steps":["trace[1379640935] 'agreement among raft nodes before linearized reading' (duration: 274.033806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:05.485264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:05.150923Z","time spent":"333.099111ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:21:05.60905Z","caller":"traceutil/trace.go:171","msg":"trace[1325184444] transaction","detail":"{read_only:false; response_revision:18006; number_of_response:1; }","duration":"118.10696ms","start":"2026-10-14T16:21:05.490906Z","end":"2026-10-14T16:21:05.609013Z","steps":["trace[1325184444] 'process raft request' (duration: 117.68065ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:21:05.609497Z","caller":"traceutil/trace.go:171","msg":"trace[940968968] transaction","detail":"{read_only:false; response_revision:18005; number_of_response:1; }","duration":"118.749505ms","start":"2026-10-14T16:21:05.490728Z","end":"2026-10-14T16:21:05.609478Z","steps":["trace[940968968] 'process raft request' (duration: 94.67241ms)","trace[940968968] 'compare' (duration: 23.074791ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:37.936421Z","caller":"traceutil/trace.go:171","msg":"trace[529878641] transaction","detail":"{read_only:false; response_revision:18435; number_of_response:1; }","duration":"128.626697ms","start":"2026-10-14T16:21:37.807762Z","end":"2026-10-14T16:21:37.936388Z","steps":["trace[529878641] 'process raft request' (duration: 104.950662ms)","trace[529878641] 'compare' (duration: 23.428309ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:23:41.341979Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:23:41.349661Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T16:23:41.349772Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T16:24:55.129526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17439} {"level":"info","ts":"2026-10-14T16:24:55.23246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17439,"took":"99.092404ms","hash":2669345050,"current-db-size-bytes":56385536,"current-db-size":"56 MB","current-db-size-in-use-bytes":34705408,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T16:24:55.232544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2669345050,"revision":17439,"compact-revision":15286} {"level":"warn","ts":"2026-10-14T16:26:04.043396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.775644ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-14T16:26:04.043892Z","caller":"traceutil/trace.go:171","msg":"trace[854989294] transaction","detail":"{read_only:false; response_revision:20370; number_of_response:1; }","duration":"178.151878ms","start":"2026-10-14T16:26:03.865712Z","end":"2026-10-14T16:26:04.043864Z","steps":["trace[854989294] 'process raft request' (duration: 177.796339ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:04.106492Z","caller":"traceutil/trace.go:171","msg":"trace[1800802383] transaction","detail":"{read_only:false; response_revision:20372; number_of_response:1; }","duration":"227.528726ms","start":"2026-10-14T16:26:03.878938Z","end":"2026-10-14T16:26:04.106467Z","steps":["trace[1800802383] 'process raft request' (duration: 227.470444ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:04.106543Z","caller":"traceutil/trace.go:171","msg":"trace[1382360322] transaction","detail":"{read_only:false; response_revision:20371; number_of_response:1; }","duration":"230.337472ms","start":"2026-10-14T16:26:03.876171Z","end":"2026-10-14T16:26:04.106508Z","steps":["trace[1382360322] 'process raft request' (duration: 167.680722ms)","trace[1382360322] 'compare' (duration: 62.417834ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:20.238574Z","caller":"traceutil/trace.go:171","msg":"trace[305980114] transaction","detail":"{read_only:false; response_revision:20451; number_of_response:1; }","duration":"128.750829ms","start":"2026-10-14T16:26:20.109797Z","end":"2026-10-14T16:26:20.238548Z","steps":["trace[305980114] 'process raft request' (duration: 128.584946ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:20.384716Z","caller":"traceutil/trace.go:171","msg":"trace[269245918] transaction","detail":"{read_only:false; response_revision:20453; number_of_response:1; }","duration":"203.124843ms","start":"2026-10-14T16:26:20.181555Z","end":"2026-10-14T16:26:20.38468Z","steps":["trace[269245918] 'process raft request' (duration: 203.032691ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:20.384779Z","caller":"traceutil/trace.go:171","msg":"trace[488071784] transaction","detail":"{read_only:false; response_revision:20452; number_of_response:1; }","duration":"216.19ms","start":"2026-10-14T16:26:20.168527Z","end":"2026-10-14T16:26:20.384717Z","steps":["trace[488071784] 'process raft request' (duration: 128.645627ms)","trace[488071784] 'compare' (duration: 87.088403ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:21.285241Z","caller":"traceutil/trace.go:171","msg":"trace[606907376] transaction","detail":"{read_only:false; response_revision:20455; number_of_response:1; }","duration":"149.621079ms","start":"2026-10-14T16:26:21.135569Z","end":"2026-10-14T16:26:21.28519Z","steps":["trace[606907376] 'process raft request' (duration: 149.465745ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:21.292676Z","caller":"traceutil/trace.go:171","msg":"trace[1122634134] linearizableReadLoop","detail":"{readStateIndex:21155; appliedIndex:21154; }","duration":"147.906439ms","start":"2026-10-14T16:26:21.144754Z","end":"2026-10-14T16:26:21.29266Z","steps":["trace[1122634134] 'read index received' (duration: 140.424063ms)","trace[1122634134] 'applied index is now lower than readState.Index' (duration: 7.481666ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:21.292766Z","caller":"traceutil/trace.go:171","msg":"trace[843562337] transaction","detail":"{read_only:false; response_revision:20456; number_of_response:1; }","duration":"153.337246ms","start":"2026-10-14T16:26:21.139398Z","end":"2026-10-14T16:26:21.292735Z","steps":["trace[843562337] 'process raft request' (duration: 153.180812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:21.292894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.133494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-14T16:26:21.292932Z","caller":"traceutil/trace.go:171","msg":"trace[266539959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20456; }","duration":"148.186385ms","start":"2026-10-14T16:26:21.144735Z","end":"2026-10-14T16:26:21.292922Z","steps":["trace[266539959] 'agreement among raft nodes before linearized reading' (duration: 148.010281ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:29:55.139135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19945} {"level":"info","ts":"2026-10-14T16:29:55.268995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19945,"took":"126.127114ms","hash":974755107,"current-db-size-bytes":56385536,"current-db-size":"56 MB","current-db-size-in-use-bytes":30351360,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T16:29:55.269061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":974755107,"revision":19945,"compact-revision":17439} {"level":"info","ts":"2026-10-14T16:30:03.466066Z","caller":"traceutil/trace.go:171","msg":"trace[956124878] linearizableReadLoop","detail":"{readStateIndex:23094; appliedIndex:23092; }","duration":"170.069588ms","start":"2026-10-14T16:30:03.295971Z","end":"2026-10-14T16:30:03.46604Z","steps":["trace[956124878] 'read index received' (duration: 9.552805ms)","trace[956124878] 'applied index is now lower than readState.Index' (duration: 160.514743ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:30:03.4661Z","caller":"traceutil/trace.go:171","msg":"trace[653276444] transaction","detail":"{read_only:false; response_revision:22333; number_of_response:1; }","duration":"175.313882ms","start":"2026-10-14T16:30:03.29076Z","end":"2026-10-14T16:30:03.466074Z","steps":["trace[653276444] 'process raft request' (duration: 174.292258ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:03.466152Z","caller":"traceutil/trace.go:171","msg":"trace[653982737] transaction","detail":"{read_only:false; response_revision:22334; number_of_response:1; }","duration":"158.270831ms","start":"2026-10-14T16:30:03.307869Z","end":"2026-10-14T16:30:03.46614Z","steps":["trace[653982737] 'process raft request' (duration: 158.065066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:03.466249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.262043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/capi-system/capi-selfsigned-issuer\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:30:03.466083Z","caller":"traceutil/trace.go:171","msg":"trace[1386842012] transaction","detail":"{read_only:false; response_revision:22335; number_of_response:1; }","duration":"118.167915ms","start":"2026-10-14T16:30:03.347893Z","end":"2026-10-14T16:30:03.466061Z","steps":["trace[1386842012] 'process raft request' (duration: 118.122284ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:03.466298Z","caller":"traceutil/trace.go:171","msg":"trace[1718195233] range","detail":"{range_begin:/registry/cert-manager.io/issuers/capi-system/capi-selfsigned-issuer; range_end:; response_count:0; response_revision:22335; }","duration":"170.342875ms","start":"2026-10-14T16:30:03.295938Z","end":"2026-10-14T16:30:03.466281Z","steps":["trace[1718195233] 'agreement among raft nodes before linearized reading' (duration: 170.247593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:03.466334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.708504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:30:03.466427Z","caller":"traceutil/trace.go:171","msg":"trace[123632699] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:22335; }","duration":"100.847547ms","start":"2026-10-14T16:30:03.365562Z","end":"2026-10-14T16:30:03.46641Z","steps":["trace[123632699] 'agreement among raft nodes before linearized reading' (duration: 100.715174ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:30:13.663317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.446322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:30:13.663577Z","caller":"traceutil/trace.go:171","msg":"trace[1432566176] transaction","detail":"{read_only:false; response_revision:22880; number_of_response:1; }","duration":"164.341303ms","start":"2026-10-14T16:30:13.4992Z","end":"2026-10-14T16:30:13.663542Z","steps":["trace[1432566176] 'process raft request' (duration: 56.43266ms)","trace[1432566176] 'compare' (duration: 107.283249ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:30:13.663595Z","caller":"traceutil/trace.go:171","msg":"trace[2065108150] transaction","detail":"{read_only:false; response_revision:22881; number_of_response:1; }","duration":"161.155219ms","start":"2026-10-14T16:30:13.502408Z","end":"2026-10-14T16:30:13.663564Z","steps":["trace[2065108150] 'process raft request' (duration: 161.043736ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:31:43.329688Z","caller":"traceutil/trace.go:171","msg":"trace[1505493207] linearizableReadLoop","detail":"{readStateIndex:24627; appliedIndex:24626; }","duration":"125.946668ms","start":"2026-10-14T16:31:43.203717Z","end":"2026-10-14T16:31:43.329664Z","steps":["trace[1505493207] 'read index received' (duration: 125.675282ms)","trace[1505493207] 'applied index is now lower than readState.Index' (duration: 270.036µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:31:43.329753Z","caller":"traceutil/trace.go:171","msg":"trace[2022737118] transaction","detail":"{read_only:false; response_revision:23832; number_of_response:1; }","duration":"186.206489ms","start":"2026-10-14T16:31:43.143511Z","end":"2026-10-14T16:31:43.329718Z","steps":["trace[2022737118] 'process raft request' (duration: 186.002884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:31:43.329918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.188814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T16:31:43.329953Z","caller":"traceutil/trace.go:171","msg":"trace[909891118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23832; }","duration":"126.274326ms","start":"2026-10-14T16:31:43.203667Z","end":"2026-10-14T16:31:43.329942Z","steps":["trace[909891118] 'agreement among raft nodes before linearized reading' (duration: 126.141933ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:31:43.473783Z","caller":"traceutil/trace.go:171","msg":"trace[1502370888] transaction","detail":"{read_only:false; response_revision:23834; number_of_response:1; }","duration":"139.107079ms","start":"2026-10-14T16:31:43.334646Z","end":"2026-10-14T16:31:43.473753Z","steps":["trace[1502370888] 'process raft request' (duration: 53.580513ms)","trace[1502370888] 'compare' (duration: 84.911991ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:33:36.684933Z","caller":"traceutil/trace.go:171","msg":"trace[439768812] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"155.840563ms","start":"2026-10-14T16:33:36.529058Z","end":"2026-10-14T16:33:36.684898Z","steps":["trace[439768812] 'process raft request' (duration: 155.639788ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:33:36.913047Z","caller":"traceutil/trace.go:171","msg":"trace[1913318235] linearizableReadLoop","detail":"{readStateIndex:25703; appliedIndex:25702; }","duration":"122.861986ms","start":"2026-10-14T16:33:36.79016Z","end":"2026-10-14T16:33:36.913022Z","steps":["trace[1913318235] 'read index received' (duration: 88.019725ms)","trace[1913318235] 'applied index is now lower than readState.Index' (duration: 34.841391ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:33:36.913222Z","caller":"traceutil/trace.go:171","msg":"trace[907971590] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"126.667086ms","start":"2026-10-14T16:33:36.786539Z","end":"2026-10-14T16:33:36.913206Z","steps":["trace[907971590] 'process raft request' (duration: 91.691051ms)","trace[907971590] 'compare' (duration: 34.676257ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:33:36.913882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.703306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:33:36.913963Z","caller":"traceutil/trace.go:171","msg":"trace[1290490040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24880; }","duration":"123.818509ms","start":"2026-10-14T16:33:36.790125Z","end":"2026-10-14T16:33:36.913943Z","steps":["trace[1290490040] 'agreement among raft nodes before linearized reading' (duration: 123.674106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:37.38205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.7694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:33:37.382201Z","caller":"traceutil/trace.go:171","msg":"trace[969097506] linearizableReadLoop","detail":"{readStateIndex:25704; appliedIndex:25703; }","duration":"461.98449ms","start":"2026-10-14T16:33:36.920197Z","end":"2026-10-14T16:33:37.382182Z","steps":["trace[969097506] 'read index received' (duration: 238.973493ms)","trace[969097506] 'applied index is now lower than readState.Index' (duration: 223.005706ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:33:37.38223Z","caller":"traceutil/trace.go:171","msg":"trace[47119791] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"463.965046ms","start":"2026-10-14T16:33:36.918232Z","end":"2026-10-14T16:33:37.382197Z","steps":["trace[47119791] 'process raft request' (duration: 240.95989ms)","trace[47119791] 'compare' (duration: 222.639847ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:33:37.382369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:33:36.9182Z","time spent":"464.091969ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:33:37.38237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.166004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-10-14T16:33:37.382418Z","caller":"traceutil/trace.go:171","msg":"trace[538607971] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24881; }","duration":"462.228185ms","start":"2026-10-14T16:33:36.920172Z","end":"2026-10-14T16:33:37.3824Z","steps":["trace[538607971] 'agreement among raft nodes before linearized reading' (duration: 462.062391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:37.382461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:33:36.920162Z","time spent":"462.285146ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-14T16:33:37.408026Z","caller":"traceutil/trace.go:171","msg":"trace[1801276791] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"204.467989ms","start":"2026-10-14T16:33:37.203533Z","end":"2026-10-14T16:33:37.408001Z","steps":["trace[1801276791] 'process raft request' (duration: 204.323336ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:33:37.455094Z","caller":"traceutil/trace.go:171","msg":"trace[171010357] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"120.853219ms","start":"2026-10-14T16:33:37.334219Z","end":"2026-10-14T16:33:37.455073Z","steps":["trace[171010357] 'process raft request' (duration: 120.595973ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:42.110193Z","caller":"traceutil/trace.go:171","msg":"trace[1311423727] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"364.469842ms","start":"2026-10-14T16:34:41.745692Z","end":"2026-10-14T16:34:42.110161Z","steps":["trace[1311423727] 'process raft request' (duration: 364.241877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:42.110442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:41.745676Z","time spent":"364.609475ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:42.110494Z","caller":"traceutil/trace.go:171","msg":"trace[1705696361] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"337.23217ms","start":"2026-10-14T16:34:41.773237Z","end":"2026-10-14T16:34:42.110469Z","steps":["trace[1705696361] 'process raft request' (duration: 337.108757ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:42.110513Z","caller":"traceutil/trace.go:171","msg":"trace[1207893970] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"361.724728ms","start":"2026-10-14T16:34:41.748741Z","end":"2026-10-14T16:34:42.110466Z","steps":["trace[1207893970] 'process raft request' (duration: 361.366819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:42.110639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:41.773206Z","time spent":"337.345183ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:34:42.110703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:41.748715Z","time spent":"361.873341ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:42.180685Z","caller":"traceutil/trace.go:171","msg":"trace[52526031] linearizableReadLoop","detail":"{readStateIndex:26296; appliedIndex:26292; }","duration":"388.215382ms","start":"2026-10-14T16:34:41.792433Z","end":"2026-10-14T16:34:42.180649Z","steps":["trace[52526031] 'read index received' (duration: 317.500325ms)","trace[52526031] 'applied index is now lower than readState.Index' (duration: 70.712977ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:34:42.180892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.430197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:34:42.180955Z","caller":"traceutil/trace.go:171","msg":"trace[235372531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25458; }","duration":"388.5395ms","start":"2026-10-14T16:34:41.792398Z","end":"2026-10-14T16:34:42.180938Z","steps":["trace[235372531] 'agreement among raft nodes before linearized reading' (duration: 388.387056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:42.181008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:41.792386Z","time spent":"388.609711ms","remote":"127.0.0.1:53236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T16:34:42.181173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.506483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:34:42.181253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.9167ms","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:8"} {"level":"info","ts":"2026-10-14T16:34:42.181283Z","caller":"traceutil/trace.go:171","msg":"trace[1261934854] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25458; }","duration":"199.698258ms","start":"2026-10-14T16:34:41.981561Z","end":"2026-10-14T16:34:42.181259Z","steps":["trace[1261934854] 'agreement among raft nodes before linearized reading' (duration: 199.496453ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:42.181313Z","caller":"traceutil/trace.go:171","msg":"trace[1260789397] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25458; }","duration":"160.028723ms","start":"2026-10-14T16:34:42.02127Z","end":"2026-10-14T16:34:42.181298Z","steps":["trace[1260789397] 'agreement among raft nodes before linearized reading' (duration: 159.90134ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:42.181188Z","caller":"traceutil/trace.go:171","msg":"trace[1607469750] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"394.09361ms","start":"2026-10-14T16:34:41.787045Z","end":"2026-10-14T16:34:42.181139Z","steps":["trace[1607469750] 'process raft request' (duration: 393.425695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:42.181212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.325825ms","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-10-14T16:34:42.181471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:41.787023Z","time spent":"394.349167ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:42.181511Z","caller":"traceutil/trace.go:171","msg":"trace[1230276207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25458; }","duration":"100.659913ms","start":"2026-10-14T16:34:42.080835Z","end":"2026-10-14T16:34:42.181495Z","steps":["trace[1230276207] 'agreement among raft nodes before linearized reading' (duration: 100.242393ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:42.529313Z","caller":"traceutil/trace.go:171","msg":"trace[297899119] linearizableReadLoop","detail":"{readStateIndex:26297; appliedIndex:26296; }","duration":"190.407188ms","start":"2026-10-14T16:34:42.338884Z","end":"2026-10-14T16:34:42.529292Z","steps":["trace[297899119] 'read index received' (duration: 127.763142ms)","trace[297899119] 'applied index is now lower than readState.Index' (duration: 62.642926ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:42.529404Z","caller":"traceutil/trace.go:171","msg":"trace[657953974] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"343.415706ms","start":"2026-10-14T16:34:42.185946Z","end":"2026-10-14T16:34:42.529362Z","steps":["trace[657953974] 'process raft request' (duration: 280.702928ms)","trace[657953974] 'compare' (duration: 62.298608ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:34:42.529568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.185925Z","time spent":"343.552679ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:34:42.529889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.993093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-14T16:34:42.530043Z","caller":"traceutil/trace.go:171","msg":"trace[1545176518] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25459; }","duration":"191.144506ms","start":"2026-10-14T16:34:42.338821Z","end":"2026-10-14T16:34:42.529965Z","steps":["trace[1545176518] 'agreement among raft nodes before linearized reading' (duration: 190.555262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.106583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.026402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:34:43.106735Z","caller":"traceutil/trace.go:171","msg":"trace[2105494801] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"688.628762ms","start":"2026-10-14T16:34:42.418067Z","end":"2026-10-14T16:34:43.106696Z","steps":["trace[2105494801] 'process raft request' (duration: 357.412915ms)","trace[2105494801] 'compare' (duration: 330.890919ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:43.106765Z","caller":"traceutil/trace.go:171","msg":"trace[74692464] linearizableReadLoop","detail":"{readStateIndex:26298; appliedIndex:26297; }","duration":"577.385949ms","start":"2026-10-14T16:34:42.529363Z","end":"2026-10-14T16:34:43.106749Z","steps":["trace[74692464] 'read index received' (duration: 246.002929ms)","trace[74692464] 'applied index is now lower than readState.Index' (duration: 331.38208ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:43.107327Z","caller":"traceutil/trace.go:171","msg":"trace[396461452] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"569.904893ms","start":"2026-10-14T16:34:42.537393Z","end":"2026-10-14T16:34:43.107298Z","steps":["trace[396461452] 'process raft request' (duration: 569.311959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.10745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.537364Z","time spent":"570.026275ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:34:43.10784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.911005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-14T16:34:43.107878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.418043Z","time spent":"688.788035ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:43.107903Z","caller":"traceutil/trace.go:171","msg":"trace[1245839346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25461; }","duration":"685.007597ms","start":"2026-10-14T16:34:42.42288Z","end":"2026-10-14T16:34:43.107887Z","steps":["trace[1245839346] 'agreement among raft nodes before linearized reading' (duration: 683.978823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.107955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.422864Z","time spent":"685.079879ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T16:34:43.107955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.233839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T16:34:43.108028Z","caller":"traceutil/trace.go:171","msg":"trace[179449696] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"374.935167ms","start":"2026-10-14T16:34:42.733074Z","end":"2026-10-14T16:34:43.108009Z","steps":["trace[179449696] 'process raft request' (duration: 374.054226ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:43.108058Z","caller":"traceutil/trace.go:171","msg":"trace[290144848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25461; }","duration":"673.405693ms","start":"2026-10-14T16:34:42.434636Z","end":"2026-10-14T16:34:43.108042Z","steps":["trace[290144848] 'agreement among raft nodes before linearized reading' (duration: 672.213925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.108101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.434593Z","time spent":"673.497645ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T16:34:43.108131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.733045Z","time spent":"375.02612ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:43.108272Z","caller":"traceutil/trace.go:171","msg":"trace[1790013869] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"134.160702ms","start":"2026-10-14T16:34:42.974102Z","end":"2026-10-14T16:34:43.108262Z","steps":["trace[1790013869] 'process raft request' (duration: 133.841164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.108355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.694508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-14T16:34:43.108349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.143098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:34:43.108407Z","caller":"traceutil/trace.go:171","msg":"trace[1319751508] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25463; }","duration":"569.80019ms","start":"2026-10-14T16:34:42.538592Z","end":"2026-10-14T16:34:43.108392Z","steps":["trace[1319751508] 'agreement among raft nodes before linearized reading' (duration: 569.608956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.108442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.538581Z","time spent":"569.851522ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T16:34:43.108435Z","caller":"traceutil/trace.go:171","msg":"trace[1984051035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25463; }","duration":"315.267221ms","start":"2026-10-14T16:34:42.793143Z","end":"2026-10-14T16:34:43.10841Z","steps":["trace[1984051035] 'agreement among raft nodes before linearized reading' (duration: 315.134067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.108494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.793122Z","time spent":"315.360843ms","remote":"127.0.0.1:53236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T16:34:43.108753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.458729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T16:34:43.108969Z","caller":"traceutil/trace.go:171","msg":"trace[213603213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25461; }","duration":"638.712305ms","start":"2026-10-14T16:34:42.470242Z","end":"2026-10-14T16:34:43.108954Z","steps":["trace[213603213] 'agreement among raft nodes before linearized reading' (duration: 636.536884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.109069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:42.470219Z","time spent":"638.833678ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T16:34:43.914165Z","caller":"traceutil/trace.go:171","msg":"trace[1605299411] linearizableReadLoop","detail":"{readStateIndex:26305; appliedIndex:26301; }","duration":"773.142934ms","start":"2026-10-14T16:34:43.140984Z","end":"2026-10-14T16:34:43.914127Z","steps":["trace[1605299411] 'read index received' (duration: 16.254814ms)","trace[1605299411] 'applied index is now lower than readState.Index' (duration: 756.887ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:43.914185Z","caller":"traceutil/trace.go:171","msg":"trace[550657826] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"797.241371ms","start":"2026-10-14T16:34:43.1169Z","end":"2026-10-14T16:34:43.914142Z","steps":["trace[550657826] 'process raft request' (duration: 796.979645ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:43.914205Z","caller":"traceutil/trace.go:171","msg":"trace[1999434742] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"796.3524ms","start":"2026-10-14T16:34:43.11782Z","end":"2026-10-14T16:34:43.914173Z","steps":["trace[1999434742] 'process raft request' (duration: 796.214527ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:43.914298Z","caller":"traceutil/trace.go:171","msg":"trace[222167959] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"796.33913ms","start":"2026-10-14T16:34:43.117935Z","end":"2026-10-14T16:34:43.914274Z","steps":["trace[222167959] 'process raft request' (duration: 796.143296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.914381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:43.116875Z","time spent":"797.404426ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:34:43.914415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:43.117923Z","time spent":"796.425302ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:34:43.914438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:43.117805Z","time spent":"796.520945ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:34:43.914482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.492792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-10-14T16:34:43.914536Z","caller":"traceutil/trace.go:171","msg":"trace[1755595688] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25467; }","duration":"773.606394ms","start":"2026-10-14T16:34:43.140913Z","end":"2026-10-14T16:34:43.914519Z","steps":["trace[1755595688] 'agreement among raft nodes before linearized reading' (duration: 773.371759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.914592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:43.140869Z","time spent":"773.708927ms","remote":"127.0.0.1:39346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7937,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-10-14T16:34:43.920234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.577942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:34:43.920297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.208277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:34:43.920335Z","caller":"traceutil/trace.go:171","msg":"trace[775992249] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25468; }","duration":"630.742067ms","start":"2026-10-14T16:34:43.289569Z","end":"2026-10-14T16:34:43.920311Z","steps":["trace[775992249] 'agreement among raft nodes before linearized reading' (duration: 630.564303ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:43.920326Z","caller":"traceutil/trace.go:171","msg":"trace[1782303015] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"132.004481ms","start":"2026-10-14T16:34:43.788289Z","end":"2026-10-14T16:34:43.920293Z","steps":["trace[1782303015] 'process raft request' (duration: 131.697084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.920386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:43.289545Z","time spent":"630.826068ms","remote":"127.0.0.1:47618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:34:43.920445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.765876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:34:43.92045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.376193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:34:43.920498Z","caller":"traceutil/trace.go:171","msg":"trace[2000645145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25468; }","duration":"128.857318ms","start":"2026-10-14T16:34:43.791629Z","end":"2026-10-14T16:34:43.920486Z","steps":["trace[2000645145] 'agreement among raft nodes before linearized reading' (duration: 128.784296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.920499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.175506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:34:43.920382Z","caller":"traceutil/trace.go:171","msg":"trace[1419030039] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25468; }","duration":"166.364761ms","start":"2026-10-14T16:34:43.753992Z","end":"2026-10-14T16:34:43.920356Z","steps":["trace[1419030039] 'agreement among raft nodes before linearized reading' (duration: 166.186567ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:43.920636Z","caller":"traceutil/trace.go:171","msg":"trace[1344343924] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25468; }","duration":"194.37811ms","start":"2026-10-14T16:34:43.726216Z","end":"2026-10-14T16:34:43.920594Z","steps":["trace[1344343924] 'agreement among raft nodes before linearized reading' (duration: 194.182526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.920516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.906893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"info","ts":"2026-10-14T16:34:43.92081Z","caller":"traceutil/trace.go:171","msg":"trace[691735639] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:25468; }","duration":"503.233771ms","start":"2026-10-14T16:34:43.417552Z","end":"2026-10-14T16:34:43.920786Z","steps":["trace[691735639] 'agreement among raft nodes before linearized reading' (duration: 502.820221ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:43.920534Z","caller":"traceutil/trace.go:171","msg":"trace[1478883532] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25468; }","duration":"427.519176ms","start":"2026-10-14T16:34:43.49299Z","end":"2026-10-14T16:34:43.920509Z","steps":["trace[1478883532] 'agreement among raft nodes before linearized reading' (duration: 427.344102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:43.920867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:43.417533Z","time spent":"503.317853ms","remote":"127.0.0.1:53430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":870,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-10-14T16:34:43.920909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:43.492969Z","time spent":"427.906626ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:34:43.921674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.326489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T16:34:43.921768Z","caller":"traceutil/trace.go:171","msg":"trace[1994513735] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25468; }","duration":"105.824675ms","start":"2026-10-14T16:34:43.815928Z","end":"2026-10-14T16:34:43.921753Z","steps":["trace[1994513735] 'agreement among raft nodes before linearized reading' (duration: 104.276838ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:44.231733Z","caller":"traceutil/trace.go:171","msg":"trace[405765403] linearizableReadLoop","detail":"{readStateIndex:26309; appliedIndex:26307; }","duration":"111.301724ms","start":"2026-10-14T16:34:44.120414Z","end":"2026-10-14T16:34:44.231716Z","steps":["trace[405765403] 'read index received' (duration: 56.464851ms)","trace[405765403] 'applied index is now lower than readState.Index' (duration: 54.836283ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:34:44.231741Z","caller":"traceutil/trace.go:171","msg":"trace[54861961] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"115.300088ms","start":"2026-10-14T16:34:44.11642Z","end":"2026-10-14T16:34:44.23172Z","steps":["trace[54861961] 'process raft request' (duration: 115.134044ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:44.231815Z","caller":"traceutil/trace.go:171","msg":"trace[358988771] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"110.130626ms","start":"2026-10-14T16:34:44.121677Z","end":"2026-10-14T16:34:44.231807Z","steps":["trace[358988771] 'process raft request' (duration: 110.007963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:44.232109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.667312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-14T16:34:44.232196Z","caller":"traceutil/trace.go:171","msg":"trace[355328938] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25471; }","duration":"111.816416ms","start":"2026-10-14T16:34:44.120361Z","end":"2026-10-14T16:34:44.232177Z","steps":["trace[355328938] 'agreement among raft nodes before linearized reading' (duration: 111.523289ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:55.146985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22141} {"level":"info","ts":"2026-10-14T16:34:55.289602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22141,"took":"138.858893ms","hash":2088332716,"current-db-size-bytes":58494976,"current-db-size":"58 MB","current-db-size-in-use-bytes":44183552,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-14T16:34:55.289674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2088332716,"revision":22141,"compact-revision":19945} {"level":"info","ts":"2026-10-14T16:36:10.219728Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a2a.wal"} {"level":"info","ts":"2026-10-14T16:39:55.155972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25549} {"level":"info","ts":"2026-10-14T16:39:55.343276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25549,"took":"183.049821ms","hash":3220009029,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":35553280,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T16:39:55.343361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3220009029,"revision":25549,"compact-revision":22141} {"level":"info","ts":"2026-10-14T16:41:20.263615Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:41:20.273972Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T16:41:20.27412Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T16:44:55.162911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28410} {"level":"info","ts":"2026-10-14T16:44:55.328255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28410,"took":"160.739114ms","hash":3344170879,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":30171136,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T16:44:55.328329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3344170879,"revision":28410,"compact-revision":25549} {"level":"info","ts":"2026-10-14T16:49:55.17102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30631} {"level":"info","ts":"2026-10-14T16:49:55.301805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30631,"took":"126.742403ms","hash":3484913039,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":30957568,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:49:55.301879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3484913039,"revision":30631,"compact-revision":28410} {"level":"info","ts":"2026-10-14T16:54:55.179519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32889} {"level":"info","ts":"2026-10-14T16:54:55.342355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32889,"took":"158.454919ms","hash":1463429620,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":30781440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:54:55.34242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1463429620,"revision":32889,"compact-revision":30631} {"level":"info","ts":"2026-10-14T16:59:55.185648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35186} {"level":"info","ts":"2026-10-14T16:59:55.319923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35186,"took":"129.131079ms","hash":1043700810,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":32165888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T16:59:55.319972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1043700810,"revision":35186,"compact-revision":32889} {"level":"info","ts":"2026-10-14T17:02:43.655061Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T17:02:43.661159Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T17:02:43.661295Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T17:04:55.194387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37411} {"level":"info","ts":"2026-10-14T17:04:55.334394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37411,"took":"135.956051ms","hash":79808409,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":32690176,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T17:04:55.334484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":79808409,"revision":37411,"compact-revision":35186}