{"level":"warn","ts":"2026-10-15T11:17:00.887109Z","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-15T11:17:00.887201Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.52:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.52:2380","--initial-cluster=instance=https://199.204.45.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.52: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-15T11:17:00.887298Z","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-15T11:17:00.887314Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.52:2380"]} {"level":"info","ts":"2026-10-15T11:17:00.887338Z","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-15T11:17:00.888112Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.52:2379"]} {"level":"info","ts":"2026-10-15T11:17:00.888213Z","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://199.204.45.52:2380"],"listen-peer-urls":["https://199.204.45.52:2380"],"advertise-client-urls":["https://199.204.45.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.52: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-15T11:17:00.892919Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.455653ms"} {"level":"info","ts":"2026-10-15T11:17:00.908526Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"65c3b6b1424650a7","cluster-id":"23202d5627f8e737"} {"level":"info","ts":"2026-10-15T11:17:00.908606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T11:17:00.908628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 became follower at term 0"} {"level":"info","ts":"2026-10-15T11:17:00.90864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 65c3b6b1424650a7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T11:17:00.908648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 became follower at term 1"} {"level":"info","ts":"2026-10-15T11:17:00.908713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 switched to configuration voters=(7332905490726670503)"} {"level":"warn","ts":"2026-10-15T11:17:00.918041Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T11:17:00.920224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T11:17:00.926532Z","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-15T11:17:00.929065Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"65c3b6b1424650a7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T11:17:00.929307Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"65c3b6b1424650a7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T11:17:00.929592Z","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-15T11:17:00.929749Z","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-15T11:17:00.929885Z","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-15T11:17:00.930626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 switched to configuration voters=(7332905490726670503)"} {"level":"info","ts":"2026-10-15T11:17:00.932451Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"23202d5627f8e737","local-member-id":"65c3b6b1424650a7","added-peer-id":"65c3b6b1424650a7","added-peer-peer-urls":["https://199.204.45.52:2380"]} {"level":"info","ts":"2026-10-15T11:17:00.934113Z","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-15T11:17:00.934281Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.52:2380"} {"level":"info","ts":"2026-10-15T11:17:00.934325Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.52:2380"} {"level":"info","ts":"2026-10-15T11:17:00.934419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"65c3b6b1424650a7","initial-advertise-peer-urls":["https://199.204.45.52:2380"],"listen-peer-urls":["https://199.204.45.52:2380"],"advertise-client-urls":["https://199.204.45.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T11:17:00.934465Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T11:17:01.109296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T11:17:01.109385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T11:17:01.109424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 received MsgPreVoteResp from 65c3b6b1424650a7 at term 1"} {"level":"info","ts":"2026-10-15T11:17:01.109453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 became candidate at term 2"} {"level":"info","ts":"2026-10-15T11:17:01.109464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 received MsgVoteResp from 65c3b6b1424650a7 at term 2"} {"level":"info","ts":"2026-10-15T11:17:01.109482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65c3b6b1424650a7 became leader at term 2"} {"level":"info","ts":"2026-10-15T11:17:01.109511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 65c3b6b1424650a7 elected leader 65c3b6b1424650a7 at term 2"} {"level":"info","ts":"2026-10-15T11:17:01.110909Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:17:01.11234Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"65c3b6b1424650a7","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.52:2379]}","request-path":"/0/members/65c3b6b1424650a7/attributes","cluster-id":"23202d5627f8e737","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T11:17:01.112366Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:17:01.112409Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T11:17:01.11272Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T11:17:01.112787Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T11:17:01.114448Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"23202d5627f8e737","local-member-id":"65c3b6b1424650a7","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:17:01.114576Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:17:01.114617Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T11:17:01.114737Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T11:17:01.116818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.52:2379"} {"level":"info","ts":"2026-10-15T11:17:03.313545Z","caller":"traceutil/trace.go:171","msg":"trace[1614902742] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"106.88385ms","start":"2026-10-15T11:17:03.20663Z","end":"2026-10-15T11:17:03.313514Z","steps":["trace[1614902742] 'process raft request' (duration: 105.285271ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:17:03.313666Z","caller":"traceutil/trace.go:171","msg":"trace[1259996449] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"103.285619ms","start":"2026-10-15T11:17:03.21035Z","end":"2026-10-15T11:17:03.313636Z","steps":["trace[1259996449] 'process raft request' (duration: 103.048003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:53.363457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.654839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-15T11:17:53.363682Z","caller":"traceutil/trace.go:171","msg":"trace[498949204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"105.069519ms","start":"2026-10-15T11:17:53.258564Z","end":"2026-10-15T11:17:53.363634Z","steps":["trace[498949204] 'range keys from in-memory index tree' (duration: 104.517876ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:17:53.667662Z","caller":"traceutil/trace.go:171","msg":"trace[1361905920] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"116.422824ms","start":"2026-10-15T11:17:53.551211Z","end":"2026-10-15T11:17:53.667634Z","steps":["trace[1361905920] 'read index received' (duration: 113.913052ms)","trace[1361905920] 'applied index is now lower than readState.Index' (duration: 2.508542ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:17:53.667832Z","caller":"traceutil/trace.go:171","msg":"trace[1376395047] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"229.246388ms","start":"2026-10-15T11:17:53.438535Z","end":"2026-10-15T11:17:53.667782Z","steps":["trace[1376395047] 'process raft request' (duration: 227.194416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:53.667989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.750702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-59tfq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-15T11:17:53.668029Z","caller":"traceutil/trace.go:171","msg":"trace[734057461] range","detail":"{range_begin:/registry/pods/kube-system/cilium-59tfq; range_end:; response_count:1; response_revision:616; }","duration":"116.858636ms","start":"2026-10-15T11:17:53.551158Z","end":"2026-10-15T11:17:53.668017Z","steps":["trace[734057461] 'agreement among raft nodes before linearized reading' (duration: 116.62333ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:17:53.87599Z","caller":"traceutil/trace.go:171","msg":"trace[1059187849] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"181.626806ms","start":"2026-10-15T11:17:53.694336Z","end":"2026-10-15T11:17:53.875963Z","steps":["trace[1059187849] 'process raft request' (duration: 181.37256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:54.51932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.105676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:17:54.51951Z","caller":"traceutil/trace.go:171","msg":"trace[1353643071] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"481.009279ms","start":"2026-10-15T11:17:54.038484Z","end":"2026-10-15T11:17:54.519493Z","steps":["trace[1353643071] 'read index received' (duration: 37.481µs)","trace[1353643071] 'applied index is now lower than readState.Index' (duration: 480.970898ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:17:54.519543Z","caller":"traceutil/trace.go:171","msg":"trace[627534021] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"822.494506ms","start":"2026-10-15T11:17:53.697001Z","end":"2026-10-15T11:17:54.519495Z","steps":["trace[627534021] 'process raft request' (duration: 234.596782ms)","trace[627534021] 'compare' (duration: 586.797247ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:17:54.51972Z","caller":"traceutil/trace.go:171","msg":"trace[1391709200] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"774.935556ms","start":"2026-10-15T11:17:53.74477Z","end":"2026-10-15T11:17:54.519705Z","steps":["trace[1391709200] 'process raft request' (duration: 774.64427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:54.519726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:17:53.696989Z","time spent":"822.637209ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:17:54.519816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:17:53.74473Z","time spent":"775.053309ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T11:17:54.520053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.558823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:17:54.520154Z","caller":"traceutil/trace.go:171","msg":"trace[1449380520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"481.691876ms","start":"2026-10-15T11:17:54.03844Z","end":"2026-10-15T11:17:54.520132Z","steps":["trace[1449380520] 'agreement among raft nodes before linearized reading' (duration: 481.516482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:54.520149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.130249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-15T11:17:54.5202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:17:54.03842Z","time spent":"481.768728ms","remote":"127.0.0.1:60782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T11:17:54.520225Z","caller":"traceutil/trace.go:171","msg":"trace[114166382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"371.256312ms","start":"2026-10-15T11:17:54.148949Z","end":"2026-10-15T11:17:54.520205Z","steps":["trace[114166382] 'agreement among raft nodes before linearized reading' (duration: 371.002696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:17:54.520267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:17:54.148921Z","time spent":"371.337943ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T11:18:09.838791Z","caller":"traceutil/trace.go:171","msg":"trace[1589754219] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"347.684912ms","start":"2026-10-15T11:18:09.491062Z","end":"2026-10-15T11:18:09.838747Z","steps":["trace[1589754219] 'process raft request' (duration: 347.550908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:09.838975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:18:09.491045Z","time spent":"347.842705ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:18:09.839107Z","caller":"traceutil/trace.go:171","msg":"trace[1363363352] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"185.42785ms","start":"2026-10-15T11:18:09.65366Z","end":"2026-10-15T11:18:09.839088Z","steps":["trace[1363363352] 'read index received' (duration: 184.878707ms)","trace[1363363352] 'applied index is now lower than readState.Index' (duration: 547.653µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:18:09.839293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.730389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:18:09.839373Z","caller":"traceutil/trace.go:171","msg":"trace[1400643395] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:787; }","duration":"181.829872ms","start":"2026-10-15T11:18:09.657505Z","end":"2026-10-15T11:18:09.839335Z","steps":["trace[1400643395] 'agreement among raft nodes before linearized reading' (duration: 181.716599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:09.839424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.672857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nx2fh\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-10-15T11:18:09.839512Z","caller":"traceutil/trace.go:171","msg":"trace[2021867269] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nx2fh; range_end:; response_count:1; response_revision:787; }","duration":"185.878242ms","start":"2026-10-15T11:18:09.653614Z","end":"2026-10-15T11:18:09.839492Z","steps":["trace[2021867269] 'agreement among raft nodes before linearized reading' (duration: 185.593284ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:09.991939Z","caller":"traceutil/trace.go:171","msg":"trace[897497859] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"109.574033ms","start":"2026-10-15T11:18:09.882339Z","end":"2026-10-15T11:18:09.991913Z","steps":["trace[897497859] 'read index received' (duration: 109.343517ms)","trace[897497859] 'applied index is now lower than readState.Index' (duration: 229.506µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:18:09.992134Z","caller":"traceutil/trace.go:171","msg":"trace[543772121] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"144.773334ms","start":"2026-10-15T11:18:09.84734Z","end":"2026-10-15T11:18:09.992114Z","steps":["trace[543772121] 'process raft request' (duration: 144.399155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:18:09.992412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.038245ms","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-15T11:18:09.992515Z","caller":"traceutil/trace.go:171","msg":"trace[1778912672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"110.167769ms","start":"2026-10-15T11:18:09.882306Z","end":"2026-10-15T11:18:09.992474Z","steps":["trace[1778912672] 'agreement among raft nodes before linearized reading' (duration: 109.793249ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:18:40.375207Z","caller":"traceutil/trace.go:171","msg":"trace[1526528686] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"153.864391ms","start":"2026-10-15T11:18:40.22131Z","end":"2026-10-15T11:18:40.375174Z","steps":["trace[1526528686] 'process raft request' (duration: 153.687636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:13.928546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.244281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:19:13.928675Z","caller":"traceutil/trace.go:171","msg":"trace[1546352355] linearizableReadLoop","detail":"{readStateIndex:1749; appliedIndex:1748; }","duration":"202.239ms","start":"2026-10-15T11:19:13.726415Z","end":"2026-10-15T11:19:13.928654Z","steps":["trace[1546352355] 'read index received' (duration: 18.69497ms)","trace[1546352355] 'applied index is now lower than readState.Index' (duration: 183.54252ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:19:13.928711Z","caller":"traceutil/trace.go:171","msg":"trace[729421365] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"236.291709ms","start":"2026-10-15T11:19:13.692391Z","end":"2026-10-15T11:19:13.928683Z","steps":["trace[729421365] 'process raft request' (duration: 52.771171ms)","trace[729421365] 'compare' (duration: 183.107468ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:19:13.928796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.378543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:19:13.92888Z","caller":"traceutil/trace.go:171","msg":"trace[1433504662] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:0; response_revision:1682; }","duration":"202.459085ms","start":"2026-10-15T11:19:13.726403Z","end":"2026-10-15T11:19:13.928862Z","steps":["trace[1433504662] 'agreement among raft nodes before linearized reading' (duration: 202.350693ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:28.001417Z","caller":"traceutil/trace.go:171","msg":"trace[1138516247] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"402.200393ms","start":"2026-10-15T11:19:27.599186Z","end":"2026-10-15T11:19:28.001386Z","steps":["trace[1138516247] 'read index received' (duration: 402.101901ms)","trace[1138516247] 'applied index is now lower than readState.Index' (duration: 97.232µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:19:28.001655Z","caller":"traceutil/trace.go:171","msg":"trace[1781592089] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"438.838607ms","start":"2026-10-15T11:19:27.562777Z","end":"2026-10-15T11:19:28.001616Z","steps":["trace[1781592089] 'process raft request' (duration: 438.437877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:28.001674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.47263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T11:19:28.001725Z","caller":"traceutil/trace.go:171","msg":"trace[1879082223] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1874; }","duration":"402.570543ms","start":"2026-10-15T11:19:27.599139Z","end":"2026-10-15T11:19:28.001709Z","steps":["trace[1879082223] 'agreement among raft nodes before linearized reading' (duration: 402.390938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:28.001775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:27.599123Z","time spent":"402.639294ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T11:19:28.001791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:19:27.562752Z","time spent":"438.961811ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:19:28.010967Z","caller":"traceutil/trace.go:171","msg":"trace[1375547015] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"155.351617ms","start":"2026-10-15T11:19:27.855586Z","end":"2026-10-15T11:19:28.010937Z","steps":["trace[1375547015] 'process raft request' (duration: 155.140311ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:41.244805Z","caller":"traceutil/trace.go:171","msg":"trace[1565991569] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"113.858041ms","start":"2026-10-15T11:19:41.130925Z","end":"2026-10-15T11:19:41.244783Z","steps":["trace[1565991569] 'process raft request' (duration: 113.727908ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:41.245192Z","caller":"traceutil/trace.go:171","msg":"trace[788818514] linearizableReadLoop","detail":"{readStateIndex:2061; appliedIndex:2061; }","duration":"111.262965ms","start":"2026-10-15T11:19:41.133874Z","end":"2026-10-15T11:19:41.245137Z","steps":["trace[788818514] 'read index received' (duration: 111.249845ms)","trace[788818514] 'applied index is now lower than readState.Index' (duration: 10.61µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:19:41.245387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.497921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T11:19:41.245435Z","caller":"traceutil/trace.go:171","msg":"trace[1818164393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1984; }","duration":"111.588634ms","start":"2026-10-15T11:19:41.133836Z","end":"2026-10-15T11:19:41.245425Z","steps":["trace[1818164393] 'agreement among raft nodes before linearized reading' (duration: 111.43908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:41.261057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.159432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11978"} {"level":"info","ts":"2026-10-15T11:19:41.261126Z","caller":"traceutil/trace.go:171","msg":"trace[125788239] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1985; }","duration":"107.328136ms","start":"2026-10-15T11:19:41.153785Z","end":"2026-10-15T11:19:41.261113Z","steps":["trace[125788239] 'agreement among raft nodes before linearized reading' (duration: 107.122551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:19:41.388007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.281818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:19:41.388132Z","caller":"traceutil/trace.go:171","msg":"trace[1390109134] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"114.939679ms","start":"2026-10-15T11:19:41.273172Z","end":"2026-10-15T11:19:41.388111Z","steps":["trace[1390109134] 'read index received' (duration: 53.912µs)","trace[1390109134] 'applied index is now lower than readState.Index' (duration: 114.884037ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:19:41.388212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.044761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:19:41.388246Z","caller":"traceutil/trace.go:171","msg":"trace[1790453438] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1986; }","duration":"115.118853ms","start":"2026-10-15T11:19:41.273114Z","end":"2026-10-15T11:19:41.388233Z","steps":["trace[1790453438] 'agreement among raft nodes before linearized reading' (duration: 115.051441ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:19:41.388232Z","caller":"traceutil/trace.go:171","msg":"trace[1386534596] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"138.067612ms","start":"2026-10-15T11:19:41.250134Z","end":"2026-10-15T11:19:41.388201Z","steps":["trace[1386534596] 'process raft request' (duration: 20.457706ms)","trace[1386534596] 'compare' (duration: 116.747444ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:19:44.200587Z","caller":"traceutil/trace.go:171","msg":"trace[91622188] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"117.513903ms","start":"2026-10-15T11:19:44.082992Z","end":"2026-10-15T11:19:44.200506Z","steps":["trace[91622188] 'process raft request' (duration: 117.056413ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:20:24.491782Z","caller":"traceutil/trace.go:171","msg":"trace[1427293782] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"103.002234ms","start":"2026-10-15T11:20:24.388726Z","end":"2026-10-15T11:20:24.491729Z","steps":["trace[1427293782] 'process raft request' (duration: 102.582194ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:20:24.857838Z","caller":"traceutil/trace.go:171","msg":"trace[687743103] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"361.14371ms","start":"2026-10-15T11:20:24.496622Z","end":"2026-10-15T11:20:24.857766Z","steps":["trace[687743103] 'process raft request' (duration: 360.966515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:20:24.858023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:20:24.4966Z","time spent":"361.330164ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:20:24.859184Z","caller":"traceutil/trace.go:171","msg":"trace[628426165] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"361.928829ms","start":"2026-10-15T11:20:24.497216Z","end":"2026-10-15T11:20:24.859145Z","steps":["trace[628426165] 'process raft request' (duration: 361.695063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:20:24.859375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:20:24.497192Z","time spent":"362.072152ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:21:07.901095Z","caller":"traceutil/trace.go:171","msg":"trace[1089959873] linearizableReadLoop","detail":"{readStateIndex:2738; appliedIndex:2737; }","duration":"153.985358ms","start":"2026-10-15T11:21:07.74708Z","end":"2026-10-15T11:21:07.901065Z","steps":["trace[1089959873] 'read index received' (duration: 153.779923ms)","trace[1089959873] 'applied index is now lower than readState.Index' (duration: 204.485µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:21:07.901235Z","caller":"traceutil/trace.go:171","msg":"trace[960152143] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"277.818589ms","start":"2026-10-15T11:21:07.623401Z","end":"2026-10-15T11:21:07.90122Z","steps":["trace[960152143] 'process raft request' (duration: 277.496941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:21:07.901453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.368116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T11:21:07.901488Z","caller":"traceutil/trace.go:171","msg":"trace[1363861334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2635; }","duration":"154.445158ms","start":"2026-10-15T11:21:07.747031Z","end":"2026-10-15T11:21:07.901476Z","steps":["trace[1363861334] 'agreement among raft nodes before linearized reading' (duration: 154.323956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:21:07.901628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.685001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:21:07.901719Z","caller":"traceutil/trace.go:171","msg":"trace[770607392] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2635; }","duration":"101.859405ms","start":"2026-10-15T11:21:07.799837Z","end":"2026-10-15T11:21:07.901696Z","steps":["trace[770607392] 'agreement among raft nodes before linearized reading' (duration: 101.687861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:21:41.085201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.556612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:21:41.085281Z","caller":"traceutil/trace.go:171","msg":"trace[807958271] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2834; }","duration":"162.693524ms","start":"2026-10-15T11:21:40.922571Z","end":"2026-10-15T11:21:41.085264Z","steps":["trace[807958271] 'count revisions from in-memory index tree' (duration: 162.481589ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:21:41.368084Z","caller":"traceutil/trace.go:171","msg":"trace[1850419040] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"125.462641ms","start":"2026-10-15T11:21:41.242595Z","end":"2026-10-15T11:21:41.368058Z","steps":["trace[1850419040] 'process raft request' (duration: 125.408639ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:21:41.368228Z","caller":"traceutil/trace.go:171","msg":"trace[748685877] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"126.027155ms","start":"2026-10-15T11:21:41.242162Z","end":"2026-10-15T11:21:41.368189Z","steps":["trace[748685877] 'process raft request' (duration: 125.664976ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:21:41.549033Z","caller":"traceutil/trace.go:171","msg":"trace[1579759075] linearizableReadLoop","detail":"{readStateIndex:2949; appliedIndex:2946; }","duration":"181.832089ms","start":"2026-10-15T11:21:41.36716Z","end":"2026-10-15T11:21:41.548992Z","steps":["trace[1579759075] 'read index received' (duration: 666.956µs)","trace[1579759075] 'applied index is now lower than readState.Index' (duration: 181.163023ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:21:41.549086Z","caller":"traceutil/trace.go:171","msg":"trace[436828888] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"227.748538ms","start":"2026-10-15T11:21:41.321304Z","end":"2026-10-15T11:21:41.549053Z","steps":["trace[436828888] 'process raft request' (duration: 210.92609ms)","trace[436828888] 'compare' (duration: 16.491839ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:21:41.54934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.149676ms","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-15T11:21:41.549405Z","caller":"traceutil/trace.go:171","msg":"trace[842509585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2838; }","duration":"182.29277ms","start":"2026-10-15T11:21:41.367092Z","end":"2026-10-15T11:21:41.549385Z","steps":["trace[842509585] 'agreement among raft nodes before linearized reading' (duration: 182.034194ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:26.456159Z","caller":"traceutil/trace.go:171","msg":"trace[971274794] transaction","detail":"{read_only:false; response_revision:3842; number_of_response:1; }","duration":"124.168517ms","start":"2026-10-15T11:24:26.331958Z","end":"2026-10-15T11:24:26.456127Z","steps":["trace[971274794] 'process raft request' (duration: 119.078841ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:26.456381Z","caller":"traceutil/trace.go:171","msg":"trace[1438581445] transaction","detail":"{read_only:false; response_revision:3843; number_of_response:1; }","duration":"121.990093ms","start":"2026-10-15T11:24:26.334373Z","end":"2026-10-15T11:24:26.456363Z","steps":["trace[1438581445] 'process raft request' (duration: 121.911301ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:24:59.032765Z","caller":"traceutil/trace.go:171","msg":"trace[1527273885] linearizableReadLoop","detail":"{readStateIndex:4236; appliedIndex:4235; }","duration":"107.554666ms","start":"2026-10-15T11:24:58.92518Z","end":"2026-10-15T11:24:59.032735Z","steps":["trace[1527273885] 'read index received' (duration: 107.407332ms)","trace[1527273885] 'applied index is now lower than readState.Index' (duration: 145.744µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:24:59.032851Z","caller":"traceutil/trace.go:171","msg":"trace[546313389] transaction","detail":"{read_only:false; response_revision:4079; number_of_response:1; }","duration":"125.055078ms","start":"2026-10-15T11:24:58.907746Z","end":"2026-10-15T11:24:59.032801Z","steps":["trace[546313389] 'process raft request' (duration: 124.812362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:24:59.03297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.765972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:24:59.033011Z","caller":"traceutil/trace.go:171","msg":"trace[1163216330] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:4079; }","duration":"107.834053ms","start":"2026-10-15T11:24:58.925166Z","end":"2026-10-15T11:24:59.033Z","steps":["trace[1163216330] 'agreement among raft nodes before linearized reading' (duration: 107.68952ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:26:33.503447Z","caller":"traceutil/trace.go:171","msg":"trace[503564029] linearizableReadLoop","detail":"{readStateIndex:5507; appliedIndex:5506; }","duration":"265.908116ms","start":"2026-10-15T11:26:33.237504Z","end":"2026-10-15T11:26:33.503412Z","steps":["trace[503564029] 'read index received' (duration: 265.724792ms)","trace[503564029] 'applied index is now lower than readState.Index' (duration: 181.954µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:26:33.503475Z","caller":"traceutil/trace.go:171","msg":"trace[1724536159] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"267.212658ms","start":"2026-10-15T11:26:33.236233Z","end":"2026-10-15T11:26:33.503445Z","steps":["trace[1724536159] 'process raft request' (duration: 266.974473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:33.503588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.065961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9a3de64-01ca-4416-9d63-e746850c7dbb\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-10-15T11:26:33.503625Z","caller":"traceutil/trace.go:171","msg":"trace[1836761805] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9a3de64-01ca-4416-9d63-e746850c7dbb; range_end:; response_count:1; response_revision:5304; }","duration":"266.148613ms","start":"2026-10-15T11:26:33.237462Z","end":"2026-10-15T11:26:33.50361Z","steps":["trace[1836761805] 'agreement among raft nodes before linearized reading' (duration: 266.057371ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:26:33.62814Z","caller":"traceutil/trace.go:171","msg":"trace[978372413] linearizableReadLoop","detail":"{readStateIndex:5508; appliedIndex:5507; }","duration":"124.566967ms","start":"2026-10-15T11:26:33.503541Z","end":"2026-10-15T11:26:33.628108Z","steps":["trace[978372413] 'read index received' (duration: 81.154048ms)","trace[978372413] 'applied index is now lower than readState.Index' (duration: 43.411589ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:26:33.628296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.992843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-4q9gp-tls\" ","response":"range_response_count:1 size:1966"} {"level":"warn","ts":"2026-10-15T11:26:33.628322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.508097ms","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-15T11:26:33.628358Z","caller":"traceutil/trace.go:171","msg":"trace[2116134163] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-4q9gp-tls; range_end:; response_count:1; response_revision:5305; }","duration":"150.084145ms","start":"2026-10-15T11:26:33.478258Z","end":"2026-10-15T11:26:33.628342Z","steps":["trace[2116134163] 'agreement among raft nodes before linearized reading' (duration: 149.86155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:33.628357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.082916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:26:33.628359Z","caller":"traceutil/trace.go:171","msg":"trace[1138124489] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"196.511288ms","start":"2026-10-15T11:26:33.431827Z","end":"2026-10-15T11:26:33.628338Z","steps":["trace[1138124489] 'process raft request' (duration: 152.930384ms)","trace[1138124489] 'compare' (duration: 43.206175ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:26:33.628377Z","caller":"traceutil/trace.go:171","msg":"trace[372064873] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5305; }","duration":"276.59609ms","start":"2026-10-15T11:26:33.351768Z","end":"2026-10-15T11:26:33.628364Z","steps":["trace[372064873] 'agreement among raft nodes before linearized reading' (duration: 276.386264ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:26:33.628395Z","caller":"traceutil/trace.go:171","msg":"trace[942899774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5305; }","duration":"120.164618ms","start":"2026-10-15T11:26:33.508218Z","end":"2026-10-15T11:26:33.628383Z","steps":["trace[942899774] 'agreement among raft nodes before linearized reading' (duration: 120.096466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:33.628435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.832077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T11:26:33.62845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.405484ms","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-15T11:26:33.628483Z","caller":"traceutil/trace.go:171","msg":"trace[213387890] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:5305; }","duration":"378.887488ms","start":"2026-10-15T11:26:33.24958Z","end":"2026-10-15T11:26:33.628468Z","steps":["trace[213387890] 'agreement among raft nodes before linearized reading' (duration: 378.803466ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:26:33.628544Z","caller":"traceutil/trace.go:171","msg":"trace[996351934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5305; }","duration":"304.546127ms","start":"2026-10-15T11:26:33.32398Z","end":"2026-10-15T11:26:33.628526Z","steps":["trace[996351934] 'agreement among raft nodes before linearized reading' (duration: 304.309551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:33.628561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:26:33.24956Z","time spent":"378.9903ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-15T11:26:33.628596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:26:33.323965Z","time spent":"304.617509ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T11:26:46.188024Z","caller":"traceutil/trace.go:171","msg":"trace[1578423294] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"300.55197ms","start":"2026-10-15T11:26:45.887445Z","end":"2026-10-15T11:26:46.187997Z","steps":["trace[1578423294] 'process raft request' (duration: 295.69025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:46.188179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:26:45.887421Z","time spent":"300.683182ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:26:46.189285Z","caller":"traceutil/trace.go:171","msg":"trace[1494193735] linearizableReadLoop","detail":"{readStateIndex:5748; appliedIndex:5746; }","duration":"298.490688ms","start":"2026-10-15T11:26:45.890751Z","end":"2026-10-15T11:26:46.189241Z","steps":["trace[1494193735] 'read index received' (duration: 292.403298ms)","trace[1494193735] 'applied index is now lower than readState.Index' (duration: 6.08545ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:26:46.189371Z","caller":"traceutil/trace.go:171","msg":"trace[2016329060] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"301.83242ms","start":"2026-10-15T11:26:45.887499Z","end":"2026-10-15T11:26:46.189332Z","steps":["trace[2016329060] 'process raft request' (duration: 300.387235ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:26:46.189463Z","caller":"traceutil/trace.go:171","msg":"trace[785527382] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"171.752259ms","start":"2026-10-15T11:26:46.017669Z","end":"2026-10-15T11:26:46.189421Z","steps":["trace[785527382] 'process raft request' (duration: 171.428501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:46.189593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:26:45.88747Z","time spent":"301.999295ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:26:46.189601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.044493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:26:46.189684Z","caller":"traceutil/trace.go:171","msg":"trace[1519214547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5538; }","duration":"152.143786ms","start":"2026-10-15T11:26:46.037522Z","end":"2026-10-15T11:26:46.189666Z","steps":["trace[1519214547] 'agreement among raft nodes before linearized reading' (duration: 152.004372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:26:46.189743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.99483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70707"} {"level":"info","ts":"2026-10-15T11:26:46.189789Z","caller":"traceutil/trace.go:171","msg":"trace[1295228368] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5538; }","duration":"299.069843ms","start":"2026-10-15T11:26:45.890709Z","end":"2026-10-15T11:26:46.189779Z","steps":["trace[1295228368] 'agreement among raft nodes before linearized reading' (duration: 298.881508ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:01.73124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2951} {"level":"warn","ts":"2026-10-15T11:27:01.941355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.104469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:27:01.941441Z","caller":"traceutil/trace.go:171","msg":"trace[1623444819] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5830; }","duration":"192.237662ms","start":"2026-10-15T11:27:01.749183Z","end":"2026-10-15T11:27:01.94142Z","steps":["trace[1623444819] 'range keys from in-memory index tree' (duration: 192.025527ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:01.999683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2951,"took":"267.390023ms","hash":486255100,"current-db-size-bytes":55771136,"current-db-size":"56 MB","current-db-size-in-use-bytes":31932416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T11:27:01.999744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":486255100,"revision":2951,"compact-revision":-1} {"level":"warn","ts":"2026-10-15T11:27:05.868663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.168313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-10-15T11:27:05.868643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.106985ms","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-15T11:27:05.868731Z","caller":"traceutil/trace.go:171","msg":"trace[305197295] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5861; }","duration":"264.280096ms","start":"2026-10-15T11:27:05.604434Z","end":"2026-10-15T11:27:05.868714Z","steps":["trace[305197295] 'range keys from in-memory index tree' (duration: 263.974409ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:05.868757Z","caller":"traceutil/trace.go:171","msg":"trace[1425659595] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5861; }","duration":"178.305771ms","start":"2026-10-15T11:27:05.690428Z","end":"2026-10-15T11:27:05.868734Z","steps":["trace[1425659595] 'range keys from in-memory index tree' (duration: 177.805948ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:06.839332Z","caller":"traceutil/trace.go:171","msg":"trace[1913632752] linearizableReadLoop","detail":"{readStateIndex:6095; appliedIndex:6094; }","duration":"183.748623ms","start":"2026-10-15T11:27:06.655564Z","end":"2026-10-15T11:27:06.839313Z","steps":["trace[1913632752] 'read index received' (duration: 183.444615ms)","trace[1913632752] 'applied index is now lower than readState.Index' (duration: 303.368µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:27:06.83944Z","caller":"traceutil/trace.go:171","msg":"trace[563467705] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"191.394742ms","start":"2026-10-15T11:27:06.648015Z","end":"2026-10-15T11:27:06.83941Z","steps":["trace[563467705] 'process raft request' (duration: 191.135946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:06.839478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.868856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:27:06.839544Z","caller":"traceutil/trace.go:171","msg":"trace[469499620] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5876; }","duration":"183.99464ms","start":"2026-10-15T11:27:06.655509Z","end":"2026-10-15T11:27:06.839504Z","steps":["trace[469499620] 'agreement among raft nodes before linearized reading' (duration: 183.886277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:27:06.842303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.621171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-15T11:27:06.842374Z","caller":"traceutil/trace.go:171","msg":"trace[1288782421] 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:5877; }","duration":"157.747354ms","start":"2026-10-15T11:27:06.684607Z","end":"2026-10-15T11:27:06.842355Z","steps":["trace[1288782421] 'agreement among raft nodes before linearized reading' (duration: 157.537239ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:27:47.868008Z","caller":"traceutil/trace.go:171","msg":"trace[1045317092] transaction","detail":"{read_only:false; response_revision:6231; number_of_response:1; }","duration":"166.759415ms","start":"2026-10-15T11:27:47.701202Z","end":"2026-10-15T11:27:47.867962Z","steps":["trace[1045317092] 'process raft request' (duration: 166.52414ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:32:01.738141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5830} {"level":"info","ts":"2026-10-15T11:32:01.837269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5830,"took":"97.371515ms","hash":4098016123,"current-db-size-bytes":55771136,"current-db-size":"56 MB","current-db-size-in-use-bytes":26779648,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T11:32:01.837344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4098016123,"revision":5830,"compact-revision":2951} {"level":"warn","ts":"2026-10-15T11:33:18.504084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.232311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:33:18.504228Z","caller":"traceutil/trace.go:171","msg":"trace[1226997826] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"139.585543ms","start":"2026-10-15T11:33:18.364616Z","end":"2026-10-15T11:33:18.504201Z","steps":["trace[1226997826] 'process raft request' (duration: 20.124926ms)","trace[1226997826] 'compare' (duration: 119.060547ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:33:19.307157Z","caller":"traceutil/trace.go:171","msg":"trace[550334958] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"168.282868ms","start":"2026-10-15T11:33:19.138832Z","end":"2026-10-15T11:33:19.307114Z","steps":["trace[550334958] 'process raft request' (duration: 168.133724ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:19.331753Z","caller":"traceutil/trace.go:171","msg":"trace[2089900151] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8952; }","duration":"187.695946ms","start":"2026-10-15T11:33:19.144024Z","end":"2026-10-15T11:33:19.33172Z","steps":["trace[2089900151] 'read index received' (duration: 163.131191ms)","trace[2089900151] 'applied index is now lower than readState.Index' (duration: 24.563405ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:33:19.332071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.007213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-15T11:33:19.33212Z","caller":"traceutil/trace.go:171","msg":"trace[594885353] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8645; }","duration":"188.107045ms","start":"2026-10-15T11:33:19.144001Z","end":"2026-10-15T11:33:19.332108Z","steps":["trace[594885353] 'agreement among raft nodes before linearized reading' (duration: 187.84163ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:19.332508Z","caller":"traceutil/trace.go:171","msg":"trace[805558632] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"191.019876ms","start":"2026-10-15T11:33:19.141455Z","end":"2026-10-15T11:33:19.332475Z","steps":["trace[805558632] 'process raft request' (duration: 190.089144ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:20.069376Z","caller":"traceutil/trace.go:171","msg":"trace[2023564718] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"203.569886ms","start":"2026-10-15T11:33:19.865774Z","end":"2026-10-15T11:33:20.069344Z","steps":["trace[2023564718] 'process raft request' (duration: 203.194016ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:20.346093Z","caller":"traceutil/trace.go:171","msg":"trace[254623093] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"118.960656ms","start":"2026-10-15T11:33:20.227092Z","end":"2026-10-15T11:33:20.346053Z","steps":["trace[254623093] 'process raft request' (duration: 99.515838ms)","trace[254623093] 'compare' (duration: 19.216182ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:33:20.358894Z","caller":"traceutil/trace.go:171","msg":"trace[188631134] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"130.736916ms","start":"2026-10-15T11:33:20.228115Z","end":"2026-10-15T11:33:20.358852Z","steps":["trace[188631134] 'process raft request' (duration: 130.536171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:20.779109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.933979ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-15T11:33:20.779229Z","caller":"traceutil/trace.go:171","msg":"trace[1089079597] linearizableReadLoop","detail":"{readStateIndex:8962; appliedIndex:8961; }","duration":"164.015234ms","start":"2026-10-15T11:33:20.615195Z","end":"2026-10-15T11:33:20.77921Z","steps":["trace[1089079597] 'read index received' (duration: 18.008633ms)","trace[1089079597] 'applied index is now lower than readState.Index' (duration: 146.005011ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:33:20.779763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.562707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T11:33:20.779814Z","caller":"traceutil/trace.go:171","msg":"trace[1147401887] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8653; }","duration":"164.67276ms","start":"2026-10-15T11:33:20.61513Z","end":"2026-10-15T11:33:20.779803Z","steps":["trace[1147401887] 'agreement among raft nodes before linearized reading' (duration: 164.573727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:20.77986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.109382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:33:20.780007Z","caller":"traceutil/trace.go:171","msg":"trace[46843798] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8653; }","duration":"126.255765ms","start":"2026-10-15T11:33:20.653724Z","end":"2026-10-15T11:33:20.77998Z","steps":["trace[46843798] 'agreement among raft nodes before linearized reading' (duration: 126.068901ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:45.699446Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c0.wal"} {"level":"info","ts":"2026-10-15T11:33:46.621855Z","caller":"traceutil/trace.go:171","msg":"trace[1392654986] linearizableReadLoop","detail":"{readStateIndex:9161; appliedIndex:9160; }","duration":"121.714343ms","start":"2026-10-15T11:33:46.500114Z","end":"2026-10-15T11:33:46.621828Z","steps":["trace[1392654986] 'read index received' (duration: 121.59028ms)","trace[1392654986] 'applied index is now lower than readState.Index' (duration: 122.843µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:33:46.62198Z","caller":"traceutil/trace.go:171","msg":"trace[1511585614] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"123.846736ms","start":"2026-10-15T11:33:46.498095Z","end":"2026-10-15T11:33:46.621942Z","steps":["trace[1511585614] 'process raft request' (duration: 123.561359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:46.622097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.96062ms","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-15T11:33:46.622135Z","caller":"traceutil/trace.go:171","msg":"trace[584129196] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8845; }","duration":"122.012541ms","start":"2026-10-15T11:33:46.50011Z","end":"2026-10-15T11:33:46.622122Z","steps":["trace[584129196] 'agreement among raft nodes before linearized reading' (duration: 121.881018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:46.62236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.711391ms","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-15T11:33:46.622477Z","caller":"traceutil/trace.go:171","msg":"trace[1104545239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8845; }","duration":"114.822774ms","start":"2026-10-15T11:33:46.507595Z","end":"2026-10-15T11:33:46.622418Z","steps":["trace[1104545239] 'agreement among raft nodes before linearized reading' (duration: 114.454876ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:49.873513Z","caller":"traceutil/trace.go:171","msg":"trace[560180834] linearizableReadLoop","detail":"{readStateIndex:9176; appliedIndex:9175; }","duration":"395.857205ms","start":"2026-10-15T11:33:49.477636Z","end":"2026-10-15T11:33:49.873494Z","steps":["trace[560180834] 'read index received' (duration: 395.832474ms)","trace[560180834] 'applied index is now lower than readState.Index' (duration: 23.931µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:33:49.873618Z","caller":"traceutil/trace.go:171","msg":"trace[980436119] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"485.520041ms","start":"2026-10-15T11:33:49.388068Z","end":"2026-10-15T11:33:49.873588Z","steps":["trace[980436119] 'process raft request' (duration: 485.321476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:49.873669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.013709ms","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-15T11:33:49.873694Z","caller":"traceutil/trace.go:171","msg":"trace[691370484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8860; }","duration":"396.07363ms","start":"2026-10-15T11:33:49.477612Z","end":"2026-10-15T11:33:49.873685Z","steps":["trace[691370484] 'agreement among raft nodes before linearized reading' (duration: 395.969927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:49.87373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:33:49.477602Z","time spent":"396.114372ms","remote":"127.0.0.1:32810","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":"warn","ts":"2026-10-15T11:33:49.874169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:33:49.388053Z","time spent":"485.633053ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:33:49.876147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.131938ms","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-15T11:33:49.876208Z","caller":"traceutil/trace.go:171","msg":"trace[134572751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8861; }","duration":"393.207049ms","start":"2026-10-15T11:33:49.482984Z","end":"2026-10-15T11:33:49.876191Z","steps":["trace[134572751] 'agreement among raft nodes before linearized reading' (duration: 393.032865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:49.876239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:33:49.482975Z","time spent":"393.25657ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T11:33:49.876341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.215634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:33:49.876374Z","caller":"traceutil/trace.go:171","msg":"trace[387883799] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8861; }","duration":"115.284915ms","start":"2026-10-15T11:33:49.761079Z","end":"2026-10-15T11:33:49.876364Z","steps":["trace[387883799] 'agreement among raft nodes before linearized reading' (duration: 115.235264ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:52.044722Z","caller":"traceutil/trace.go:171","msg":"trace[1041506307] linearizableReadLoop","detail":"{readStateIndex:9189; appliedIndex:9187; }","duration":"149.622042ms","start":"2026-10-15T11:33:51.895075Z","end":"2026-10-15T11:33:52.044697Z","steps":["trace[1041506307] 'read index received' (duration: 115.809125ms)","trace[1041506307] 'applied index is now lower than readState.Index' (duration: 33.811807ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:33:52.045271Z","caller":"traceutil/trace.go:171","msg":"trace[1316721047] transaction","detail":"{read_only:false; response_revision:8871; number_of_response:1; }","duration":"165.138452ms","start":"2026-10-15T11:33:51.880098Z","end":"2026-10-15T11:33:52.045236Z","steps":["trace[1316721047] 'process raft request' (duration: 164.384305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:52.045571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.487643ms","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-15T11:33:52.04562Z","caller":"traceutil/trace.go:171","msg":"trace[1183393989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8871; }","duration":"150.575194ms","start":"2026-10-15T11:33:51.895033Z","end":"2026-10-15T11:33:52.045609Z","steps":["trace[1183393989] 'agreement among raft nodes before linearized reading' (duration: 150.349219ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:52.295358Z","caller":"traceutil/trace.go:171","msg":"trace[1886128270] linearizableReadLoop","detail":"{readStateIndex:9190; appliedIndex:9189; }","duration":"250.545692ms","start":"2026-10-15T11:33:52.044789Z","end":"2026-10-15T11:33:52.295335Z","steps":["trace[1886128270] 'read index received' (duration: 250.376868ms)","trace[1886128270] 'applied index is now lower than readState.Index' (duration: 167.634µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:33:52.295565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.248286ms","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-15T11:33:52.295661Z","caller":"traceutil/trace.go:171","msg":"trace[1064566356] transaction","detail":"{read_only:false; response_revision:8872; number_of_response:1; }","duration":"283.457248ms","start":"2026-10-15T11:33:52.012167Z","end":"2026-10-15T11:33:52.295625Z","steps":["trace[1064566356] 'process raft request' (duration: 282.999197ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:52.295706Z","caller":"traceutil/trace.go:171","msg":"trace[1329816688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8872; }","duration":"400.41752ms","start":"2026-10-15T11:33:51.895272Z","end":"2026-10-15T11:33:52.29569Z","steps":["trace[1329816688] 'agreement among raft nodes before linearized reading' (duration: 400.150354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:33:52.295762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:33:51.895261Z","time spent":"400.482972ms","remote":"127.0.0.1:32810","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-15T11:33:52.343467Z","caller":"traceutil/trace.go:171","msg":"trace[693052773] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"292.117734ms","start":"2026-10-15T11:33:52.051312Z","end":"2026-10-15T11:33:52.34343Z","steps":["trace[693052773] 'process raft request' (duration: 287.75837ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:52.343574Z","caller":"traceutil/trace.go:171","msg":"trace[1686533505] transaction","detail":"{read_only:false; response_revision:8874; number_of_response:1; }","duration":"131.599572ms","start":"2026-10-15T11:33:52.211949Z","end":"2026-10-15T11:33:52.343548Z","steps":["trace[1686533505] 'process raft request' (duration: 131.372846ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:33:59.19126Z","caller":"traceutil/trace.go:171","msg":"trace[567660284] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"133.862066ms","start":"2026-10-15T11:33:59.057376Z","end":"2026-10-15T11:33:59.191238Z","steps":["trace[567660284] 'process raft request' (duration: 133.321113ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:03.00097Z","caller":"traceutil/trace.go:171","msg":"trace[1637348196] transaction","detail":"{read_only:false; response_revision:8942; number_of_response:1; }","duration":"162.994942ms","start":"2026-10-15T11:34:02.837947Z","end":"2026-10-15T11:34:03.000942Z","steps":["trace[1637348196] 'process raft request' (duration: 162.769016ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:03.155363Z","caller":"traceutil/trace.go:171","msg":"trace[80549457] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"139.289475ms","start":"2026-10-15T11:34:03.016046Z","end":"2026-10-15T11:34:03.155335Z","steps":["trace[80549457] 'process raft request' (duration: 139.123001ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:03.177068Z","caller":"traceutil/trace.go:171","msg":"trace[1633882604] linearizableReadLoop","detail":"{readStateIndex:9264; appliedIndex:9262; }","duration":"137.439321ms","start":"2026-10-15T11:34:03.039597Z","end":"2026-10-15T11:34:03.177036Z","steps":["trace[1633882604] 'read index received' (duration: 115.562349ms)","trace[1633882604] 'applied index is now lower than readState.Index' (duration: 21.875842ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:34:03.177376Z","caller":"traceutil/trace.go:171","msg":"trace[2070273538] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8944; }","duration":"161.163287ms","start":"2026-10-15T11:34:03.016203Z","end":"2026-10-15T11:34:03.177366Z","steps":["trace[2070273538] 'process raft request' (duration: 160.568583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:34:03.177757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.150909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:34:03.1778Z","caller":"traceutil/trace.go:171","msg":"trace[562217298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8945; }","duration":"138.248071ms","start":"2026-10-15T11:34:03.039541Z","end":"2026-10-15T11:34:03.177789Z","steps":["trace[562217298] 'agreement among raft nodes before linearized reading' (duration: 138.169079ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:34:16.351357Z","caller":"traceutil/trace.go:171","msg":"trace[122954482] transaction","detail":"{read_only:false; response_revision:9078; number_of_response:1; }","duration":"117.245729ms","start":"2026-10-15T11:34:16.234086Z","end":"2026-10-15T11:34:16.351331Z","steps":["trace[122954482] 'process raft request' (duration: 117.103085ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:35:10.907157Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"65c3b6b1424650a7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T11:35:10.918545Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T11:35:10.918681Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T11:35:29.416087Z","caller":"traceutil/trace.go:171","msg":"trace[2070880061] transaction","detail":"{read_only:false; response_revision:9776; number_of_response:1; }","duration":"138.839809ms","start":"2026-10-15T11:35:29.277213Z","end":"2026-10-15T11:35:29.416053Z","steps":["trace[2070880061] 'process raft request' (duration: 84.018389ms)","trace[2070880061] 'compare' (duration: 54.714809ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:35:29.416147Z","caller":"traceutil/trace.go:171","msg":"trace[914339093] linearizableReadLoop","detail":"{readStateIndex:10122; appliedIndex:10121; }","duration":"107.91418ms","start":"2026-10-15T11:35:29.308207Z","end":"2026-10-15T11:35:29.416121Z","steps":["trace[914339093] 'read index received' (duration: 53.027948ms)","trace[914339093] 'applied index is now lower than readState.Index' (duration: 54.884132ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:35:29.416147Z","caller":"traceutil/trace.go:171","msg":"trace[1437768053] transaction","detail":"{read_only:false; response_revision:9777; number_of_response:1; }","duration":"103.920564ms","start":"2026-10-15T11:35:29.312196Z","end":"2026-10-15T11:35:29.416117Z","steps":["trace[1437768053] 'process raft request' (duration: 103.866372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:35:29.416324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.24013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T11:35:29.416369Z","caller":"traceutil/trace.go:171","msg":"trace[1870481946] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9777; }","duration":"160.325323ms","start":"2026-10-15T11:35:29.256031Z","end":"2026-10-15T11:35:29.416356Z","steps":["trace[1870481946] 'agreement among raft nodes before linearized reading' (duration: 160.154208ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:35:29.540888Z","caller":"traceutil/trace.go:171","msg":"trace[661162540] linearizableReadLoop","detail":"{readStateIndex:10124; appliedIndex:10123; }","duration":"109.676393ms","start":"2026-10-15T11:35:29.43118Z","end":"2026-10-15T11:35:29.540857Z","steps":["trace[661162540] 'read index received' (duration: 90.275319ms)","trace[661162540] 'applied index is now lower than readState.Index' (duration: 19.400044ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:35:29.540937Z","caller":"traceutil/trace.go:171","msg":"trace[1342179910] transaction","detail":"{read_only:false; response_revision:9778; number_of_response:1; }","duration":"120.167193ms","start":"2026-10-15T11:35:29.420739Z","end":"2026-10-15T11:35:29.540906Z","steps":["trace[1342179910] 'process raft request' (duration: 100.821701ms)","trace[1342179910] 'compare' (duration: 19.128367ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:35:29.541117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.910378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jp2ft\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T11:35:29.541158Z","caller":"traceutil/trace.go:171","msg":"trace[1771534697] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jp2ft; range_end:; response_count:1; response_revision:9778; }","duration":"110.000461ms","start":"2026-10-15T11:35:29.431146Z","end":"2026-10-15T11:35:29.541147Z","steps":["trace[1771534697] 'agreement among raft nodes before linearized reading' (duration: 109.820437ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:37:01.749988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8003} {"level":"info","ts":"2026-10-15T11:37:01.826686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8003,"took":"74.594823ms","hash":2905405986,"current-db-size-bytes":55771136,"current-db-size":"56 MB","current-db-size-in-use-bytes":28221440,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T11:37:01.826791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2905405986,"revision":8003,"compact-revision":5830} {"level":"info","ts":"2026-10-15T11:38:56.225454Z","caller":"traceutil/trace.go:171","msg":"trace[768404138] linearizableReadLoop","detail":"{readStateIndex:11922; appliedIndex:11921; }","duration":"113.463752ms","start":"2026-10-15T11:38:56.11196Z","end":"2026-10-15T11:38:56.225424Z","steps":["trace[768404138] 'read index received' (duration: 113.232867ms)","trace[768404138] 'applied index is now lower than readState.Index' (duration: 229.635µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:38:56.225515Z","caller":"traceutil/trace.go:171","msg":"trace[1357207519] transaction","detail":"{read_only:false; response_revision:11526; number_of_response:1; }","duration":"172.001642ms","start":"2026-10-15T11:38:56.05348Z","end":"2026-10-15T11:38:56.225482Z","steps":["trace[1357207519] 'process raft request' (duration: 171.790417ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:38:56.225599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.617606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:38:56.225686Z","caller":"traceutil/trace.go:171","msg":"trace[1839443903] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11526; }","duration":"113.824161ms","start":"2026-10-15T11:38:56.111842Z","end":"2026-10-15T11:38:56.225666Z","steps":["trace[1839443903] 'agreement among raft nodes before linearized reading' (duration: 113.676598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:38:56.225739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.674664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T11:38:56.22575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.595522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T11:38:56.225769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.89237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:38:56.225836Z","caller":"traceutil/trace.go:171","msg":"trace[189192727] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11526; }","duration":"112.863868ms","start":"2026-10-15T11:38:56.11295Z","end":"2026-10-15T11:38:56.225814Z","steps":["trace[189192727] 'agreement among raft nodes before linearized reading' (duration: 112.666493ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:38:56.22584Z","caller":"traceutil/trace.go:171","msg":"trace[213964644] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11526; }","duration":"112.796436ms","start":"2026-10-15T11:38:56.113026Z","end":"2026-10-15T11:38:56.225822Z","steps":["trace[213964644] 'agreement among raft nodes before linearized reading' (duration: 112.654253ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:38:56.225858Z","caller":"traceutil/trace.go:171","msg":"trace[647176888] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11526; }","duration":"111.035753ms","start":"2026-10-15T11:38:56.1148Z","end":"2026-10-15T11:38:56.225836Z","steps":["trace[647176888] 'agreement among raft nodes before linearized reading' (duration: 110.843248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:38:56.226008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.934226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-15T11:38:56.22612Z","caller":"traceutil/trace.go:171","msg":"trace[1310110384] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11526; }","duration":"103.085539ms","start":"2026-10-15T11:38:56.122965Z","end":"2026-10-15T11:38:56.22605Z","steps":["trace[1310110384] 'agreement among raft nodes before linearized reading' (duration: 102.668769ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:39:16.089544Z","caller":"traceutil/trace.go:171","msg":"trace[905116274] transaction","detail":"{read_only:false; response_revision:11636; number_of_response:1; }","duration":"177.481489ms","start":"2026-10-15T11:39:15.912037Z","end":"2026-10-15T11:39:16.089518Z","steps":["trace[905116274] 'process raft request' (duration: 177.329065ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:39:16.127135Z","caller":"traceutil/trace.go:171","msg":"trace[718205367] transaction","detail":"{read_only:false; response_revision:11637; number_of_response:1; }","duration":"175.037438ms","start":"2026-10-15T11:39:15.952059Z","end":"2026-10-15T11:39:16.127096Z","steps":["trace[718205367] 'process raft request' (duration: 174.800832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:39:16.316955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.793936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T11:39:16.317088Z","caller":"traceutil/trace.go:171","msg":"trace[1914226255] transaction","detail":"{read_only:false; response_revision:11639; number_of_response:1; }","duration":"184.201322ms","start":"2026-10-15T11:39:16.13287Z","end":"2026-10-15T11:39:16.317071Z","steps":["trace[1914226255] 'process raft request' (duration: 56.217181ms)","trace[1914226255] 'compare' (duration: 127.700685ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:42:01.758031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10425} {"level":"info","ts":"2026-10-15T11:42:01.844807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10425,"took":"84.652062ms","hash":1820717545,"current-db-size-bytes":55771136,"current-db-size":"56 MB","current-db-size-in-use-bytes":27697152,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T11:42:01.84488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1820717545,"revision":10425,"compact-revision":8003} {"level":"info","ts":"2026-10-15T11:45:02.711975Z","caller":"traceutil/trace.go:171","msg":"trace[70311347] transaction","detail":"{read_only:false; response_revision:14009; number_of_response:1; }","duration":"153.383703ms","start":"2026-10-15T11:45:02.558566Z","end":"2026-10-15T11:45:02.71195Z","steps":["trace[70311347] 'process raft request' (duration: 153.227129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:45:06.236772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.60022ms","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-15T11:45:06.236879Z","caller":"traceutil/trace.go:171","msg":"trace[1994224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14026; }","duration":"125.768444ms","start":"2026-10-15T11:45:06.111085Z","end":"2026-10-15T11:45:06.236854Z","steps":["trace[1994224] 'range keys from in-memory index tree' (duration: 125.380244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:45:06.236936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.143845ms","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-15T11:45:06.236919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.98757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jp2ft\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T11:45:06.236995Z","caller":"traceutil/trace.go:171","msg":"trace[980404018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14026; }","duration":"135.248397ms","start":"2026-10-15T11:45:06.101731Z","end":"2026-10-15T11:45:06.236979Z","steps":["trace[980404018] 'range keys from in-memory index tree' (duration: 134.705323ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:45:06.237018Z","caller":"traceutil/trace.go:171","msg":"trace[202026203] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jp2ft; range_end:; response_count:1; response_revision:14026; }","duration":"113.121733ms","start":"2026-10-15T11:45:06.123873Z","end":"2026-10-15T11:45:06.236995Z","steps":["trace[202026203] 'range keys from in-memory index tree' (duration: 112.689303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:45:26.720017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.943578ms","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-15T11:45:26.720118Z","caller":"traceutil/trace.go:171","msg":"trace[1982131868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14138; }","duration":"154.082819ms","start":"2026-10-15T11:45:26.566008Z","end":"2026-10-15T11:45:26.72009Z","steps":["trace[1982131868] 'range keys from in-memory index tree' (duration: 153.759383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:45:26.720524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.807342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:582411"} {"level":"info","ts":"2026-10-15T11:45:26.720618Z","caller":"traceutil/trace.go:171","msg":"trace[114331716] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:14138; }","duration":"296.938505ms","start":"2026-10-15T11:45:26.423646Z","end":"2026-10-15T11:45:26.720584Z","steps":["trace[114331716] 'range keys from in-memory index tree' (duration: 296.230168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:45:44.147101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.608072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T11:45:44.147184Z","caller":"traceutil/trace.go:171","msg":"trace[800070976] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:14242; }","duration":"296.743275ms","start":"2026-10-15T11:45:43.850424Z","end":"2026-10-15T11:45:44.147167Z","steps":["trace[800070976] 'count revisions from in-memory index tree' (duration: 296.52796ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:45:54.718851Z","caller":"traceutil/trace.go:171","msg":"trace[1342402358] transaction","detail":"{read_only:false; response_revision:14414; number_of_response:1; }","duration":"153.652107ms","start":"2026-10-15T11:45:54.565172Z","end":"2026-10-15T11:45:54.718824Z","steps":["trace[1342402358] 'process raft request' (duration: 153.504903ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:45:54.760688Z","caller":"traceutil/trace.go:171","msg":"trace[2125992909] transaction","detail":"{read_only:false; response_revision:14415; number_of_response:1; }","duration":"195.490895ms","start":"2026-10-15T11:45:54.565172Z","end":"2026-10-15T11:45:54.760663Z","steps":["trace[2125992909] 'process raft request' (duration: 195.257289ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:45:54.760879Z","caller":"traceutil/trace.go:171","msg":"trace[1505733936] linearizableReadLoop","detail":"{readStateIndex:14911; appliedIndex:14908; }","duration":"101.72851ms","start":"2026-10-15T11:45:54.659119Z","end":"2026-10-15T11:45:54.760848Z","steps":["trace[1505733936] 'read index received' (duration: 59.475451ms)","trace[1505733936] 'applied index is now lower than readState.Index' (duration: 42.251319ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:45:54.760972Z","caller":"traceutil/trace.go:171","msg":"trace[1977981340] transaction","detail":"{read_only:false; response_revision:14416; number_of_response:1; }","duration":"195.700251ms","start":"2026-10-15T11:45:54.565215Z","end":"2026-10-15T11:45:54.760915Z","steps":["trace[1977981340] 'process raft request' (duration: 195.380913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:45:54.76113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.988417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-6mmvj.18deb165f0e5bc31\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-15T11:45:54.761173Z","caller":"traceutil/trace.go:171","msg":"trace[913469604] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-6mmvj.18deb165f0e5bc31; range_end:; response_count:1; response_revision:14416; }","duration":"102.08451ms","start":"2026-10-15T11:45:54.659075Z","end":"2026-10-15T11:45:54.76116Z","steps":["trace[913469604] 'agreement among raft nodes before linearized reading' (duration: 101.916806ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:47:01.818585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12737} {"level":"info","ts":"2026-10-15T11:47:01.908625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12737,"took":"86.872226ms","hash":3957886030,"current-db-size-bytes":55771136,"current-db-size":"56 MB","current-db-size-in-use-bytes":31277056,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T11:47:01.908724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3957886030,"revision":12737,"compact-revision":10425} {"level":"info","ts":"2026-10-15T11:47:03.253209Z","caller":"traceutil/trace.go:171","msg":"trace[366372974] transaction","detail":"{read_only:false; response_revision:15341; number_of_response:1; }","duration":"155.205346ms","start":"2026-10-15T11:47:03.097975Z","end":"2026-10-15T11:47:03.25318Z","steps":["trace[366372974] 'process raft request' (duration: 154.275323ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:47:03.262887Z","caller":"traceutil/trace.go:171","msg":"trace[1433861430] transaction","detail":"{read_only:false; response_revision:15342; number_of_response:1; }","duration":"162.517876ms","start":"2026-10-15T11:47:03.100353Z","end":"2026-10-15T11:47:03.26287Z","steps":["trace[1433861430] 'process raft request' (duration: 162.392293ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:47:03.263125Z","caller":"traceutil/trace.go:171","msg":"trace[1446759226] transaction","detail":"{read_only:false; response_revision:15343; number_of_response:1; }","duration":"129.528613ms","start":"2026-10-15T11:47:03.133554Z","end":"2026-10-15T11:47:03.263083Z","steps":["trace[1446759226] 'process raft request' (duration: 129.279217ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:47:35.365716Z","caller":"traceutil/trace.go:171","msg":"trace[1491670036] transaction","detail":"{read_only:false; response_revision:15508; number_of_response:1; }","duration":"107.864011ms","start":"2026-10-15T11:47:35.257807Z","end":"2026-10-15T11:47:35.365671Z","steps":["trace[1491670036] 'process raft request' (duration: 107.548333ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:47:35.496621Z","caller":"traceutil/trace.go:171","msg":"trace[1590632296] linearizableReadLoop","detail":"{readStateIndex:16037; appliedIndex:16036; }","duration":"126.743995ms","start":"2026-10-15T11:47:35.36985Z","end":"2026-10-15T11:47:35.496594Z","steps":["trace[1590632296] 'read index received' (duration: 109.0232ms)","trace[1590632296] 'applied index is now lower than readState.Index' (duration: 17.719115ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:47:35.497635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.77216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769736"} {"level":"info","ts":"2026-10-15T11:47:35.497744Z","caller":"traceutil/trace.go:171","msg":"trace[773689379] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15508; }","duration":"127.886184ms","start":"2026-10-15T11:47:35.369808Z","end":"2026-10-15T11:47:35.497694Z","steps":["trace[773689379] 'agreement among raft nodes before linearized reading' (duration: 127.029972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:47:35.508137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.225417ms","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-15T11:47:35.508205Z","caller":"traceutil/trace.go:171","msg":"trace[1724327133] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15509; }","duration":"116.327119ms","start":"2026-10-15T11:47:35.391862Z","end":"2026-10-15T11:47:35.508189Z","steps":["trace[1724327133] 'agreement among raft nodes before linearized reading' (duration: 116.201736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:47:35.508225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.051289ms","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-15T11:47:35.508258Z","caller":"traceutil/trace.go:171","msg":"trace[940960306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15509; }","duration":"111.108351ms","start":"2026-10-15T11:47:35.39714Z","end":"2026-10-15T11:47:35.508249Z","steps":["trace[940960306] 'agreement among raft nodes before linearized reading' (duration: 111.022069ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:47:35.508264Z","caller":"traceutil/trace.go:171","msg":"trace[1157765311] transaction","detail":"{read_only:false; response_revision:15509; number_of_response:1; }","duration":"135.250934ms","start":"2026-10-15T11:47:35.372987Z","end":"2026-10-15T11:47:35.508237Z","steps":["trace[1157765311] 'process raft request' (duration: 134.917705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:47:35.508394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.968996ms","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-15T11:47:35.508413Z","caller":"traceutil/trace.go:171","msg":"trace[482567391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15509; }","duration":"102.008387ms","start":"2026-10-15T11:47:35.4064Z","end":"2026-10-15T11:47:35.508408Z","steps":["trace[482567391] 'agreement among raft nodes before linearized reading' (duration: 101.948075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:47:35.508446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.072524ms","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-15T11:47:35.508538Z","caller":"traceutil/trace.go:171","msg":"trace[2088357050] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15509; }","duration":"114.174637ms","start":"2026-10-15T11:47:35.394346Z","end":"2026-10-15T11:47:35.50852Z","steps":["trace[2088357050] 'agreement among raft nodes before linearized reading' (duration: 114.065424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:47:35.508725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.691879ms","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-15T11:47:35.50882Z","caller":"traceutil/trace.go:171","msg":"trace[1064779788] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15509; }","duration":"116.859653ms","start":"2026-10-15T11:47:35.391934Z","end":"2026-10-15T11:47:35.508794Z","steps":["trace[1064779788] 'agreement among raft nodes before linearized reading' (duration: 116.714019ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:47:36.189327Z","caller":"traceutil/trace.go:171","msg":"trace[1178199077] transaction","detail":"{read_only:false; response_revision:15517; number_of_response:1; }","duration":"102.52895ms","start":"2026-10-15T11:47:36.086781Z","end":"2026-10-15T11:47:36.18931Z","steps":["trace[1178199077] 'process raft request' (duration: 102.209082ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:48:04.44868Z","caller":"traceutil/trace.go:171","msg":"trace[1331139403] linearizableReadLoop","detail":"{readStateIndex:16195; appliedIndex:16194; }","duration":"100.39107ms","start":"2026-10-15T11:48:04.34826Z","end":"2026-10-15T11:48:04.448651Z","steps":["trace[1331139403] 'read index received' (duration: 100.170335ms)","trace[1331139403] 'applied index is now lower than readState.Index' (duration: 219.695µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:48:04.448708Z","caller":"traceutil/trace.go:171","msg":"trace[98787353] transaction","detail":"{read_only:false; response_revision:15660; number_of_response:1; }","duration":"169.596893ms","start":"2026-10-15T11:48:04.279085Z","end":"2026-10-15T11:48:04.448682Z","steps":["trace[98787353] 'process raft request' (duration: 169.372077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:48:04.448885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.612105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jp2ft\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-15T11:48:04.448921Z","caller":"traceutil/trace.go:171","msg":"trace[855139106] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jp2ft; range_end:; response_count:1; response_revision:15660; }","duration":"100.697388ms","start":"2026-10-15T11:48:04.348216Z","end":"2026-10-15T11:48:04.448913Z","steps":["trace[855139106] 'agreement among raft nodes before linearized reading' (duration: 100.556954ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:52:01.825467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15336} {"level":"info","ts":"2026-10-15T11:52:01.935393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15336,"took":"106.990638ms","hash":1972759260,"current-db-size-bytes":55771136,"current-db-size":"56 MB","current-db-size-in-use-bytes":32141312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T11:52:01.935465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1972759260,"revision":15336,"compact-revision":12737} {"level":"info","ts":"2026-10-15T11:53:14.043287Z","caller":"traceutil/trace.go:171","msg":"trace[902743085] transaction","detail":"{read_only:false; response_revision:18105; number_of_response:1; }","duration":"224.348066ms","start":"2026-10-15T11:53:13.818914Z","end":"2026-10-15T11:53:14.043262Z","steps":["trace[902743085] 'process raft request' (duration: 224.149031ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:53:14.043543Z","caller":"traceutil/trace.go:171","msg":"trace[908016926] linearizableReadLoop","detail":"{readStateIndex:18718; appliedIndex:18715; }","duration":"104.983117ms","start":"2026-10-15T11:53:13.938535Z","end":"2026-10-15T11:53:14.043518Z","steps":["trace[908016926] 'read index received' (duration: 104.408923ms)","trace[908016926] 'applied index is now lower than readState.Index' (duration: 573.174µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:53:14.043616Z","caller":"traceutil/trace.go:171","msg":"trace[375738386] transaction","detail":"{read_only:false; response_revision:18106; number_of_response:1; }","duration":"224.544631ms","start":"2026-10-15T11:53:13.819053Z","end":"2026-10-15T11:53:14.043597Z","steps":["trace[375738386] 'process raft request' (duration: 224.163292ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:53:14.043687Z","caller":"traceutil/trace.go:171","msg":"trace[1373506549] transaction","detail":"{read_only:false; response_revision:18107; number_of_response:1; }","duration":"224.311784ms","start":"2026-10-15T11:53:13.819351Z","end":"2026-10-15T11:53:14.043662Z","steps":["trace[1373506549] 'process raft request' (duration: 224.081629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:53:14.043787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.234203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kbq44\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-15T11:53:14.043871Z","caller":"traceutil/trace.go:171","msg":"trace[158417698] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kbq44; range_end:; response_count:1; response_revision:18107; }","duration":"105.363096ms","start":"2026-10-15T11:53:13.938489Z","end":"2026-10-15T11:53:14.043852Z","steps":["trace[158417698] 'agreement among raft nodes before linearized reading' (duration: 105.168631ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:53:16.985926Z","caller":"traceutil/trace.go:171","msg":"trace[1930486133] transaction","detail":"{read_only:false; response_revision:18129; number_of_response:1; }","duration":"158.906171ms","start":"2026-10-15T11:53:16.826958Z","end":"2026-10-15T11:53:16.985864Z","steps":["trace[1930486133] 'process raft request' (duration: 158.718516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:53:17.377326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.444285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T11:53:17.37753Z","caller":"traceutil/trace.go:171","msg":"trace[479058765] linearizableReadLoop","detail":"{readStateIndex:18743; appliedIndex:18741; }","duration":"254.081926ms","start":"2026-10-15T11:53:17.123431Z","end":"2026-10-15T11:53:17.377512Z","steps":["trace[479058765] 'read index received' (duration: 31.92µs)","trace[479058765] 'applied index is now lower than readState.Index' (duration: 254.048986ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:53:17.377537Z","caller":"traceutil/trace.go:171","msg":"trace[25978096] transaction","detail":"{read_only:false; response_revision:18131; number_of_response:1; }","duration":"450.691661ms","start":"2026-10-15T11:53:16.926832Z","end":"2026-10-15T11:53:17.377523Z","steps":["trace[25978096] 'process raft request' (duration: 450.605529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:53:17.377644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:53:16.926806Z","time spent":"450.772082ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:53:17.377529Z","caller":"traceutil/trace.go:171","msg":"trace[658525849] transaction","detail":"{read_only:false; response_revision:18130; number_of_response:1; }","duration":"531.648208ms","start":"2026-10-15T11:53:16.845856Z","end":"2026-10-15T11:53:17.377504Z","steps":["trace[658525849] 'process raft request' (duration: 210.933356ms)","trace[658525849] 'compare' (duration: 320.24026ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:53:17.37771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.273541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-15T11:53:17.377745Z","caller":"traceutil/trace.go:171","msg":"trace[1012523938] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18131; }","duration":"254.332702ms","start":"2026-10-15T11:53:17.123402Z","end":"2026-10-15T11:53:17.377734Z","steps":["trace[1012523938] 'agreement among raft nodes before linearized reading' (duration: 254.216579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:53:17.377772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.507454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4hbg8\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-10-15T11:53:17.377803Z","caller":"traceutil/trace.go:171","msg":"trace[1219142214] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4hbg8; range_end:; response_count:1; response_revision:18131; }","duration":"161.583136ms","start":"2026-10-15T11:53:17.216212Z","end":"2026-10-15T11:53:17.377795Z","steps":["trace[1219142214] 'agreement among raft nodes before linearized reading' (duration: 161.479683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:53:17.377801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.913404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-15T11:53:17.377863Z","caller":"traceutil/trace.go:171","msg":"trace[1895166111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18131; }","duration":"186.004015ms","start":"2026-10-15T11:53:17.19185Z","end":"2026-10-15T11:53:17.377854Z","steps":["trace[1895166111] 'agreement among raft nodes before linearized reading' (duration: 185.80299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:53:17.378035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:53:16.845836Z","time spent":"532.113898ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:55:39.928123Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"65c3b6b1424650a7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T11:55:39.9353Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T11:55:39.935433Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T11:57:01.837147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17502} {"level":"info","ts":"2026-10-15T11:57:01.93829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17502,"took":"97.914781ms","hash":1430383054,"current-db-size-bytes":55771136,"current-db-size":"56 MB","current-db-size-in-use-bytes":35053568,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T11:57:01.938377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1430383054,"revision":17502,"compact-revision":15336} {"level":"info","ts":"2026-10-15T11:58:28.483515Z","caller":"traceutil/trace.go:171","msg":"trace[1875640101] transaction","detail":"{read_only:false; response_revision:20539; number_of_response:1; }","duration":"130.529835ms","start":"2026-10-15T11:58:28.352929Z","end":"2026-10-15T11:58:28.483459Z","steps":["trace[1875640101] 'process raft request' (duration: 130.257939ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:58:44.661293Z","caller":"traceutil/trace.go:171","msg":"trace[574309521] transaction","detail":"{read_only:false; response_revision:20620; number_of_response:1; }","duration":"105.843983ms","start":"2026-10-15T11:58:44.555423Z","end":"2026-10-15T11:58:44.661267Z","steps":["trace[574309521] 'process raft request' (duration: 105.669879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.122209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.193986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T11:58:45.122381Z","caller":"traceutil/trace.go:171","msg":"trace[1313838597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20623; }","duration":"127.36607ms","start":"2026-10-15T11:58:44.994931Z","end":"2026-10-15T11:58:45.122297Z","steps":["trace[1313838597] 'range keys from in-memory index tree' (duration: 126.97738ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:58:45.42628Z","caller":"traceutil/trace.go:171","msg":"trace[697630184] transaction","detail":"{read_only:false; response_revision:20624; number_of_response:1; }","duration":"298.534885ms","start":"2026-10-15T11:58:45.127714Z","end":"2026-10-15T11:58:45.426249Z","steps":["trace[697630184] 'process raft request' (duration: 298.35539ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:58:45.589232Z","caller":"traceutil/trace.go:171","msg":"trace[1507956894] transaction","detail":"{read_only:false; response_revision:20625; number_of_response:1; }","duration":"461.450906ms","start":"2026-10-15T11:58:45.127733Z","end":"2026-10-15T11:58:45.589184Z","steps":["trace[1507956894] 'process raft request' (duration: 440.338649ms)","trace[1507956894] 'compare' (duration: 20.76714ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T11:58:45.589371Z","caller":"traceutil/trace.go:171","msg":"trace[233737543] linearizableReadLoop","detail":"{readStateIndex:21321; appliedIndex:21318; }","duration":"374.882495ms","start":"2026-10-15T11:58:45.21447Z","end":"2026-10-15T11:58:45.589353Z","steps":["trace[233737543] 'read index received' (duration: 211.603306ms)","trace[233737543] 'applied index is now lower than readState.Index' (duration: 163.278069ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:58:45.589426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.127713Z","time spent":"461.61534ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:58:45.589546Z","caller":"traceutil/trace.go:171","msg":"trace[2126947923] transaction","detail":"{read_only:false; response_revision:20626; number_of_response:1; }","duration":"461.807655ms","start":"2026-10-15T11:58:45.127716Z","end":"2026-10-15T11:58:45.589523Z","steps":["trace[2126947923] 'process raft request' (duration: 461.263441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.589644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.127688Z","time spent":"461.905037ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:58:45.590105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.620613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-10-15T11:58:45.590147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.124504ms","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-15T11:58:45.590183Z","caller":"traceutil/trace.go:171","msg":"trace[965935942] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20626; }","duration":"375.755307ms","start":"2026-10-15T11:58:45.21441Z","end":"2026-10-15T11:58:45.590165Z","steps":["trace[965935942] 'agreement among raft nodes before linearized reading' (duration: 375.07544ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T11:58:45.590209Z","caller":"traceutil/trace.go:171","msg":"trace[410747503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20626; }","duration":"331.223346ms","start":"2026-10-15T11:58:45.258968Z","end":"2026-10-15T11:58:45.590191Z","steps":["trace[410747503] 'agreement among raft nodes before linearized reading' (duration: 330.595091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.590226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.214386Z","time spent":"375.832169ms","remote":"127.0.0.1:60958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-15T11:58:45.590106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.251912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-10-15T11:58:45.590266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.25895Z","time spent":"331.300778ms","remote":"127.0.0.1:32810","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":"info","ts":"2026-10-15T11:58:45.590297Z","caller":"traceutil/trace.go:171","msg":"trace[1071656834] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:20626; }","duration":"199.488918ms","start":"2026-10-15T11:58:45.39079Z","end":"2026-10-15T11:58:45.590278Z","steps":["trace[1071656834] 'agreement among raft nodes before linearized reading' (duration: 198.796661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.590594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.328212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.52\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-15T11:58:45.59067Z","caller":"traceutil/trace.go:171","msg":"trace[1360529959] range","detail":"{range_begin:/registry/masterleases/199.204.45.52; range_end:; response_count:1; response_revision:20626; }","duration":"214.428695ms","start":"2026-10-15T11:58:45.376219Z","end":"2026-10-15T11:58:45.590647Z","steps":["trace[1360529959] 'agreement among raft nodes before linearized reading' (duration: 213.515042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.913571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.592288Z","time spent":"321.281133ms","remote":"127.0.0.1:60808","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-15T11:58:45.913524Z","caller":"traceutil/trace.go:171","msg":"trace[2108142681] linearizableReadLoop","detail":"{readStateIndex:21322; appliedIndex:21321; }","duration":"320.092413ms","start":"2026-10-15T11:58:45.593407Z","end":"2026-10-15T11:58:45.9135Z","steps":["trace[2108142681] 'read index received' (duration: 237.54194ms)","trace[2108142681] 'applied index is now lower than readState.Index' (duration: 82.548883ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T11:58:45.914139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.723738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-48fec09d-855c-4c4d-acc6-975e0ff59332\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-10-15T11:58:45.914414Z","caller":"traceutil/trace.go:171","msg":"trace[1335209782] range","detail":"{range_begin:/registry/persistentvolumes/pvc-48fec09d-855c-4c4d-acc6-975e0ff59332; range_end:; response_count:1; response_revision:20626; }","duration":"321.045196ms","start":"2026-10-15T11:58:45.593351Z","end":"2026-10-15T11:58:45.914396Z","steps":["trace[1335209782] 'agreement among raft nodes before linearized reading' (duration: 320.711767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.91448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.593329Z","time spent":"321.135668ms","remote":"127.0.0.1:60934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1316,"request content":"key:\"/registry/persistentvolumes/pvc-48fec09d-855c-4c4d-acc6-975e0ff59332\" "} {"level":"info","ts":"2026-10-15T11:58:45.946098Z","caller":"traceutil/trace.go:171","msg":"trace[1006322082] transaction","detail":"{read_only:false; response_revision:20627; number_of_response:1; }","duration":"351.186615ms","start":"2026-10-15T11:58:45.594852Z","end":"2026-10-15T11:58:45.946039Z","steps":["trace[1006322082] 'process raft request' (duration: 350.9913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.946225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.594838Z","time spent":"351.317728ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T11:58:45.946436Z","caller":"traceutil/trace.go:171","msg":"trace[1010080399] transaction","detail":"{read_only:false; response_revision:20628; number_of_response:1; }","duration":"351.552264ms","start":"2026-10-15T11:58:45.594875Z","end":"2026-10-15T11:58:45.946427Z","steps":["trace[1010080399] 'process raft request' (duration: 351.110553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.946484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.594859Z","time spent":"351.600006ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T11:58:45.946721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.447465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T11:58:45.946746Z","caller":"traceutil/trace.go:171","msg":"trace[2065918564] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20629; }","duration":"352.499207ms","start":"2026-10-15T11:58:45.59424Z","end":"2026-10-15T11:58:45.946739Z","steps":["trace[2065918564] 'agreement among raft nodes before linearized reading' (duration: 352.379504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T11:58:45.946763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T11:58:45.594223Z","time spent":"352.535198ms","remote":"127.0.0.1:60958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-15T11:58:45.94695Z","caller":"traceutil/trace.go:171","msg":"trace[461569840] transaction","detail":"{read_only:false; response_revision:20629; number_of_response:1; }","duration":"146.068379ms","start":"2026-10-15T11:58:45.800822Z","end":"2026-10-15T11:58:45.946891Z","steps":["trace[461569840] 'process raft request' (duration: 145.571747ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:01.844354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19974} {"level":"info","ts":"2026-10-15T12:02:01.970603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19974,"took":"122.563302ms","hash":2731255783,"current-db-size-bytes":55771136,"current-db-size":"56 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T12:02:01.970673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2731255783,"revision":19974,"compact-revision":17502} {"level":"info","ts":"2026-10-15T12:02:30.936036Z","caller":"traceutil/trace.go:171","msg":"trace[1091938455] transaction","detail":"{read_only:false; response_revision:22952; number_of_response:1; }","duration":"119.875078ms","start":"2026-10-15T12:02:30.816128Z","end":"2026-10-15T12:02:30.936003Z","steps":["trace[1091938455] 'process raft request' (duration: 23.26018ms)","trace[1091938455] 'compare' (duration: 96.361541ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:02:30.936081Z","caller":"traceutil/trace.go:171","msg":"trace[321330939] transaction","detail":"{read_only:false; response_revision:22953; number_of_response:1; }","duration":"111.844611ms","start":"2026-10-15T12:02:30.824212Z","end":"2026-10-15T12:02:30.936057Z","steps":["trace[321330939] 'process raft request' (duration: 111.724868ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:30.936244Z","caller":"traceutil/trace.go:171","msg":"trace[1585186282] linearizableReadLoop","detail":"{readStateIndex:23725; appliedIndex:23722; }","duration":"108.057217ms","start":"2026-10-15T12:02:30.828158Z","end":"2026-10-15T12:02:30.936216Z","steps":["trace[1585186282] 'read index received' (duration: 11.245245ms)","trace[1585186282] 'applied index is now lower than readState.Index' (duration: 96.810672ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:02:30.936506Z","caller":"traceutil/trace.go:171","msg":"trace[350006978] transaction","detail":"{read_only:false; response_revision:22954; number_of_response:1; }","duration":"111.391439ms","start":"2026-10-15T12:02:30.825041Z","end":"2026-10-15T12:02:30.936433Z","steps":["trace[350006978] 'process raft request' (duration: 110.97488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:30.936636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.462317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-10-15T12:02:30.936695Z","caller":"traceutil/trace.go:171","msg":"trace[58803175] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:22954; }","duration":"108.586271ms","start":"2026-10-15T12:02:30.828094Z","end":"2026-10-15T12:02:30.93668Z","steps":["trace[58803175] 'agreement among raft nodes before linearized reading' (duration: 108.328244ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:31.032411Z","caller":"traceutil/trace.go:171","msg":"trace[1680232892] transaction","detail":"{read_only:false; response_revision:22955; number_of_response:1; }","duration":"154.906625ms","start":"2026-10-15T12:02:30.877467Z","end":"2026-10-15T12:02:31.032374Z","steps":["trace[1680232892] 'process raft request' (duration: 154.650519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:31.032832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.375106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T12:02:31.032865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.522491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/capo-system/capo-webhook-service-cert\" ","response":"range_response_count:1 size:5137"} {"level":"warn","ts":"2026-10-15T12:02:31.032868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.921054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/capo-system/capo-serving-cert-7jv4s\" ","response":"range_response_count:1 size:2473"} {"level":"warn","ts":"2026-10-15T12:02:31.032937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.504626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-10-15T12:02:31.032952Z","caller":"traceutil/trace.go:171","msg":"trace[1826658927] range","detail":"{range_begin:/registry/secrets/capo-system/capo-webhook-service-cert; range_end:; response_count:1; response_revision:22955; }","duration":"197.634923ms","start":"2026-10-15T12:02:30.835301Z","end":"2026-10-15T12:02:31.032936Z","steps":["trace[1826658927] 'agreement among raft nodes before linearized reading' (duration: 197.310205ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:31.032961Z","caller":"traceutil/trace.go:171","msg":"trace[1586969266] range","detail":"{range_begin:/registry/secrets/capo-system/capo-serving-cert-7jv4s; range_end:; response_count:1; response_revision:22955; }","duration":"150.086137ms","start":"2026-10-15T12:02:30.882859Z","end":"2026-10-15T12:02:31.032945Z","steps":["trace[1586969266] 'agreement among raft nodes before linearized reading' (duration: 149.882292ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:31.033055Z","caller":"traceutil/trace.go:171","msg":"trace[940852066] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:22955; }","duration":"203.564247ms","start":"2026-10-15T12:02:30.8294Z","end":"2026-10-15T12:02:31.032965Z","steps":["trace[940852066] 'agreement among raft nodes before linearized reading' (duration: 203.379452ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:31.032934Z","caller":"traceutil/trace.go:171","msg":"trace[1888001236] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:22955; }","duration":"199.513249ms","start":"2026-10-15T12:02:30.833402Z","end":"2026-10-15T12:02:31.032915Z","steps":["trace[1888001236] 'agreement among raft nodes before linearized reading' (duration: 199.217952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:02:31.043223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.569954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-10-15T12:02:31.043538Z","caller":"traceutil/trace.go:171","msg":"trace[1512164788] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:22956; }","duration":"100.888412ms","start":"2026-10-15T12:02:30.942628Z","end":"2026-10-15T12:02:31.043516Z","steps":["trace[1512164788] 'agreement among raft nodes before linearized reading' (duration: 100.437111ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:38.813962Z","caller":"traceutil/trace.go:171","msg":"trace[369520541] transaction","detail":"{read_only:false; response_revision:23158; number_of_response:1; }","duration":"147.415063ms","start":"2026-10-15T12:02:38.666473Z","end":"2026-10-15T12:02:38.813888Z","steps":["trace[369520541] 'process raft request' (duration: 147.209448ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:02:38.841085Z","caller":"traceutil/trace.go:171","msg":"trace[164465364] transaction","detail":"{read_only:false; response_revision:23159; number_of_response:1; }","duration":"108.720484ms","start":"2026-10-15T12:02:38.732346Z","end":"2026-10-15T12:02:38.841066Z","steps":["trace[164465364] 'process raft request' (duration: 108.18946ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:06.126023Z","caller":"traceutil/trace.go:171","msg":"trace[314977620] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"182.360483ms","start":"2026-10-15T12:06:05.943636Z","end":"2026-10-15T12:06:06.125996Z","steps":["trace[314977620] 'process raft request' (duration: 182.110946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:06.435122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.798323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:06:06.435413Z","caller":"traceutil/trace.go:171","msg":"trace[1304515676] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"304.503302ms","start":"2026-10-15T12:06:06.130863Z","end":"2026-10-15T12:06:06.435367Z","steps":["trace[1304515676] 'process raft request' (duration: 177.37987ms)","trace[1304515676] 'compare' (duration: 126.675871ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:06:06.435454Z","caller":"traceutil/trace.go:171","msg":"trace[986086082] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"302.936245ms","start":"2026-10-15T12:06:06.132466Z","end":"2026-10-15T12:06:06.435402Z","steps":["trace[986086082] 'process raft request' (duration: 302.76001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:06.435554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.130833Z","time spent":"304.649396ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:06:06.435631Z","caller":"traceutil/trace.go:171","msg":"trace[1579626972] linearizableReadLoop","detail":"{readStateIndex:26083; appliedIndex:26081; }","duration":"215.483994ms","start":"2026-10-15T12:06:06.220127Z","end":"2026-10-15T12:06:06.435611Z","steps":["trace[1579626972] 'read index received' (duration: 88.123676ms)","trace[1579626972] 'applied index is now lower than readState.Index' (duration: 127.358398ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:06:06.435836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.696019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:06:06.435929Z","caller":"traceutil/trace.go:171","msg":"trace[119388611] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25259; }","duration":"215.842952ms","start":"2026-10-15T12:06:06.220074Z","end":"2026-10-15T12:06:06.435917Z","steps":["trace[119388611] 'agreement among raft nodes before linearized reading' (duration: 215.675438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:06.436564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.13244Z","time spent":"303.108538ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:06:07.050844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.437394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T12:06:07.051395Z","caller":"traceutil/trace.go:171","msg":"trace[192363045] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"245.399346ms","start":"2026-10-15T12:06:06.805976Z","end":"2026-10-15T12:06:07.051375Z","steps":["trace[192363045] 'process raft request' (duration: 245.357314ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:07.051731Z","caller":"traceutil/trace.go:171","msg":"trace[1187152203] linearizableReadLoop","detail":"{readStateIndex:26084; appliedIndex:26083; }","duration":"615.978966ms","start":"2026-10-15T12:06:06.435721Z","end":"2026-10-15T12:06:07.0517Z","steps":["trace[1187152203] 'read index received' (duration: 224.547606ms)","trace[1187152203] 'applied index is now lower than readState.Index' (duration: 391.428709ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:06:07.051835Z","caller":"traceutil/trace.go:171","msg":"trace[1052199267] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"590.44903ms","start":"2026-10-15T12:06:06.461369Z","end":"2026-10-15T12:06:07.051818Z","steps":["trace[1052199267] 'process raft request' (duration: 589.566299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.051973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.461355Z","time spent":"590.519042ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:06:07.052145Z","caller":"traceutil/trace.go:171","msg":"trace[1217919282] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"469.011558ms","start":"2026-10-15T12:06:06.583122Z","end":"2026-10-15T12:06:07.052133Z","steps":["trace[1217919282] 'process raft request' (duration: 467.87114ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:07.052764Z","caller":"traceutil/trace.go:171","msg":"trace[545699273] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"312.880858ms","start":"2026-10-15T12:06:06.739854Z","end":"2026-10-15T12:06:07.052734Z","steps":["trace[545699273] 'process raft request' (duration: 311.391601ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:07.052826Z","caller":"traceutil/trace.go:171","msg":"trace[1187641398] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"680.519275ms","start":"2026-10-15T12:06:06.37227Z","end":"2026-10-15T12:06:07.052789Z","steps":["trace[1187641398] 'process raft request' (duration: 288.06249ms)","trace[1187641398] 'compare' (duration: 390.126457ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:06:07.052877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.585523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T12:06:07.052918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.73983Z","time spent":"312.99518ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:06:07.052962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.372244Z","time spent":"680.661288ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:06:07.052966Z","caller":"traceutil/trace.go:171","msg":"trace[2085738805] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25264; }","duration":"746.744906ms","start":"2026-10-15T12:06:06.306202Z","end":"2026-10-15T12:06:07.052947Z","steps":["trace[2085738805] 'agreement among raft nodes before linearized reading' (duration: 746.559292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.053007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.230664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-15T12:06:07.05307Z","caller":"traceutil/trace.go:171","msg":"trace[380318134] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25264; }","duration":"695.330216ms","start":"2026-10-15T12:06:06.357723Z","end":"2026-10-15T12:06:07.053053Z","steps":["trace[380318134] 'agreement among raft nodes before linearized reading' (duration: 695.169192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.053107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.357702Z","time spent":"695.397968ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-15T12:06:07.053061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.336191ms","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":"warn","ts":"2026-10-15T12:06:07.053018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.306178Z","time spent":"746.830387ms","remote":"127.0.0.1:37088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:06:07.053161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.98181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T12:06:07.053239Z","caller":"traceutil/trace.go:171","msg":"trace[1456812996] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25264; }","duration":"671.111764ms","start":"2026-10-15T12:06:06.382112Z","end":"2026-10-15T12:06:07.053224Z","steps":["trace[1456812996] 'agreement among raft nodes before linearized reading' (duration: 670.670564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.053274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.38209Z","time spent":"671.176195ms","remote":"127.0.0.1:60958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-15T12:06:07.053348Z","caller":"traceutil/trace.go:171","msg":"trace[1702579341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25264; }","duration":"687.522725ms","start":"2026-10-15T12:06:06.365647Z","end":"2026-10-15T12:06:07.053169Z","steps":["trace[1702579341] 'agreement among raft nodes before linearized reading' (duration: 687.111456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.053741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.365632Z","time spent":"688.057259ms","remote":"127.0.0.1:32810","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-15T12:06:07.061567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.583094Z","time spent":"469.08321ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:06:07.303939Z","caller":"traceutil/trace.go:171","msg":"trace[748394705] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"417.478067ms","start":"2026-10-15T12:06:06.886432Z","end":"2026-10-15T12:06:07.303911Z","steps":["trace[748394705] 'process raft request' (duration: 417.222981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.304091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.886402Z","time spent":"417.607351ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:06:07.304465Z","caller":"traceutil/trace.go:171","msg":"trace[1194739742] linearizableReadLoop","detail":"{readStateIndex:26089; appliedIndex:26089; }","duration":"252.638423ms","start":"2026-10-15T12:06:07.051794Z","end":"2026-10-15T12:06:07.304433Z","steps":["trace[1194739742] 'read index received' (duration: 252.618143ms)","trace[1194739742] 'applied index is now lower than readState.Index' (duration: 17.52µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:06:07.304585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.525814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:06:07.304648Z","caller":"traceutil/trace.go:171","msg":"trace[2084466153] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25265; }","duration":"559.613207ms","start":"2026-10-15T12:06:06.745017Z","end":"2026-10-15T12:06:07.30463Z","steps":["trace[2084466153] 'agreement among raft nodes before linearized reading' (duration: 559.515665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.304719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.745006Z","time spent":"559.701669ms","remote":"127.0.0.1:60552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:06:07.304719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.409332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T12:06:07.304761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.906113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:06:07.304806Z","caller":"traceutil/trace.go:171","msg":"trace[1787105210] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25265; }","duration":"692.023556ms","start":"2026-10-15T12:06:06.612769Z","end":"2026-10-15T12:06:07.304793Z","steps":["trace[1787105210] 'agreement among raft nodes before linearized reading' (duration: 691.819971ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:07.304814Z","caller":"traceutil/trace.go:171","msg":"trace[1883969720] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25265; }","duration":"803.552046ms","start":"2026-10-15T12:06:06.501234Z","end":"2026-10-15T12:06:07.304786Z","steps":["trace[1883969720] 'agreement among raft nodes before linearized reading' (duration: 803.382842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.304827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.377774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T12:06:07.304844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.612746Z","time spent":"692.088617ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:06:07.30487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.501224Z","time spent":"803.629667ms","remote":"127.0.0.1:58526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:06:07.304929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.125041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:06:07.304927Z","caller":"traceutil/trace.go:171","msg":"trace[384603498] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25265; }","duration":"294.467976ms","start":"2026-10-15T12:06:07.010394Z","end":"2026-10-15T12:06:07.304862Z","steps":["trace[384603498] 'agreement among raft nodes before linearized reading' (duration: 294.380525ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:07.304967Z","caller":"traceutil/trace.go:171","msg":"trace[1308344799] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25265; }","duration":"476.193493ms","start":"2026-10-15T12:06:06.828765Z","end":"2026-10-15T12:06:07.304958Z","steps":["trace[1308344799] 'agreement among raft nodes before linearized reading' (duration: 476.130722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.304993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.828752Z","time spent":"476.234714ms","remote":"127.0.0.1:32834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:06:07.305051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.507328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T12:06:07.305138Z","caller":"traceutil/trace.go:171","msg":"trace[1110431504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25265; }","duration":"263.643901ms","start":"2026-10-15T12:06:07.041471Z","end":"2026-10-15T12:06:07.305115Z","steps":["trace[1110431504] 'agreement among raft nodes before linearized reading' (duration: 263.369184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.305157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.994941ms","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-15T12:06:07.305222Z","caller":"traceutil/trace.go:171","msg":"trace[1793533001] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25265; }","duration":"796.080492ms","start":"2026-10-15T12:06:06.509126Z","end":"2026-10-15T12:06:07.305207Z","steps":["trace[1793533001] 'agreement among raft nodes before linearized reading' (duration: 795.97578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.305271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.509117Z","time spent":"796.140684ms","remote":"127.0.0.1:58500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-10-15T12:06:07.305278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.712173ms","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":"info","ts":"2026-10-15T12:06:07.305311Z","caller":"traceutil/trace.go:171","msg":"trace[1500563588] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25265; }","duration":"862.772595ms","start":"2026-10-15T12:06:06.442528Z","end":"2026-10-15T12:06:07.305301Z","steps":["trace[1500563588] 'agreement among raft nodes before linearized reading' (duration: 862.662112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.305343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.442512Z","time spent":"862.824806ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T12:06:07.30517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.967163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-10-15T12:06:07.305428Z","caller":"traceutil/trace.go:171","msg":"trace[1069671934] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25265; }","duration":"848.23769ms","start":"2026-10-15T12:06:06.457176Z","end":"2026-10-15T12:06:07.305413Z","steps":["trace[1069671934] 'agreement among raft nodes before linearized reading' (duration: 847.935152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.305472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:06:06.457167Z","time spent":"848.292471ms","remote":"127.0.0.1:60936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-15T12:06:07.306566Z","caller":"traceutil/trace.go:171","msg":"trace[463958143] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"245.288873ms","start":"2026-10-15T12:06:07.061261Z","end":"2026-10-15T12:06:07.30655Z","steps":["trace[463958143] 'process raft request' (duration: 245.213121ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:07.306707Z","caller":"traceutil/trace.go:171","msg":"trace[757532581] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"253.67486ms","start":"2026-10-15T12:06:07.053023Z","end":"2026-10-15T12:06:07.306698Z","steps":["trace[757532581] 'process raft request' (duration: 253.31192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.30688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.954403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T12:06:07.306913Z","caller":"traceutil/trace.go:171","msg":"trace[309126968] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25268; }","duration":"249.011444ms","start":"2026-10-15T12:06:07.057892Z","end":"2026-10-15T12:06:07.306904Z","steps":["trace[309126968] 'agreement among raft nodes before linearized reading' (duration: 248.885901ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:06:07.306921Z","caller":"traceutil/trace.go:171","msg":"trace[86119324] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"236.809176ms","start":"2026-10-15T12:06:07.070083Z","end":"2026-10-15T12:06:07.306892Z","steps":["trace[86119324] 'process raft request' (duration: 236.429257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.307029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.444826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-15T12:06:07.307077Z","caller":"traceutil/trace.go:171","msg":"trace[552380032] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25268; }","duration":"124.529209ms","start":"2026-10-15T12:06:07.182535Z","end":"2026-10-15T12:06:07.307065Z","steps":["trace[552380032] 'agreement among raft nodes before linearized reading' (duration: 124.369945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:06:07.307143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.290224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-15T12:06:07.307209Z","caller":"traceutil/trace.go:171","msg":"trace[1332794261] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25268; }","duration":"216.404637ms","start":"2026-10-15T12:06:07.090788Z","end":"2026-10-15T12:06:07.307192Z","steps":["trace[1332794261] 'agreement among raft nodes before linearized reading' (duration: 216.14577ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:01.851058Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22345} {"level":"info","ts":"2026-10-15T12:07:01.984442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22345,"took":"129.318275ms","hash":43681140,"current-db-size-bytes":57036800,"current-db-size":"57 MB","current-db-size-in-use-bytes":45875200,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-15T12:07:01.98456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":43681140,"revision":22345,"compact-revision":19974} {"level":"info","ts":"2026-10-15T12:07:03.20715Z","caller":"traceutil/trace.go:171","msg":"trace[1848433922] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"108.330101ms","start":"2026-10-15T12:07:03.098797Z","end":"2026-10-15T12:07:03.207127Z","steps":["trace[1848433922] 'process raft request' (duration: 108.195738ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:08.624908Z","caller":"traceutil/trace.go:171","msg":"trace[2072588378] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"184.628808ms","start":"2026-10-15T12:07:08.440252Z","end":"2026-10-15T12:07:08.624881Z","steps":["trace[2072588378] 'process raft request' (duration: 184.476414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:08.859969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.170518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T12:07:08.860039Z","caller":"traceutil/trace.go:171","msg":"trace[130062000] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25816; }","duration":"182.282081ms","start":"2026-10-15T12:07:08.677741Z","end":"2026-10-15T12:07:08.860023Z","steps":["trace[130062000] 'range keys from in-memory index tree' (duration: 182.024165ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:09.040173Z","caller":"traceutil/trace.go:171","msg":"trace[429903090] linearizableReadLoop","detail":"{readStateIndex:26654; appliedIndex:26653; }","duration":"176.270624ms","start":"2026-10-15T12:07:08.863869Z","end":"2026-10-15T12:07:09.040139Z","steps":["trace[429903090] 'read index received' (duration: 176.229943ms)","trace[429903090] 'applied index is now lower than readState.Index' (duration: 39.371µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:07:09.040307Z","caller":"traceutil/trace.go:171","msg":"trace[772156611] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"176.776096ms","start":"2026-10-15T12:07:08.863519Z","end":"2026-10-15T12:07:09.040295Z","steps":["trace[772156611] 'process raft request' (duration: 176.441958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.040604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.729904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T12:07:09.040717Z","caller":"traceutil/trace.go:171","msg":"trace[2020607209] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25817; }","duration":"176.892558ms","start":"2026-10-15T12:07:08.863804Z","end":"2026-10-15T12:07:09.040696Z","steps":["trace[2020607209] 'agreement among raft nodes before linearized reading' (duration: 176.600362ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:09.315608Z","caller":"traceutil/trace.go:171","msg":"trace[1411965586] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"450.130106ms","start":"2026-10-15T12:07:08.86545Z","end":"2026-10-15T12:07:09.31558Z","steps":["trace[1411965586] 'process raft request' (duration: 449.981982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.315789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:07:08.865426Z","time spent":"450.29222ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:07:09.316285Z","caller":"traceutil/trace.go:171","msg":"trace[1180561704] linearizableReadLoop","detail":"{readStateIndex:26655; appliedIndex:26655; }","duration":"276.017265ms","start":"2026-10-15T12:07:09.040255Z","end":"2026-10-15T12:07:09.316273Z","steps":["trace[1180561704] 'read index received' (duration: 276.011875ms)","trace[1180561704] 'applied index is now lower than readState.Index' (duration: 4.38µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:07:09.316545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.21568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:07:09.316619Z","caller":"traceutil/trace.go:171","msg":"trace[338196324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25818; }","duration":"278.320042ms","start":"2026-10-15T12:07:09.038277Z","end":"2026-10-15T12:07:09.316597Z","steps":["trace[338196324] 'agreement among raft nodes before linearized reading' (duration: 278.170549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.425372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.287026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:07:09.425438Z","caller":"traceutil/trace.go:171","msg":"trace[541668977] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25819; }","duration":"194.387768ms","start":"2026-10-15T12:07:09.231038Z","end":"2026-10-15T12:07:09.425426Z","steps":["trace[541668977] 'agreement among raft nodes before linearized reading' (duration: 194.208453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.42554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.572615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:07:09.425624Z","caller":"traceutil/trace.go:171","msg":"trace[780024525] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25822; }","duration":"253.717219ms","start":"2026-10-15T12:07:09.171887Z","end":"2026-10-15T12:07:09.425605Z","steps":["trace[780024525] 'agreement among raft nodes before linearized reading' (duration: 253.520734ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:09.425754Z","caller":"traceutil/trace.go:171","msg":"trace[813278384] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"248.547412ms","start":"2026-10-15T12:07:09.177193Z","end":"2026-10-15T12:07:09.425741Z","steps":["trace[813278384] 'process raft request' (duration: 230.5018ms)","trace[813278384] 'compare' (duration: 16.843913ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:07:09.425843Z","caller":"traceutil/trace.go:171","msg":"trace[476984581] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"102.084039ms","start":"2026-10-15T12:07:09.323728Z","end":"2026-10-15T12:07:09.425812Z","steps":["trace[476984581] 'process raft request' (duration: 101.497955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.425902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.954732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:07:09.425936Z","caller":"traceutil/trace.go:171","msg":"trace[1150258953] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25823; }","duration":"194.989503ms","start":"2026-10-15T12:07:09.230936Z","end":"2026-10-15T12:07:09.425925Z","steps":["trace[1150258953] 'agreement among raft nodes before linearized reading' (duration: 194.931882ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:09.426059Z","caller":"traceutil/trace.go:171","msg":"trace[301818422] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"100.719165ms","start":"2026-10-15T12:07:09.325326Z","end":"2026-10-15T12:07:09.426045Z","steps":["trace[301818422] 'process raft request' (duration: 100.036448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.426147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.979138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:07:09.426189Z","caller":"traceutil/trace.go:171","msg":"trace[279237128] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25823; }","duration":"288.06014ms","start":"2026-10-15T12:07:09.138119Z","end":"2026-10-15T12:07:09.42618Z","steps":["trace[279237128] 'agreement among raft nodes before linearized reading' (duration: 287.989008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.427088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.305389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19232"} {"level":"info","ts":"2026-10-15T12:07:09.42713Z","caller":"traceutil/trace.go:171","msg":"trace[1497177291] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25823; }","duration":"385.369851ms","start":"2026-10-15T12:07:09.04175Z","end":"2026-10-15T12:07:09.42712Z","steps":["trace[1497177291] 'agreement among raft nodes before linearized reading' (duration: 385.232787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.427159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:07:09.04174Z","time spent":"385.412352ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19254,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-15T12:07:09.425064Z","caller":"traceutil/trace.go:171","msg":"trace[84149533] linearizableReadLoop","detail":"{readStateIndex:26656; appliedIndex:26655; }","duration":"108.612768ms","start":"2026-10-15T12:07:09.316425Z","end":"2026-10-15T12:07:09.425037Z","steps":["trace[84149533] 'read index received' (duration: 91.290083ms)","trace[84149533] 'applied index is now lower than readState.Index' (duration: 17.321665ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:07:09.428212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.700418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:07:09.428249Z","caller":"traceutil/trace.go:171","msg":"trace[203693259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25823; }","duration":"110.76469ms","start":"2026-10-15T12:07:09.317472Z","end":"2026-10-15T12:07:09.428237Z","steps":["trace[203693259] 'agreement among raft nodes before linearized reading' (duration: 110.702569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.63201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.538947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:07:09.632105Z","caller":"traceutil/trace.go:171","msg":"trace[556257570] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25823; }","duration":"106.65259ms","start":"2026-10-15T12:07:09.525432Z","end":"2026-10-15T12:07:09.632084Z","steps":["trace[556257570] 'range keys from in-memory index tree' (duration: 106.431885ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:09.696324Z","caller":"traceutil/trace.go:171","msg":"trace[2019343472] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"146.042844ms","start":"2026-10-15T12:07:09.550258Z","end":"2026-10-15T12:07:09.696301Z","steps":["trace[2019343472] 'process raft request' (duration: 145.85851ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:09.833599Z","caller":"traceutil/trace.go:171","msg":"trace[337338026] linearizableReadLoop","detail":"{readStateIndex:26662; appliedIndex:26661; }","duration":"272.602501ms","start":"2026-10-15T12:07:09.560974Z","end":"2026-10-15T12:07:09.833576Z","steps":["trace[337338026] 'read index received' (duration: 135.920076ms)","trace[337338026] 'applied index is now lower than readState.Index' (duration: 136.681455ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:07:09.833778Z","caller":"traceutil/trace.go:171","msg":"trace[802709969] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"153.920767ms","start":"2026-10-15T12:07:09.679845Z","end":"2026-10-15T12:07:09.833766Z","steps":["trace[802709969] 'process raft request' (duration: 153.6435ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:09.833896Z","caller":"traceutil/trace.go:171","msg":"trace[1384667145] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"276.818254ms","start":"2026-10-15T12:07:09.557046Z","end":"2026-10-15T12:07:09.833864Z","steps":["trace[1384667145] 'process raft request' (duration: 274.061587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.834102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.074943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-15T12:07:09.834183Z","caller":"traceutil/trace.go:171","msg":"trace[639729620] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25826; }","duration":"273.238517ms","start":"2026-10-15T12:07:09.560928Z","end":"2026-10-15T12:07:09.834166Z","steps":["trace[639729620] 'agreement among raft nodes before linearized reading' (duration: 272.95587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.834234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.624578ms","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-15T12:07:09.834234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.094978ms","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-15T12:07:09.834283Z","caller":"traceutil/trace.go:171","msg":"trace[966395069] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25826; }","duration":"175.7076ms","start":"2026-10-15T12:07:09.658561Z","end":"2026-10-15T12:07:09.834269Z","steps":["trace[966395069] 'agreement among raft nodes before linearized reading' (duration: 175.554886ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:09.83429Z","caller":"traceutil/trace.go:171","msg":"trace[272973192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25826; }","duration":"178.17853ms","start":"2026-10-15T12:07:09.656097Z","end":"2026-10-15T12:07:09.834276Z","steps":["trace[272973192] 'agreement among raft nodes before linearized reading' (duration: 177.998956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.834428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.826046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T12:07:09.834507Z","caller":"traceutil/trace.go:171","msg":"trace[628591380] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25826; }","duration":"198.962299ms","start":"2026-10-15T12:07:09.635527Z","end":"2026-10-15T12:07:09.834489Z","steps":["trace[628591380] 'agreement among raft nodes before linearized reading' (duration: 198.792435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:07:09.835051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.143236ms","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-15T12:07:09.835106Z","caller":"traceutil/trace.go:171","msg":"trace[1645822004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25826; }","duration":"195.235239ms","start":"2026-10-15T12:07:09.639853Z","end":"2026-10-15T12:07:09.835088Z","steps":["trace[1645822004] 'agreement among raft nodes before linearized reading' (duration: 194.324486ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:07:23.908947Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000688f.wal"} {"level":"info","ts":"2026-10-15T12:12:01.858317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25769} {"level":"info","ts":"2026-10-15T12:12:02.021233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25769,"took":"158.328298ms","hash":2059810149,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":36048896,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T12:12:02.021302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2059810149,"revision":25769,"compact-revision":22345} {"level":"info","ts":"2026-10-15T12:13:02.88677Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"65c3b6b1424650a7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T12:13:02.892701Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T12:13:02.892796Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T12:17:01.864876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28634} {"level":"info","ts":"2026-10-15T12:17:02.015207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28634,"took":"146.38729ms","hash":3785487612,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T12:17:02.015276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3785487612,"revision":28634,"compact-revision":25769} {"level":"info","ts":"2026-10-15T12:20:59.160567Z","caller":"traceutil/trace.go:171","msg":"trace[1376115413] transaction","detail":"{read_only:false; response_revision:32658; number_of_response:1; }","duration":"119.674917ms","start":"2026-10-15T12:20:59.040857Z","end":"2026-10-15T12:20:59.160532Z","steps":["trace[1376115413] 'process raft request' (duration: 84.737902ms)","trace[1376115413] 'compare' (duration: 34.557356ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:20:59.160941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.686441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:20:59.161077Z","caller":"traceutil/trace.go:171","msg":"trace[1225866353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32659; }","duration":"122.965976ms","start":"2026-10-15T12:20:59.038083Z","end":"2026-10-15T12:20:59.161049Z","steps":["trace[1225866353] 'agreement among raft nodes before linearized reading' (duration: 122.626488ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:00.554443Z","caller":"traceutil/trace.go:171","msg":"trace[1795312698] transaction","detail":"{read_only:false; response_revision:32663; number_of_response:1; }","duration":"150.939181ms","start":"2026-10-15T12:21:00.403461Z","end":"2026-10-15T12:21:00.554401Z","steps":["trace[1795312698] 'process raft request' (duration: 80.56215ms)","trace[1795312698] 'compare' (duration: 70.153395ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:01.281385Z","caller":"traceutil/trace.go:171","msg":"trace[99543110] transaction","detail":"{read_only:false; response_revision:32671; number_of_response:1; }","duration":"173.788979ms","start":"2026-10-15T12:21:01.107568Z","end":"2026-10-15T12:21:01.281357Z","steps":["trace[99543110] 'process raft request' (duration: 75.691771ms)","trace[99543110] 'compare' (duration: 97.942794ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:01.281653Z","caller":"traceutil/trace.go:171","msg":"trace[1447850315] transaction","detail":"{read_only:false; response_revision:32672; number_of_response:1; }","duration":"114.776206ms","start":"2026-10-15T12:21:01.166866Z","end":"2026-10-15T12:21:01.281642Z","steps":["trace[1447850315] 'process raft request' (duration: 114.452758ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:01.281696Z","caller":"traceutil/trace.go:171","msg":"trace[105386709] transaction","detail":"{read_only:false; response_revision:32673; number_of_response:1; }","duration":"114.745295ms","start":"2026-10-15T12:21:01.166911Z","end":"2026-10-15T12:21:01.281656Z","steps":["trace[105386709] 'process raft request' (duration: 114.629263ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:02.969278Z","caller":"traceutil/trace.go:171","msg":"trace[825226631] transaction","detail":"{read_only:false; response_revision:32681; number_of_response:1; }","duration":"184.368858ms","start":"2026-10-15T12:21:02.784891Z","end":"2026-10-15T12:21:02.96926Z","steps":["trace[825226631] 'process raft request' (duration: 116.317723ms)","trace[825226631] 'compare' (duration: 67.836149ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:02.969178Z","caller":"traceutil/trace.go:171","msg":"trace[1099026060] linearizableReadLoop","detail":"{readStateIndex:33705; appliedIndex:33704; }","duration":"150.254823ms","start":"2026-10-15T12:21:02.8189Z","end":"2026-10-15T12:21:02.969155Z","steps":["trace[1099026060] 'read index received' (duration: 82.704181ms)","trace[1099026060] 'applied index is now lower than readState.Index' (duration: 67.549482ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:02.969481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.546021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:02.969584Z","caller":"traceutil/trace.go:171","msg":"trace[956246910] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:32681; }","duration":"150.709185ms","start":"2026-10-15T12:21:02.818849Z","end":"2026-10-15T12:21:02.969558Z","steps":["trace[956246910] 'agreement among raft nodes before linearized reading' (duration: 150.471599ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:03.127331Z","caller":"traceutil/trace.go:171","msg":"trace[1150120792] transaction","detail":"{read_only:false; response_revision:32682; number_of_response:1; }","duration":"254.917853ms","start":"2026-10-15T12:21:02.872391Z","end":"2026-10-15T12:21:03.127309Z","steps":["trace[1150120792] 'process raft request' (duration: 222.174202ms)","trace[1150120792] 'compare' (duration: 32.602178ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:03.127481Z","caller":"traceutil/trace.go:171","msg":"trace[1308264049] linearizableReadLoop","detail":"{readStateIndex:33707; appliedIndex:33705; }","duration":"157.912451ms","start":"2026-10-15T12:21:02.969544Z","end":"2026-10-15T12:21:03.127457Z","steps":["trace[1308264049] 'read index received' (duration: 125.037297ms)","trace[1308264049] 'applied index is now lower than readState.Index' (duration: 32.873774ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:03.127508Z","caller":"traceutil/trace.go:171","msg":"trace[1360220345] transaction","detail":"{read_only:false; response_revision:32683; number_of_response:1; }","duration":"232.769132ms","start":"2026-10-15T12:21:02.894716Z","end":"2026-10-15T12:21:03.127485Z","steps":["trace[1360220345] 'process raft request' (duration: 232.556117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:03.127616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.273476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:03.127649Z","caller":"traceutil/trace.go:171","msg":"trace[1886943086] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:32683; }","duration":"247.359679ms","start":"2026-10-15T12:21:02.880278Z","end":"2026-10-15T12:21:03.127638Z","steps":["trace[1886943086] 'agreement among raft nodes before linearized reading' (duration: 247.258536ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:03.172182Z","caller":"traceutil/trace.go:171","msg":"trace[277953165] transaction","detail":"{read_only:false; response_revision:32684; number_of_response:1; }","duration":"147.058356ms","start":"2026-10-15T12:21:03.025096Z","end":"2026-10-15T12:21:03.172154Z","steps":["trace[277953165] 'process raft request' (duration: 146.908882ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:03.323634Z","caller":"traceutil/trace.go:171","msg":"trace[298293273] transaction","detail":"{read_only:false; response_revision:32685; number_of_response:1; }","duration":"215.228352ms","start":"2026-10-15T12:21:03.10813Z","end":"2026-10-15T12:21:03.323358Z","steps":["trace[298293273] 'process raft request' (duration: 124.77692ms)","trace[298293273] 'compare' (duration: 90.259177ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:05.638603Z","caller":"traceutil/trace.go:171","msg":"trace[370692737] transaction","detail":"{read_only:false; response_revision:32705; number_of_response:1; }","duration":"110.559834ms","start":"2026-10-15T12:21:05.528019Z","end":"2026-10-15T12:21:05.638578Z","steps":["trace[370692737] 'process raft request' (duration: 99.75115ms)","trace[370692737] 'compare' (duration: 10.287401ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:06.588735Z","caller":"traceutil/trace.go:171","msg":"trace[2101736194] transaction","detail":"{read_only:false; response_revision:32707; number_of_response:1; }","duration":"163.486417ms","start":"2026-10-15T12:21:06.425224Z","end":"2026-10-15T12:21:06.588711Z","steps":["trace[2101736194] 'process raft request' (duration: 163.349394ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:06.995778Z","caller":"traceutil/trace.go:171","msg":"trace[22548779] linearizableReadLoop","detail":"{readStateIndex:33735; appliedIndex:33734; }","duration":"118.310933ms","start":"2026-10-15T12:21:06.877448Z","end":"2026-10-15T12:21:06.995759Z","steps":["trace[22548779] 'read index received' (duration: 118.169679ms)","trace[22548779] 'applied index is now lower than readState.Index' (duration: 140.404µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:06.995891Z","caller":"traceutil/trace.go:171","msg":"trace[109596309] transaction","detail":"{read_only:false; response_revision:32710; number_of_response:1; }","duration":"149.931316ms","start":"2026-10-15T12:21:06.84589Z","end":"2026-10-15T12:21:06.995821Z","steps":["trace[109596309] 'process raft request' (duration: 149.743402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:06.996009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.550358ms","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":"info","ts":"2026-10-15T12:21:06.996106Z","caller":"traceutil/trace.go:171","msg":"trace[882109538] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32710; }","duration":"118.699392ms","start":"2026-10-15T12:21:06.877391Z","end":"2026-10-15T12:21:06.99609Z","steps":["trace[882109538] 'agreement among raft nodes before linearized reading' (duration: 118.440046ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:07.489153Z","caller":"traceutil/trace.go:171","msg":"trace[818814959] transaction","detail":"{read_only:false; response_revision:32716; number_of_response:1; }","duration":"110.495942ms","start":"2026-10-15T12:21:07.378607Z","end":"2026-10-15T12:21:07.489102Z","steps":["trace[818814959] 'process raft request' (duration: 86.594027ms)","trace[818814959] 'compare' (duration: 23.619228ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:07.918053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.110181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T12:21:07.918129Z","caller":"traceutil/trace.go:171","msg":"trace[1878696224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:32718; }","duration":"119.244516ms","start":"2026-10-15T12:21:07.798865Z","end":"2026-10-15T12:21:07.91811Z","steps":["trace[1878696224] 'range keys from in-memory index tree' (duration: 118.911577ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:08.091217Z","caller":"traceutil/trace.go:171","msg":"trace[1107284385] transaction","detail":"{read_only:false; response_revision:32720; number_of_response:1; }","duration":"101.744958ms","start":"2026-10-15T12:21:07.989432Z","end":"2026-10-15T12:21:08.091177Z","steps":["trace[1107284385] 'process raft request' (duration: 65.234935ms)","trace[1107284385] 'compare' (duration: 36.37581ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:08.734216Z","caller":"traceutil/trace.go:171","msg":"trace[1832582488] transaction","detail":"{read_only:false; response_revision:32722; number_of_response:1; }","duration":"124.272299ms","start":"2026-10-15T12:21:08.60992Z","end":"2026-10-15T12:21:08.734193Z","steps":["trace[1832582488] 'process raft request' (duration: 124.153566ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:08.84716Z","caller":"traceutil/trace.go:171","msg":"trace[238916546] transaction","detail":"{read_only:false; response_revision:32723; number_of_response:1; }","duration":"114.448669ms","start":"2026-10-15T12:21:08.732666Z","end":"2026-10-15T12:21:08.847115Z","steps":["trace[238916546] 'process raft request' (duration: 65.342868ms)","trace[238916546] 'compare' (duration: 48.62971ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:09.183724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.716338ms","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":"info","ts":"2026-10-15T12:21:09.183889Z","caller":"traceutil/trace.go:171","msg":"trace[522397024] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32724; }","duration":"135.952974ms","start":"2026-10-15T12:21:09.047891Z","end":"2026-10-15T12:21:09.183844Z","steps":["trace[522397024] 'range keys from in-memory index tree' (duration: 135.414791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:09.18408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.071467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:09.184191Z","caller":"traceutil/trace.go:171","msg":"trace[695569539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32724; }","duration":"145.228451ms","start":"2026-10-15T12:21:09.038934Z","end":"2026-10-15T12:21:09.184162Z","steps":["trace[695569539] 'range keys from in-memory index tree' (duration: 144.983274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:09.184358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.019669ms","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-15T12:21:09.184402Z","caller":"traceutil/trace.go:171","msg":"trace[954084849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:32724; }","duration":"184.103411ms","start":"2026-10-15T12:21:09.000287Z","end":"2026-10-15T12:21:09.18439Z","steps":["trace[954084849] 'range keys from in-memory index tree' (duration: 183.854955ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:09.357498Z","caller":"traceutil/trace.go:171","msg":"trace[50003836] transaction","detail":"{read_only:false; response_revision:32726; number_of_response:1; }","duration":"166.920541ms","start":"2026-10-15T12:21:09.190544Z","end":"2026-10-15T12:21:09.357464Z","steps":["trace[50003836] 'process raft request' (duration: 106.424832ms)","trace[50003836] 'compare' (duration: 60.249413ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:09.357575Z","caller":"traceutil/trace.go:171","msg":"trace[1936314614] transaction","detail":"{read_only:false; response_revision:32727; number_of_response:1; }","duration":"165.477046ms","start":"2026-10-15T12:21:09.192068Z","end":"2026-10-15T12:21:09.357545Z","steps":["trace[1936314614] 'process raft request' (duration: 165.314192ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:09.357628Z","caller":"traceutil/trace.go:171","msg":"trace[568147075] transaction","detail":"{read_only:false; response_revision:32728; number_of_response:1; }","duration":"119.777539ms","start":"2026-10-15T12:21:09.237823Z","end":"2026-10-15T12:21:09.357601Z","steps":["trace[568147075] 'process raft request' (duration: 119.623795ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:09.652882Z","caller":"traceutil/trace.go:171","msg":"trace[1272377312] linearizableReadLoop","detail":"{readStateIndex:33756; appliedIndex:33755; }","duration":"138.388465ms","start":"2026-10-15T12:21:09.514447Z","end":"2026-10-15T12:21:09.652836Z","steps":["trace[1272377312] 'read index received' (duration: 138.251381ms)","trace[1272377312] 'applied index is now lower than readState.Index' (duration: 135.984µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:09.652933Z","caller":"traceutil/trace.go:171","msg":"trace[1777223658] transaction","detail":"{read_only:false; response_revision:32731; number_of_response:1; }","duration":"158.728661ms","start":"2026-10-15T12:21:09.494172Z","end":"2026-10-15T12:21:09.652901Z","steps":["trace[1777223658] 'process raft request' (duration: 158.477555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:09.653122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.666721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T12:21:09.653164Z","caller":"traceutil/trace.go:171","msg":"trace[1586616831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:32731; }","duration":"138.764524ms","start":"2026-10-15T12:21:09.514389Z","end":"2026-10-15T12:21:09.653153Z","steps":["trace[1586616831] 'agreement among raft nodes before linearized reading' (duration: 138.60272ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:10.96323Z","caller":"traceutil/trace.go:171","msg":"trace[183110879] transaction","detail":"{read_only:false; response_revision:32737; number_of_response:1; }","duration":"111.252641ms","start":"2026-10-15T12:21:10.851954Z","end":"2026-10-15T12:21:10.963207Z","steps":["trace[183110879] 'process raft request' (duration: 110.848961ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:10.984622Z","caller":"traceutil/trace.go:171","msg":"trace[2124297542] transaction","detail":"{read_only:false; response_revision:32738; number_of_response:1; }","duration":"131.502535ms","start":"2026-10-15T12:21:10.853085Z","end":"2026-10-15T12:21:10.984588Z","steps":["trace[2124297542] 'process raft request' (duration: 131.139216ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:11.525263Z","caller":"traceutil/trace.go:171","msg":"trace[807852222] transaction","detail":"{read_only:false; response_revision:32742; number_of_response:1; }","duration":"161.778265ms","start":"2026-10-15T12:21:11.363429Z","end":"2026-10-15T12:21:11.525208Z","steps":["trace[807852222] 'process raft request' (duration: 161.213241ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:11.525426Z","caller":"traceutil/trace.go:171","msg":"trace[1868080162] linearizableReadLoop","detail":"{readStateIndex:33770; appliedIndex:33767; }","duration":"100.392254ms","start":"2026-10-15T12:21:11.424992Z","end":"2026-10-15T12:21:11.525385Z","steps":["trace[1868080162] 'read index received' (duration: 99.570433ms)","trace[1868080162] 'applied index is now lower than readState.Index' (duration: 820.121µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:11.525536Z","caller":"traceutil/trace.go:171","msg":"trace[1770720815] transaction","detail":"{read_only:false; response_revision:32743; number_of_response:1; }","duration":"159.433148ms","start":"2026-10-15T12:21:11.366083Z","end":"2026-10-15T12:21:11.525516Z","steps":["trace[1770720815] 'process raft request' (duration: 158.910125ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:11.5257Z","caller":"traceutil/trace.go:171","msg":"trace[1983456112] transaction","detail":"{read_only:false; response_revision:32744; number_of_response:1; }","duration":"121.989473ms","start":"2026-10-15T12:21:11.403625Z","end":"2026-10-15T12:21:11.525615Z","steps":["trace[1983456112] 'process raft request' (duration: 121.630935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:11.525751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.744783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T12:21:11.526268Z","caller":"traceutil/trace.go:171","msg":"trace[539658964] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:32744; }","duration":"101.125742ms","start":"2026-10-15T12:21:11.424934Z","end":"2026-10-15T12:21:11.52606Z","steps":["trace[539658964] 'agreement among raft nodes before linearized reading' (duration: 100.61624ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:11.55235Z","caller":"traceutil/trace.go:171","msg":"trace[851847201] transaction","detail":"{read_only:false; response_revision:32745; number_of_response:1; }","duration":"105.672413ms","start":"2026-10-15T12:21:11.44665Z","end":"2026-10-15T12:21:11.552323Z","steps":["trace[851847201] 'process raft request' (duration: 105.54646ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.301831Z","caller":"traceutil/trace.go:171","msg":"trace[1506474209] transaction","detail":"{read_only:false; response_revision:32753; number_of_response:1; }","duration":"116.354634ms","start":"2026-10-15T12:21:13.185444Z","end":"2026-10-15T12:21:13.301798Z","steps":["trace[1506474209] 'process raft request' (duration: 115.854553ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.513913Z","caller":"traceutil/trace.go:171","msg":"trace[805934171] transaction","detail":"{read_only:false; response_revision:32755; number_of_response:1; }","duration":"100.562009ms","start":"2026-10-15T12:21:13.4133Z","end":"2026-10-15T12:21:13.513862Z","steps":["trace[805934171] 'process raft request' (duration: 100.291902ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.690313Z","caller":"traceutil/trace.go:171","msg":"trace[1800053414] transaction","detail":"{read_only:false; response_revision:32757; number_of_response:1; }","duration":"158.262351ms","start":"2026-10-15T12:21:13.532023Z","end":"2026-10-15T12:21:13.690285Z","steps":["trace[1800053414] 'process raft request' (duration: 158.086286ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.690379Z","caller":"traceutil/trace.go:171","msg":"trace[179621562] transaction","detail":"{read_only:false; response_revision:32758; number_of_response:1; }","duration":"154.488907ms","start":"2026-10-15T12:21:13.53585Z","end":"2026-10-15T12:21:13.690339Z","steps":["trace[179621562] 'process raft request' (duration: 154.349894ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:13.690459Z","caller":"traceutil/trace.go:171","msg":"trace[1971471989] transaction","detail":"{read_only:false; response_revision:32759; number_of_response:1; }","duration":"132.707945ms","start":"2026-10-15T12:21:13.557741Z","end":"2026-10-15T12:21:13.690449Z","steps":["trace[1971471989] 'process raft request' (duration: 132.49785ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.117945Z","caller":"traceutil/trace.go:171","msg":"trace[1743420994] transaction","detail":"{read_only:false; response_revision:32766; number_of_response:1; }","duration":"112.609813ms","start":"2026-10-15T12:21:15.0053Z","end":"2026-10-15T12:21:15.11791Z","steps":["trace[1743420994] 'process raft request' (duration: 112.365147ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.209851Z","caller":"traceutil/trace.go:171","msg":"trace[515059523] linearizableReadLoop","detail":"{readStateIndex:33793; appliedIndex:33791; }","duration":"171.842892ms","start":"2026-10-15T12:21:15.037972Z","end":"2026-10-15T12:21:15.209815Z","steps":["trace[515059523] 'read index received' (duration: 79.773021ms)","trace[515059523] 'applied index is now lower than readState.Index' (duration: 92.068091ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:15.20992Z","caller":"traceutil/trace.go:171","msg":"trace[512795324] transaction","detail":"{read_only:false; response_revision:32767; number_of_response:1; }","duration":"202.07409ms","start":"2026-10-15T12:21:15.007799Z","end":"2026-10-15T12:21:15.209873Z","steps":["trace[512795324] 'process raft request' (duration: 147.583408ms)","trace[512795324] 'compare' (duration: 54.295248ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:15.210024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.043497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T12:21:15.210079Z","caller":"traceutil/trace.go:171","msg":"trace[2132849756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32767; }","duration":"172.15322ms","start":"2026-10-15T12:21:15.037898Z","end":"2026-10-15T12:21:15.210052Z","steps":["trace[2132849756] 'agreement among raft nodes before linearized reading' (duration: 172.067498ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.596227Z","caller":"traceutil/trace.go:171","msg":"trace[1507684318] transaction","detail":"{read_only:false; response_revision:32769; number_of_response:1; }","duration":"132.248894ms","start":"2026-10-15T12:21:15.463941Z","end":"2026-10-15T12:21:15.59619Z","steps":["trace[1507684318] 'process raft request' (duration: 40.808488ms)","trace[1507684318] 'compare' (duration: 91.281242ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:15.928405Z","caller":"traceutil/trace.go:171","msg":"trace[1121135592] linearizableReadLoop","detail":"{readStateIndex:33797; appliedIndex:33796; }","duration":"330.022679ms","start":"2026-10-15T12:21:15.598343Z","end":"2026-10-15T12:21:15.928365Z","steps":["trace[1121135592] 'read index received' (duration: 259.297839ms)","trace[1121135592] 'applied index is now lower than readState.Index' (duration: 70.72348ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T12:21:15.928597Z","caller":"traceutil/trace.go:171","msg":"trace[2130168342] transaction","detail":"{read_only:false; response_revision:32770; number_of_response:1; }","duration":"408.17247ms","start":"2026-10-15T12:21:15.520403Z","end":"2026-10-15T12:21:15.928575Z","steps":["trace[2130168342] 'process raft request' (duration: 337.224385ms)","trace[2130168342] 'compare' (duration: 70.466863ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:15.928747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:15.52039Z","time spent":"408.251562ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T12:21:15.928998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.640515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-15T12:21:15.929075Z","caller":"traceutil/trace.go:171","msg":"trace[1566164752] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:32770; }","duration":"330.755627ms","start":"2026-10-15T12:21:15.598299Z","end":"2026-10-15T12:21:15.929055Z","steps":["trace[1566164752] 'agreement among raft nodes before linearized reading' (duration: 330.495571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:15.929112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:15.598281Z","time spent":"330.823229ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-15T12:21:15.930715Z","caller":"traceutil/trace.go:171","msg":"trace[49286742] transaction","detail":"{read_only:false; response_revision:32773; number_of_response:1; }","duration":"230.570367ms","start":"2026-10-15T12:21:15.700134Z","end":"2026-10-15T12:21:15.930704Z","steps":["trace[49286742] 'process raft request' (duration: 230.346921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:15.930775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.193327ms","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":"info","ts":"2026-10-15T12:21:15.930825Z","caller":"traceutil/trace.go:171","msg":"trace[13823473] transaction","detail":"{read_only:false; response_revision:32772; number_of_response:1; }","duration":"231.114451ms","start":"2026-10-15T12:21:15.699706Z","end":"2026-10-15T12:21:15.93082Z","steps":["trace[13823473] 'process raft request' (duration: 230.715751ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.930857Z","caller":"traceutil/trace.go:171","msg":"trace[1880465672] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32774; }","duration":"232.31714ms","start":"2026-10-15T12:21:15.698518Z","end":"2026-10-15T12:21:15.930835Z","steps":["trace[1880465672] 'agreement among raft nodes before linearized reading' (duration: 232.087975ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.93106Z","caller":"traceutil/trace.go:171","msg":"trace[967913676] transaction","detail":"{read_only:false; response_revision:32774; number_of_response:1; }","duration":"225.259578ms","start":"2026-10-15T12:21:15.705793Z","end":"2026-10-15T12:21:15.931052Z","steps":["trace[967913676] 'process raft request' (duration: 224.747995ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:15.930805Z","caller":"traceutil/trace.go:171","msg":"trace[1673385815] transaction","detail":"{read_only:false; response_revision:32771; number_of_response:1; }","duration":"300.348423ms","start":"2026-10-15T12:21:15.630451Z","end":"2026-10-15T12:21:15.930799Z","steps":["trace[1673385815] 'process raft request' (duration: 299.860261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:15.931158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T12:21:15.630434Z","time spent":"300.675481ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T12:21:16.092539Z","caller":"traceutil/trace.go:171","msg":"trace[182434019] transaction","detail":"{read_only:false; response_revision:32775; number_of_response:1; }","duration":"156.818174ms","start":"2026-10-15T12:21:15.935696Z","end":"2026-10-15T12:21:16.092514Z","steps":["trace[182434019] 'process raft request' (duration: 153.893153ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:16.135216Z","caller":"traceutil/trace.go:171","msg":"trace[1275780033] transaction","detail":"{read_only:false; response_revision:32776; number_of_response:1; }","duration":"199.433147ms","start":"2026-10-15T12:21:15.93576Z","end":"2026-10-15T12:21:16.135193Z","steps":["trace[1275780033] 'process raft request' (duration: 198.658389ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:21:16.13538Z","caller":"traceutil/trace.go:171","msg":"trace[2145863549] transaction","detail":"{read_only:false; response_revision:32777; number_of_response:1; }","duration":"199.490458ms","start":"2026-10-15T12:21:15.935865Z","end":"2026-10-15T12:21:16.135356Z","steps":["trace[2145863549] 'process raft request' (duration: 198.799981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T12:21:16.290181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.126203ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T12:21:16.290287Z","caller":"traceutil/trace.go:171","msg":"trace[226556872] linearizableReadLoop","detail":"{readStateIndex:33805; appliedIndex:33804; }","duration":"125.520979ms","start":"2026-10-15T12:21:16.16475Z","end":"2026-10-15T12:21:16.290271Z","steps":["trace[226556872] 'read index received' (duration: 15.260733ms)","trace[226556872] 'applied index is now lower than readState.Index' (duration: 110.258976ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T12:21:16.290516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.746095ms","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-15T12:21:16.290583Z","caller":"traceutil/trace.go:171","msg":"trace[1487927884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:32778; }","duration":"125.852907ms","start":"2026-10-15T12:21:16.164712Z","end":"2026-10-15T12:21:16.290565Z","steps":["trace[1487927884] 'agreement among raft nodes before linearized reading' (duration: 125.656003ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T12:22:01.871962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30858} {"level":"info","ts":"2026-10-15T12:22:02.009271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30858,"took":"133.139215ms","hash":1896241641,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":30519296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T12:22:02.009342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1896241641,"revision":30858,"compact-revision":28634} {"level":"info","ts":"2026-10-15T12:27:01.878238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33106} {"level":"info","ts":"2026-10-15T12:27:02.035872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33106,"took":"154.489186ms","hash":4248427963,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T12:27:02.035914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4248427963,"revision":33106,"compact-revision":30858} {"level":"info","ts":"2026-10-15T12:32:01.88987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35403} {"level":"info","ts":"2026-10-15T12:32:02.043114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35403,"took":"149.003202ms","hash":3430253104,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":30629888,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T12:32:02.043189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3430253104,"revision":35403,"compact-revision":33106} {"level":"info","ts":"2026-10-15T12:34:35.160463Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"65c3b6b1424650a7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T12:34:35.166031Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T12:34:35.166273Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T12:37:01.896852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37630} {"level":"info","ts":"2026-10-15T12:37:02.031635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37630,"took":"130.88242ms","hash":1902886725,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":32260096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T12:37:02.031708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1902886725,"revision":37630,"compact-revision":35403} {"level":"info","ts":"2026-10-15T12:42:01.908715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39909} {"level":"info","ts":"2026-10-15T12:42:02.04805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39909,"took":"135.465249ms","hash":2408435515,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31264768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T12:42:02.048205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2408435515,"revision":39909,"compact-revision":37630}