{"level":"warn","ts":"2026-08-24T07:27:50.151055Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T07:27:50.151162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-08-24T07:27:50.151237Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T07:27:50.151245Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-08-24T07:27:50.151264Z","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-08-24T07:27:50.152052Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-08-24T07:27:50.152221Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-08-24T07:27:50.15469Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.183316ms"} {"level":"info","ts":"2026-08-24T07:27:50.164317Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-08-24T07:27:50.164436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T07:27:50.16447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-08-24T07:27:50.164486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T07:27:50.164497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-08-24T07:27:50.164552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-08-24T07:27:50.169498Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T07:27:50.171207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T07:27:50.172766Z","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-08-24T07:27:50.176118Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T07:27:50.176274Z","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-08-24T07:27:50.176394Z","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-08-24T07:27:50.176406Z","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-08-24T07:27:50.176368Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T07:27:50.17919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-08-24T07:27:50.179403Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-08-24T07:27:50.182018Z","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-08-24T07:27:50.182223Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-08-24T07:27:50.182365Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T07:27:50.182301Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T07:27:50.182506Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-08-24T07:27:50.265266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T07:27:50.26533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T07:27:50.265378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-08-24T07:27:50.265401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-08-24T07:27:50.265415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-08-24T07:27:50.265433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-08-24T07:27:50.265449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-08-24T07:27:50.266467Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T07:27:50.267393Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T07:27:50.267476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T07:27:50.267767Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T07:27:50.26786Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T07:27:50.267891Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T07:27:50.268044Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T07:27:50.26812Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T07:27:50.269756Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-08-24T07:27:50.270431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T07:27:50.270632Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T07:28:02.949475Z","caller":"traceutil/trace.go:171","msg":"trace[1826024605] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"144.229372ms","start":"2026-08-24T07:28:02.805211Z","end":"2026-08-24T07:28:02.94944Z","steps":["trace[1826024605] 'process raft request' (duration: 143.904426ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:28:03.127308Z","caller":"traceutil/trace.go:171","msg":"trace[1919405186] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"174.441809ms","start":"2026-08-24T07:28:02.952837Z","end":"2026-08-24T07:28:03.127279Z","steps":["trace[1919405186] 'process raft request' (duration: 99.954682ms)","trace[1919405186] 'compare' (duration: 74.354004ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:28:34.123269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.688071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T07:28:34.123523Z","caller":"traceutil/trace.go:171","msg":"trace[1590444295] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"840.225951ms","start":"2026-08-24T07:28:33.283286Z","end":"2026-08-24T07:28:34.123512Z","steps":["trace[1590444295] 'read index received' (duration: 60.362µs)","trace[1590444295] 'applied index is now lower than readState.Index' (duration: 840.164769ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:28:34.123563Z","caller":"traceutil/trace.go:171","msg":"trace[1064577846] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.052069316s","start":"2026-08-24T07:28:33.071456Z","end":"2026-08-24T07:28:34.123525Z","steps":["trace[1064577846] 'process raft request' (duration: 132.704221ms)","trace[1064577846] 'compare' (duration: 918.561718ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:28:34.12365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.367824ms","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-08-24T07:28:34.123668Z","caller":"traceutil/trace.go:171","msg":"trace[1161961991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"840.431396ms","start":"2026-08-24T07:28:33.283231Z","end":"2026-08-24T07:28:34.123662Z","steps":["trace[1161961991] 'agreement among raft nodes before linearized reading' (duration: 840.331733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:28:34.123688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:28:33.283211Z","time spent":"840.472286ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T07:28:34.123687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.744677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-24T07:28:34.123764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:28:33.071431Z","time spent":"1.0522404s","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T07:28:34.123787Z","caller":"traceutil/trace.go:171","msg":"trace[129400077] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:592; }","duration":"361.894221ms","start":"2026-08-24T07:28:33.761872Z","end":"2026-08-24T07:28:34.123766Z","steps":["trace[129400077] 'agreement among raft nodes before linearized reading' (duration: 361.717147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:28:34.123837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:28:33.76185Z","time spent":"361.978663ms","remote":"127.0.0.1:40502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-08-24T07:30:01.793878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.782262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T07:30:01.794419Z","caller":"traceutil/trace.go:171","msg":"trace[972962103] linearizableReadLoop","detail":"{readStateIndex:1883; appliedIndex:1880; }","duration":"227.799098ms","start":"2026-08-24T07:30:01.566597Z","end":"2026-08-24T07:30:01.794396Z","steps":["trace[972962103] 'read index received' (duration: 40.112918ms)","trace[972962103] 'applied index is now lower than readState.Index' (duration: 187.6842ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:30:01.794547Z","caller":"traceutil/trace.go:171","msg":"trace[1380806966] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"309.152319ms","start":"2026-08-24T07:30:01.485379Z","end":"2026-08-24T07:30:01.794531Z","steps":["trace[1380806966] 'process raft request' (duration: 121.355847ms)","trace[1380806966] 'compare' (duration: 186.600428ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:30:01.7946Z","caller":"traceutil/trace.go:171","msg":"trace[309626943] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"308.794721ms","start":"2026-08-24T07:30:01.48576Z","end":"2026-08-24T07:30:01.794555Z","steps":["trace[309626943] 'process raft request' (duration: 308.547036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:30:01.794843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:30:01.485751Z","time spent":"308.967665ms","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T07:30:01.794928Z","caller":"traceutil/trace.go:171","msg":"trace[739265846] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"308.871182ms","start":"2026-08-24T07:30:01.485736Z","end":"2026-08-24T07:30:01.794607Z","steps":["trace[739265846] 'process raft request' (duration: 308.503165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:30:01.795212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:30:01.485359Z","time spent":"309.22832ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T07:30:01.79502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.26348ms","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-08-24T07:30:01.795419Z","caller":"traceutil/trace.go:171","msg":"trace[1804962787] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1815; }","duration":"210.73491ms","start":"2026-08-24T07:30:01.584656Z","end":"2026-08-24T07:30:01.795391Z","steps":["trace[1804962787] 'agreement among raft nodes before linearized reading' (duration: 210.21ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:30:01.795161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.539903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2ac4f6df-f2b6-409c-8c4a-ac219839db91\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-08-24T07:30:01.795507Z","caller":"traceutil/trace.go:171","msg":"trace[2031349801] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2ac4f6df-f2b6-409c-8c4a-ac219839db91; range_end:; response_count:1; response_revision:1815; }","duration":"228.942412ms","start":"2026-08-24T07:30:01.56654Z","end":"2026-08-24T07:30:01.795483Z","steps":["trace[2031349801] 'agreement among raft nodes before linearized reading' (duration: 228.330819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:30:01.795483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:30:01.485729Z","time spent":"309.537877ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T07:30:02.343626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.957833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-24T07:30:02.3437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.334475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-24T07:30:02.343726Z","caller":"traceutil/trace.go:171","msg":"trace[1594082983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1816; }","duration":"184.132707ms","start":"2026-08-24T07:30:02.159572Z","end":"2026-08-24T07:30:02.343705Z","steps":["trace[1594082983] 'range keys from in-memory index tree' (duration: 183.672527ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:30:02.343764Z","caller":"traceutil/trace.go:171","msg":"trace[141513777] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1816; }","duration":"144.444797ms","start":"2026-08-24T07:30:02.199299Z","end":"2026-08-24T07:30:02.343744Z","steps":["trace[141513777] 'range keys from in-memory index tree' (duration: 144.165661ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:30:51.026894Z","caller":"traceutil/trace.go:171","msg":"trace[1546969861] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"122.943169ms","start":"2026-08-24T07:30:50.903926Z","end":"2026-08-24T07:30:51.026869Z","steps":["trace[1546969861] 'process raft request' (duration: 122.805556ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:30:56.881222Z","caller":"traceutil/trace.go:171","msg":"trace[167679391] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2240; }","duration":"175.907692ms","start":"2026-08-24T07:30:56.705283Z","end":"2026-08-24T07:30:56.881191Z","steps":["trace[167679391] 'read index received' (duration: 95.750089ms)","trace[167679391] 'applied index is now lower than readState.Index' (duration: 80.156493ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:30:56.88131Z","caller":"traceutil/trace.go:171","msg":"trace[1568198546] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"231.486624ms","start":"2026-08-24T07:30:56.649786Z","end":"2026-08-24T07:30:56.881273Z","steps":["trace[1568198546] 'process raft request' (duration: 151.351681ms)","trace[1568198546] 'compare' (duration: 79.928069ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:30:56.881441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.144537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-24T07:30:56.881708Z","caller":"traceutil/trace.go:171","msg":"trace[1460167633] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"176.238968ms","start":"2026-08-24T07:30:56.705227Z","end":"2026-08-24T07:30:56.881466Z","steps":["trace[1460167633] 'agreement among raft nodes before linearized reading' (duration: 176.077415ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:31:32.608925Z","caller":"traceutil/trace.go:171","msg":"trace[1678554664] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"178.564527ms","start":"2026-08-24T07:31:32.430323Z","end":"2026-08-24T07:31:32.608888Z","steps":["trace[1678554664] 'process raft request' (duration: 178.366114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:32:07.019449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.490086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T07:32:07.019572Z","caller":"traceutil/trace.go:171","msg":"trace[658494422] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"201.127649ms","start":"2026-08-24T07:32:06.818425Z","end":"2026-08-24T07:32:07.019552Z","steps":["trace[658494422] 'process raft request' (duration: 73.38082ms)","trace[658494422] 'compare' (duration: 127.306111ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:32:07.082774Z","caller":"traceutil/trace.go:171","msg":"trace[363450012] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"120.784201ms","start":"2026-08-24T07:32:06.961967Z","end":"2026-08-24T07:32:07.082751Z","steps":["trace[363450012] 'process raft request' (duration: 120.606658ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:05.64084Z","caller":"traceutil/trace.go:171","msg":"trace[704945711] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"133.693547ms","start":"2026-08-24T07:36:05.507127Z","end":"2026-08-24T07:36:05.64082Z","steps":["trace[704945711] 'process raft request' (duration: 133.578394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:36:17.93174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.372705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T07:36:17.931847Z","caller":"traceutil/trace.go:171","msg":"trace[92203867] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"233.459638ms","start":"2026-08-24T07:36:17.69837Z","end":"2026-08-24T07:36:17.93183Z","steps":["trace[92203867] 'process raft request' (duration: 13.925849ms)","trace[92203867] 'compare' (duration: 219.215582ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:36:18.024719Z","caller":"traceutil/trace.go:171","msg":"trace[596133098] linearizableReadLoop","detail":"{readStateIndex:5381; appliedIndex:5380; }","duration":"110.351862ms","start":"2026-08-24T07:36:17.914336Z","end":"2026-08-24T07:36:18.024688Z","steps":["trace[596133098] 'read index received' (duration: 110.184359ms)","trace[596133098] 'applied index is now lower than readState.Index' (duration: 166.413µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:36:18.02512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.76692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xgbkg\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-24T07:36:18.025177Z","caller":"traceutil/trace.go:171","msg":"trace[2108161077] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xgbkg; range_end:; response_count:1; response_revision:5190; }","duration":"110.872783ms","start":"2026-08-24T07:36:17.91429Z","end":"2026-08-24T07:36:18.025163Z","steps":["trace[2108161077] 'agreement among raft nodes before linearized reading' (duration: 110.520636ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:18.025627Z","caller":"traceutil/trace.go:171","msg":"trace[1786628762] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"126.397544ms","start":"2026-08-24T07:36:17.899213Z","end":"2026-08-24T07:36:18.025611Z","steps":["trace[1786628762] 'process raft request' (duration: 125.323892ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:36:25.58323Z","caller":"traceutil/trace.go:171","msg":"trace[2047603251] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"117.781606ms","start":"2026-08-24T07:36:25.465416Z","end":"2026-08-24T07:36:25.583198Z","steps":["trace[2047603251] 'process raft request' (duration: 117.573321ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:37:50.762205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-08-24T07:37:50.836379Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"72.544663ms","hash":1005465706,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":34447360,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-24T07:37:50.836537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1005465706,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-08-24T07:42:30.46621Z","caller":"traceutil/trace.go:171","msg":"trace[1812722014] transaction","detail":"{read_only:false; response_revision:8033; number_of_response:1; }","duration":"126.465163ms","start":"2026-08-24T07:42:30.339717Z","end":"2026-08-24T07:42:30.466182Z","steps":["trace[1812722014] 'process raft request' (duration: 126.228528ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:34.687314Z","caller":"traceutil/trace.go:171","msg":"trace[421310889] linearizableReadLoop","detail":"{readStateIndex:8464; appliedIndex:8463; }","duration":"313.47384ms","start":"2026-08-24T07:42:34.37382Z","end":"2026-08-24T07:42:34.687294Z","steps":["trace[421310889] 'read index received' (duration: 313.397458ms)","trace[421310889] 'applied index is now lower than readState.Index' (duration: 75.002µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:42:34.687434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.610452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T07:42:34.687455Z","caller":"traceutil/trace.go:171","msg":"trace[1900506325] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8180; }","duration":"313.708775ms","start":"2026-08-24T07:42:34.373739Z","end":"2026-08-24T07:42:34.687448Z","steps":["trace[1900506325] 'agreement among raft nodes before linearized reading' (duration: 313.646544ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:34.687415Z","caller":"traceutil/trace.go:171","msg":"trace[1991347363] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"394.440863ms","start":"2026-08-24T07:42:34.292911Z","end":"2026-08-24T07:42:34.687351Z","steps":["trace[1991347363] 'process raft request' (duration: 394.218869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:42:34.68748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:42:34.373705Z","time spent":"313.770566ms","remote":"127.0.0.1:40666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":7,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-24T07:42:34.687685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:42:34.292888Z","time spent":"394.659248ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T07:42:34.718665Z","caller":"traceutil/trace.go:171","msg":"trace[1958086690] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"330.466257ms","start":"2026-08-24T07:42:34.388157Z","end":"2026-08-24T07:42:34.718624Z","steps":["trace[1958086690] 'process raft request' (duration: 330.191501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:42:34.718692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.546904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T07:42:34.718773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.539208ms","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-08-24T07:42:34.718782Z","caller":"traceutil/trace.go:171","msg":"trace[82378447] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8181; }","duration":"343.771389ms","start":"2026-08-24T07:42:34.374989Z","end":"2026-08-24T07:42:34.71876Z","steps":["trace[82378447] 'agreement among raft nodes before linearized reading' (duration: 343.565345ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:34.718811Z","caller":"traceutil/trace.go:171","msg":"trace[903633459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8181; }","duration":"183.63004ms","start":"2026-08-24T07:42:34.535172Z","end":"2026-08-24T07:42:34.718802Z","steps":["trace[903633459] 'agreement among raft nodes before linearized reading' (duration: 183.477807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:42:34.718831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:42:34.388132Z","time spent":"330.61156ms","remote":"127.0.0.1:40698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19413,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T07:42:34.718826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.132735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T07:42:34.718839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T07:42:34.374953Z","time spent":"343.876361ms","remote":"127.0.0.1:41190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-08-24T07:42:34.718951Z","caller":"traceutil/trace.go:171","msg":"trace[46933324] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8181; }","duration":"293.302809ms","start":"2026-08-24T07:42:34.42562Z","end":"2026-08-24T07:42:34.718922Z","steps":["trace[46933324] 'agreement among raft nodes before linearized reading' (duration: 293.080175ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:47.097815Z","caller":"traceutil/trace.go:171","msg":"trace[292517679] transaction","detail":"{read_only:false; response_revision:8248; number_of_response:1; }","duration":"295.909203ms","start":"2026-08-24T07:42:46.801878Z","end":"2026-08-24T07:42:47.097787Z","steps":["trace[292517679] 'process raft request' (duration: 295.826631ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:47.097962Z","caller":"traceutil/trace.go:171","msg":"trace[574952755] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"295.880521ms","start":"2026-08-24T07:42:46.802043Z","end":"2026-08-24T07:42:47.097923Z","steps":["trace[574952755] 'process raft request' (duration: 295.708348ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:47.097821Z","caller":"traceutil/trace.go:171","msg":"trace[450322792] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"296.263439ms","start":"2026-08-24T07:42:46.801507Z","end":"2026-08-24T07:42:47.097771Z","steps":["trace[450322792] 'process raft request' (duration: 247.279778ms)","trace[450322792] 'compare' (duration: 48.665294ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:42:47.098431Z","caller":"traceutil/trace.go:171","msg":"trace[377144613] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"296.087906ms","start":"2026-08-24T07:42:46.802332Z","end":"2026-08-24T07:42:47.098419Z","steps":["trace[377144613] 'process raft request' (duration: 295.538115ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:48.265992Z","caller":"traceutil/trace.go:171","msg":"trace[2137275445] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"109.093167ms","start":"2026-08-24T07:42:48.156846Z","end":"2026-08-24T07:42:48.265939Z","steps":["trace[2137275445] 'process raft request' (duration: 108.938614ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:42:50.76896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-08-24T07:42:50.866837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"95.485579ms","hash":3457741204,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":23367680,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-24T07:42:50.866928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3457741204,"revision":6103,"compact-revision":2977} {"level":"info","ts":"2026-08-24T07:43:15.094216Z","caller":"traceutil/trace.go:171","msg":"trace[1932400410] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"148.804408ms","start":"2026-08-24T07:43:14.945387Z","end":"2026-08-24T07:43:15.094191Z","steps":["trace[1932400410] 'process raft request' (duration: 148.676946ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:15.108609Z","caller":"traceutil/trace.go:171","msg":"trace[76578493] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"161.889686ms","start":"2026-08-24T07:43:14.946684Z","end":"2026-08-24T07:43:15.108573Z","steps":["trace[76578493] 'process raft request' (duration: 161.6637ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:25.415545Z","caller":"traceutil/trace.go:171","msg":"trace[1741424204] transaction","detail":"{read_only:false; response_revision:8510; number_of_response:1; }","duration":"119.398948ms","start":"2026-08-24T07:43:25.296119Z","end":"2026-08-24T07:43:25.415518Z","steps":["trace[1741424204] 'process raft request' (duration: 78.936321ms)","trace[1741424204] 'compare' (duration: 40.280704ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:43:25.415965Z","caller":"traceutil/trace.go:171","msg":"trace[582203625] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"119.628763ms","start":"2026-08-24T07:43:25.29632Z","end":"2026-08-24T07:43:25.415949Z","steps":["trace[582203625] 'process raft request' (duration: 119.153163ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:43:57.62549Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023cf.wal"} {"level":"info","ts":"2026-08-24T07:45:35.788969Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T07:45:35.796339Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-24T07:45:35.796451Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-24T07:47:50.775164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8272} {"level":"info","ts":"2026-08-24T07:47:50.849948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8272,"took":"72.578435ms","hash":3325900722,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":24055808,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-24T07:47:50.850017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3325900722,"revision":8272,"compact-revision":6103} {"level":"info","ts":"2026-08-24T07:50:29.31552Z","caller":"traceutil/trace.go:171","msg":"trace[1630245585] transaction","detail":"{read_only:false; response_revision:11778; number_of_response:1; }","duration":"186.136809ms","start":"2026-08-24T07:50:29.129357Z","end":"2026-08-24T07:50:29.315494Z","steps":["trace[1630245585] 'process raft request' (duration: 185.994476ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:50:29.328664Z","caller":"traceutil/trace.go:171","msg":"trace[608186138] linearizableReadLoop","detail":"{readStateIndex:12180; appliedIndex:12178; }","duration":"172.717932ms","start":"2026-08-24T07:50:29.155933Z","end":"2026-08-24T07:50:29.328651Z","steps":["trace[608186138] 'read index received' (duration: 159.38672ms)","trace[608186138] 'applied index is now lower than readState.Index' (duration: 13.330252ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:50:29.328715Z","caller":"traceutil/trace.go:171","msg":"trace[784622799] transaction","detail":"{read_only:false; response_revision:11779; number_of_response:1; }","duration":"196.527751ms","start":"2026-08-24T07:50:29.13218Z","end":"2026-08-24T07:50:29.328708Z","steps":["trace[784622799] 'process raft request' (duration: 196.412488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:50:29.328959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.996039ms","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-08-24T07:50:29.329056Z","caller":"traceutil/trace.go:171","msg":"trace[1857749861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11779; }","duration":"173.106781ms","start":"2026-08-24T07:50:29.155927Z","end":"2026-08-24T07:50:29.329034Z","steps":["trace[1857749861] 'agreement among raft nodes before linearized reading' (duration: 172.828685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:50:29.426787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.974059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-24T07:50:29.426813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.488421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-24T07:50:29.426819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.227207ms","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-08-24T07:50:29.426845Z","caller":"traceutil/trace.go:171","msg":"trace[364059383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11779; }","duration":"108.044901ms","start":"2026-08-24T07:50:29.318788Z","end":"2026-08-24T07:50:29.426833Z","steps":["trace[364059383] 'agreement among raft nodes before linearized reading' (duration: 107.873366ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:50:29.426873Z","caller":"traceutil/trace.go:171","msg":"trace[1185830203] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11779; }","duration":"111.593473ms","start":"2026-08-24T07:50:29.315264Z","end":"2026-08-24T07:50:29.426857Z","steps":["trace[1185830203] 'agreement among raft nodes before linearized reading' (duration: 111.421319ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:50:29.426879Z","caller":"traceutil/trace.go:171","msg":"trace[2116759381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11779; }","duration":"112.316878ms","start":"2026-08-24T07:50:29.314547Z","end":"2026-08-24T07:50:29.426864Z","steps":["trace[2116759381] 'agreement among raft nodes before linearized reading' (duration: 112.172565ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:52:50.781922Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10495} {"level":"info","ts":"2026-08-24T07:52:50.859257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10495,"took":"75.471573ms","hash":2010228541,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":26288128,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-24T07:52:50.859404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2010228541,"revision":10495,"compact-revision":8272} {"level":"info","ts":"2026-08-24T07:57:08.415876Z","caller":"traceutil/trace.go:171","msg":"trace[383669938] transaction","detail":"{read_only:false; response_revision:14428; number_of_response:1; }","duration":"167.046497ms","start":"2026-08-24T07:57:08.248801Z","end":"2026-08-24T07:57:08.415848Z","steps":["trace[383669938] 'process raft request' (duration: 166.852734ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:23.578311Z","caller":"traceutil/trace.go:171","msg":"trace[1968618368] transaction","detail":"{read_only:false; response_revision:14520; number_of_response:1; }","duration":"147.196952ms","start":"2026-08-24T07:57:23.431082Z","end":"2026-08-24T07:57:23.578279Z","steps":["trace[1968618368] 'process raft request' (duration: 89.585113ms)","trace[1968618368] 'compare' (duration: 57.451796ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:57:24.634583Z","caller":"traceutil/trace.go:171","msg":"trace[1142507277] transaction","detail":"{read_only:false; response_revision:14528; number_of_response:1; }","duration":"163.460245ms","start":"2026-08-24T07:57:24.471098Z","end":"2026-08-24T07:57:24.634558Z","steps":["trace[1142507277] 'process raft request' (duration: 163.310292ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:24.645731Z","caller":"traceutil/trace.go:171","msg":"trace[814099877] transaction","detail":"{read_only:false; response_revision:14529; number_of_response:1; }","duration":"174.088491ms","start":"2026-08-24T07:57:24.471619Z","end":"2026-08-24T07:57:24.645707Z","steps":["trace[814099877] 'process raft request' (duration: 173.918568ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:33.297191Z","caller":"traceutil/trace.go:171","msg":"trace[163300775] linearizableReadLoop","detail":"{readStateIndex:15192; appliedIndex:15191; }","duration":"107.507718ms","start":"2026-08-24T07:57:33.189639Z","end":"2026-08-24T07:57:33.297147Z","steps":["trace[163300775] 'read index received' (duration: 79.000806ms)","trace[163300775] 'applied index is now lower than readState.Index' (duration: 28.505402ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:57:33.297235Z","caller":"traceutil/trace.go:171","msg":"trace[1844926599] transaction","detail":"{read_only:false; response_revision:14691; number_of_response:1; }","duration":"222.699336ms","start":"2026-08-24T07:57:33.074491Z","end":"2026-08-24T07:57:33.29719Z","steps":["trace[1844926599] 'process raft request' (duration: 194.163973ms)","trace[1844926599] 'compare' (duration: 28.305479ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:57:33.297385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.739423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T07:57:33.297452Z","caller":"traceutil/trace.go:171","msg":"trace[1135067881] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:14691; }","duration":"107.892686ms","start":"2026-08-24T07:57:33.189546Z","end":"2026-08-24T07:57:33.297439Z","steps":["trace[1135067881] 'agreement among raft nodes before linearized reading' (duration: 107.702602ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:33.516625Z","caller":"traceutil/trace.go:171","msg":"trace[1815054827] transaction","detail":"{read_only:false; response_revision:14692; number_of_response:1; }","duration":"201.360149ms","start":"2026-08-24T07:57:33.315229Z","end":"2026-08-24T07:57:33.516589Z","steps":["trace[1815054827] 'process raft request' (duration: 194.722404ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:57:50.789626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12750} {"level":"info","ts":"2026-08-24T07:57:50.865473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12750,"took":"72.589565ms","hash":4207141803,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":29122560,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-24T07:57:50.865543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4207141803,"revision":12750,"compact-revision":10495} {"level":"info","ts":"2026-08-24T07:59:12.391411Z","caller":"traceutil/trace.go:171","msg":"trace[1843368924] linearizableReadLoop","detail":"{readStateIndex:16307; appliedIndex:16306; }","duration":"162.439642ms","start":"2026-08-24T07:59:12.228946Z","end":"2026-08-24T07:59:12.391386Z","steps":["trace[1843368924] 'read index received' (duration: 121.76409ms)","trace[1843368924] 'applied index is now lower than readState.Index' (duration: 40.674622ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T07:59:12.391564Z","caller":"traceutil/trace.go:171","msg":"trace[882561485] transaction","detail":"{read_only:false; response_revision:15773; number_of_response:1; }","duration":"178.326558ms","start":"2026-08-24T07:59:12.213225Z","end":"2026-08-24T07:59:12.391551Z","steps":["trace[882561485] 'process raft request' (duration: 137.484652ms)","trace[882561485] 'compare' (duration: 40.467357ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T07:59:12.391925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.27207ms","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-08-24T07:59:12.392012Z","caller":"traceutil/trace.go:171","msg":"trace[1431023977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15774; }","duration":"161.401362ms","start":"2026-08-24T07:59:12.230588Z","end":"2026-08-24T07:59:12.391989Z","steps":["trace[1431023977] 'agreement among raft nodes before linearized reading' (duration: 161.146057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:59:12.392077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.810559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T07:59:12.392169Z","caller":"traceutil/trace.go:171","msg":"trace[1208753751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15774; }","duration":"159.956452ms","start":"2026-08-24T07:59:12.232193Z","end":"2026-08-24T07:59:12.392149Z","steps":["trace[1208753751] 'agreement among raft nodes before linearized reading' (duration: 159.578404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:59:12.391966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.994486ms","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-08-24T07:59:12.39288Z","caller":"traceutil/trace.go:171","msg":"trace[1121394691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15774; }","duration":"163.933014ms","start":"2026-08-24T07:59:12.228915Z","end":"2026-08-24T07:59:12.392848Z","steps":["trace[1121394691] 'agreement among raft nodes before linearized reading' (duration: 162.762771ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:59:18.383906Z","caller":"traceutil/trace.go:171","msg":"trace[7717950] transaction","detail":"{read_only:false; response_revision:15803; number_of_response:1; }","duration":"135.173306ms","start":"2026-08-24T07:59:18.248701Z","end":"2026-08-24T07:59:18.383874Z","steps":["trace[7717950] 'process raft request' (duration: 134.965172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:59:18.578123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.018445ms","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-08-24T07:59:18.578214Z","caller":"traceutil/trace.go:171","msg":"trace[792367707] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15805; }","duration":"120.159788ms","start":"2026-08-24T07:59:18.458033Z","end":"2026-08-24T07:59:18.578193Z","steps":["trace[792367707] 'agreement among raft nodes before linearized reading' (duration: 119.954364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:59:18.578188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.24041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T07:59:18.57828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.805969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-24T07:59:18.578303Z","caller":"traceutil/trace.go:171","msg":"trace[1518794666] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15805; }","duration":"120.376912ms","start":"2026-08-24T07:59:18.457894Z","end":"2026-08-24T07:59:18.578271Z","steps":["trace[1518794666] 'agreement among raft nodes before linearized reading' (duration: 120.185969ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:59:18.578272Z","caller":"traceutil/trace.go:171","msg":"trace[331989933] transaction","detail":"{read_only:false; response_revision:15805; number_of_response:1; }","duration":"148.809164ms","start":"2026-08-24T07:59:18.429426Z","end":"2026-08-24T07:59:18.578236Z","steps":["trace[331989933] 'process raft request' (duration: 148.403386ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:59:18.578339Z","caller":"traceutil/trace.go:171","msg":"trace[2046093112] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15805; }","duration":"118.864631ms","start":"2026-08-24T07:59:18.459457Z","end":"2026-08-24T07:59:18.578322Z","steps":["trace[2046093112] 'agreement among raft nodes before linearized reading' (duration: 118.715068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T07:59:18.578122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.190009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T07:59:18.578492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.053917ms","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-08-24T07:59:18.578498Z","caller":"traceutil/trace.go:171","msg":"trace[1741793309] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15805; }","duration":"119.612187ms","start":"2026-08-24T07:59:18.458877Z","end":"2026-08-24T07:59:18.578489Z","steps":["trace[1741793309] 'agreement among raft nodes before linearized reading' (duration: 119.119997ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T07:59:18.578526Z","caller":"traceutil/trace.go:171","msg":"trace[254119644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15805; }","duration":"143.113657ms","start":"2026-08-24T07:59:18.435403Z","end":"2026-08-24T07:59:18.578516Z","steps":["trace[254119644] 'agreement among raft nodes before linearized reading' (duration: 143.014495ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:02:09.008346Z","caller":"traceutil/trace.go:171","msg":"trace[300503609] transaction","detail":"{read_only:false; response_revision:17225; number_of_response:1; }","duration":"104.498767ms","start":"2026-08-24T08:02:08.903813Z","end":"2026-08-24T08:02:09.008312Z","steps":["trace[300503609] 'process raft request' (duration: 104.12015ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:02:50.812671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14998} {"level":"info","ts":"2026-08-24T08:02:50.904896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14998,"took":"89.337147ms","hash":2335624805,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":30916608,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T08:02:50.904953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2335624805,"revision":14998,"compact-revision":12750} {"level":"info","ts":"2026-08-24T08:04:49.808002Z","caller":"traceutil/trace.go:171","msg":"trace[2091781936] transaction","detail":"{read_only:false; response_revision:18354; number_of_response:1; }","duration":"164.161815ms","start":"2026-08-24T08:04:49.643804Z","end":"2026-08-24T08:04:49.807966Z","steps":["trace[2091781936] 'process raft request' (duration: 113.884692ms)","trace[2091781936] 'compare' (duration: 50.072059ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:04:49.808037Z","caller":"traceutil/trace.go:171","msg":"trace[267863017] transaction","detail":"{read_only:false; response_revision:18355; number_of_response:1; }","duration":"160.99868ms","start":"2026-08-24T08:04:49.64701Z","end":"2026-08-24T08:04:49.808009Z","steps":["trace[267863017] 'process raft request' (duration: 160.901988ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:04:49.808082Z","caller":"traceutil/trace.go:171","msg":"trace[2143776440] linearizableReadLoop","detail":"{readStateIndex:18971; appliedIndex:18970; }","duration":"162.418688ms","start":"2026-08-24T08:04:49.645602Z","end":"2026-08-24T08:04:49.80802Z","steps":["trace[2143776440] 'read index received' (duration: 112.032913ms)","trace[2143776440] 'applied index is now lower than readState.Index' (duration: 50.378985ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:04:49.808389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.186724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"warn","ts":"2026-08-24T08:04:49.808396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.758335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3279"} {"level":"info","ts":"2026-08-24T08:04:49.808463Z","caller":"traceutil/trace.go:171","msg":"trace[461563456] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:18355; }","duration":"162.262355ms","start":"2026-08-24T08:04:49.646161Z","end":"2026-08-24T08:04:49.808423Z","steps":["trace[461563456] 'agreement among raft nodes before linearized reading' (duration: 162.061961ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:04:49.808509Z","caller":"traceutil/trace.go:171","msg":"trace[1158094281] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18355; }","duration":"162.886317ms","start":"2026-08-24T08:04:49.645587Z","end":"2026-08-24T08:04:49.808473Z","steps":["trace[1158094281] 'agreement among raft nodes before linearized reading' (duration: 162.600802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:04:49.808595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.099073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-24T08:04:49.808615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.01411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9253"} {"level":"info","ts":"2026-08-24T08:04:49.808656Z","caller":"traceutil/trace.go:171","msg":"trace[1843695715] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18355; }","duration":"146.213845ms","start":"2026-08-24T08:04:49.662426Z","end":"2026-08-24T08:04:49.80864Z","steps":["trace[1843695715] 'agreement among raft nodes before linearized reading' (duration: 146.005631ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:04:49.80872Z","caller":"traceutil/trace.go:171","msg":"trace[265401916] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18355; }","duration":"162.099252ms","start":"2026-08-24T08:04:49.646563Z","end":"2026-08-24T08:04:49.808662Z","steps":["trace[265401916] 'agreement among raft nodes before linearized reading' (duration: 161.882818ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:06:24.915531Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T08:06:24.983131Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-24T08:06:24.983275Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-24T08:07:50.821177Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17481} {"level":"info","ts":"2026-08-24T08:07:50.9141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17481,"took":"89.633582ms","hash":2889536163,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":34762752,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-24T08:07:50.91416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2889536163,"revision":17481,"compact-revision":14998} {"level":"info","ts":"2026-08-24T08:09:11.34477Z","caller":"traceutil/trace.go:171","msg":"trace[1185268892] transaction","detail":"{read_only:false; response_revision:20472; number_of_response:1; }","duration":"122.839155ms","start":"2026-08-24T08:09:11.221903Z","end":"2026-08-24T08:09:11.344742Z","steps":["trace[1185268892] 'process raft request' (duration: 101.594708ms)","trace[1185268892] 'compare' (duration: 21.064044ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:09:11.345115Z","caller":"traceutil/trace.go:171","msg":"trace[780989176] transaction","detail":"{read_only:false; response_revision:20473; number_of_response:1; }","duration":"120.618189ms","start":"2026-08-24T08:09:11.224465Z","end":"2026-08-24T08:09:11.345083Z","steps":["trace[780989176] 'process raft request' (duration: 120.235101ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:11.345217Z","caller":"traceutil/trace.go:171","msg":"trace[1898500536] linearizableReadLoop","detail":"{readStateIndex:21157; appliedIndex:21153; }","duration":"116.260849ms","start":"2026-08-24T08:09:11.22892Z","end":"2026-08-24T08:09:11.345181Z","steps":["trace[1898500536] 'read index received' (duration: 17.444228ms)","trace[1898500536] 'applied index is now lower than readState.Index' (duration: 98.815681ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:09:11.345231Z","caller":"traceutil/trace.go:171","msg":"trace[83833776] transaction","detail":"{read_only:false; response_revision:20474; number_of_response:1; }","duration":"120.232582ms","start":"2026-08-24T08:09:11.224977Z","end":"2026-08-24T08:09:11.34521Z","steps":["trace[83833776] 'process raft request' (duration: 120.059878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:09:11.345413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.481033ms","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-08-24T08:09:11.345455Z","caller":"traceutil/trace.go:171","msg":"trace[807690309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20474; }","duration":"116.607425ms","start":"2026-08-24T08:09:11.228837Z","end":"2026-08-24T08:09:11.345445Z","steps":["trace[807690309] 'agreement among raft nodes before linearized reading' (duration: 116.433532ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:43.558666Z","caller":"traceutil/trace.go:171","msg":"trace[2067531638] transaction","detail":"{read_only:false; response_revision:20748; number_of_response:1; }","duration":"105.164398ms","start":"2026-08-24T08:09:43.453478Z","end":"2026-08-24T08:09:43.558642Z","steps":["trace[2067531638] 'process raft request' (duration: 19.28778ms)","trace[2067531638] 'compare' (duration: 85.769646ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:09:43.564016Z","caller":"traceutil/trace.go:171","msg":"trace[450101232] transaction","detail":"{read_only:false; response_revision:20749; number_of_response:1; }","duration":"110.11542ms","start":"2026-08-24T08:09:43.453879Z","end":"2026-08-24T08:09:43.563994Z","steps":["trace[450101232] 'process raft request' (duration: 109.816904ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:43.564262Z","caller":"traceutil/trace.go:171","msg":"trace[544995837] transaction","detail":"{read_only:false; response_revision:20750; number_of_response:1; }","duration":"109.257952ms","start":"2026-08-24T08:09:43.45499Z","end":"2026-08-24T08:09:43.564248Z","steps":["trace[544995837] 'process raft request' (duration: 108.802043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:09:43.771708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.274079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T08:09:43.771872Z","caller":"traceutil/trace.go:171","msg":"trace[1297257763] transaction","detail":"{read_only:false; response_revision:20752; number_of_response:1; }","duration":"203.352897ms","start":"2026-08-24T08:09:43.568463Z","end":"2026-08-24T08:09:43.771816Z","steps":["trace[1297257763] 'process raft request' (duration: 102.895984ms)","trace[1297257763] 'compare' (duration: 100.163567ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:09:43.94432Z","caller":"traceutil/trace.go:171","msg":"trace[192017804] transaction","detail":"{read_only:false; response_revision:20755; number_of_response:1; }","duration":"101.065477ms","start":"2026-08-24T08:09:43.843222Z","end":"2026-08-24T08:09:43.944287Z","steps":["trace[192017804] 'process raft request' (duration: 100.936574ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:44.306195Z","caller":"traceutil/trace.go:171","msg":"trace[238246262] transaction","detail":"{read_only:false; response_revision:20758; number_of_response:1; }","duration":"191.980036ms","start":"2026-08-24T08:09:44.114154Z","end":"2026-08-24T08:09:44.306134Z","steps":["trace[238246262] 'process raft request' (duration: 191.735371ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:44.306264Z","caller":"traceutil/trace.go:171","msg":"trace[227489091] linearizableReadLoop","detail":"{readStateIndex:21450; appliedIndex:21448; }","duration":"165.9861ms","start":"2026-08-24T08:09:44.140251Z","end":"2026-08-24T08:09:44.306237Z","steps":["trace[227489091] 'read index received' (duration: 165.608703ms)","trace[227489091] 'applied index is now lower than readState.Index' (duration: 375.497µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:09:44.306519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.227755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cloudformation.18ceaf63c294e3fb\" ","response":"range_response_count:1 size:692"} {"level":"info","ts":"2026-08-24T08:09:44.306569Z","caller":"traceutil/trace.go:171","msg":"trace[877057750] range","detail":"{range_begin:/registry/events/openstack/cloudformation.18ceaf63c294e3fb; range_end:; response_count:1; response_revision:20759; }","duration":"166.330507ms","start":"2026-08-24T08:09:44.140221Z","end":"2026-08-24T08:09:44.306551Z","steps":["trace[877057750] 'agreement among raft nodes before linearized reading' (duration: 166.103303ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:09:44.306622Z","caller":"traceutil/trace.go:171","msg":"trace[126204871] transaction","detail":"{read_only:false; response_revision:20759; number_of_response:1; }","duration":"190.519377ms","start":"2026-08-24T08:09:44.116085Z","end":"2026-08-24T08:09:44.306605Z","steps":["trace[126204871] 'process raft request' (duration: 189.980967ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:10:11.200071Z","caller":"traceutil/trace.go:171","msg":"trace[215107525] transaction","detail":"{read_only:false; response_revision:20927; number_of_response:1; }","duration":"162.111273ms","start":"2026-08-24T08:10:11.03793Z","end":"2026-08-24T08:10:11.200041Z","steps":["trace[215107525] 'process raft request' (duration: 161.943518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:11:24.33086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.486453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T08:11:24.331084Z","caller":"traceutil/trace.go:171","msg":"trace[1722684526] transaction","detail":"{read_only:false; response_revision:21305; number_of_response:1; }","duration":"409.023252ms","start":"2026-08-24T08:11:23.922026Z","end":"2026-08-24T08:11:24.331049Z","steps":["trace[1722684526] 'process raft request' (duration: 276.268015ms)","trace[1722684526] 'compare' (duration: 132.195316ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:11:24.331115Z","caller":"traceutil/trace.go:171","msg":"trace[60237244] transaction","detail":"{read_only:false; response_revision:21307; number_of_response:1; }","duration":"407.590933ms","start":"2026-08-24T08:11:23.923506Z","end":"2026-08-24T08:11:24.331097Z","steps":["trace[60237244] 'process raft request' (duration: 407.544132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:11:24.331197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:11:23.923479Z","time spent":"407.687506ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:11:24.331189Z","caller":"traceutil/trace.go:171","msg":"trace[1842449936] transaction","detail":"{read_only:false; response_revision:21306; number_of_response:1; }","duration":"408.684705ms","start":"2026-08-24T08:11:23.922465Z","end":"2026-08-24T08:11:24.33115Z","steps":["trace[1842449936] 'process raft request' (duration: 408.515072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:11:24.331242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:11:23.922008Z","time spent":"409.155185ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:11:24.331348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:11:23.922456Z","time spent":"408.809288ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:11:24.382063Z","caller":"traceutil/trace.go:171","msg":"trace[2009978067] linearizableReadLoop","detail":"{readStateIndex:22020; appliedIndex:22016; }","duration":"346.070576ms","start":"2026-08-24T08:11:24.035961Z","end":"2026-08-24T08:11:24.382031Z","steps":["trace[2009978067] 'read index received' (duration: 162.319966ms)","trace[2009978067] 'applied index is now lower than readState.Index' (duration: 183.74942ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:11:24.382425Z","caller":"traceutil/trace.go:171","msg":"trace[263430225] transaction","detail":"{read_only:false; response_revision:21308; number_of_response:1; }","duration":"368.189325ms","start":"2026-08-24T08:11:24.01402Z","end":"2026-08-24T08:11:24.382209Z","steps":["trace[263430225] 'process raft request' (duration: 367.854948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:11:24.382594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:11:24.014002Z","time spent":"368.488851ms","remote":"127.0.0.1:40502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:11:24.382991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.945305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:11:24.383043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.351306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-24T08:11:24.383077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.824102ms","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-08-24T08:11:24.38312Z","caller":"traceutil/trace.go:171","msg":"trace[1233896454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21308; }","duration":"347.2008ms","start":"2026-08-24T08:11:24.035903Z","end":"2026-08-24T08:11:24.383104Z","steps":["trace[1233896454] 'agreement among raft nodes before linearized reading' (duration: 346.878672ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:11:24.383137Z","caller":"traceutil/trace.go:171","msg":"trace[1476691048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21308; }","duration":"258.938603ms","start":"2026-08-24T08:11:24.124184Z","end":"2026-08-24T08:11:24.383123Z","steps":["trace[1476691048] 'agreement among raft nodes before linearized reading' (duration: 258.570745ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:11:24.383138Z","caller":"traceutil/trace.go:171","msg":"trace[1271619348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21308; }","duration":"115.488969ms","start":"2026-08-24T08:11:24.267628Z","end":"2026-08-24T08:11:24.383117Z","steps":["trace[1271619348] 'agreement among raft nodes before linearized reading' (duration: 115.174042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:11:24.383162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:11:24.035883Z","time spent":"347.269712ms","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T08:11:24.59969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.36099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T08:11:24.59995Z","caller":"traceutil/trace.go:171","msg":"trace[135213710] linearizableReadLoop","detail":"{readStateIndex:22022; appliedIndex:22020; }","duration":"210.800388ms","start":"2026-08-24T08:11:24.38913Z","end":"2026-08-24T08:11:24.59993Z","steps":["trace[135213710] 'read index received' (duration: 76.098621ms)","trace[135213710] 'applied index is now lower than readState.Index' (duration: 134.700307ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:11:24.600039Z","caller":"traceutil/trace.go:171","msg":"trace[1982156915] transaction","detail":"{read_only:false; response_revision:21310; number_of_response:1; }","duration":"211.343339ms","start":"2026-08-24T08:11:24.388686Z","end":"2026-08-24T08:11:24.600029Z","steps":["trace[1982156915] 'process raft request' (duration: 211.167745ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:11:24.600131Z","caller":"traceutil/trace.go:171","msg":"trace[20159694] transaction","detail":"{read_only:false; response_revision:21309; number_of_response:1; }","duration":"211.808859ms","start":"2026-08-24T08:11:24.38823Z","end":"2026-08-24T08:11:24.600039Z","steps":["trace[20159694] 'process raft request' (duration: 77.00885ms)","trace[20159694] 'compare' (duration: 134.212637ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:11:24.600538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.153055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-24T08:11:24.601247Z","caller":"traceutil/trace.go:171","msg":"trace[1908037257] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:21310; }","duration":"211.535453ms","start":"2026-08-24T08:11:24.389114Z","end":"2026-08-24T08:11:24.600649Z","steps":["trace[1908037257] 'agreement among raft nodes before linearized reading' (duration: 210.929511ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:11:24.787422Z","caller":"traceutil/trace.go:171","msg":"trace[141795753] linearizableReadLoop","detail":"{readStateIndex:22023; appliedIndex:22022; }","duration":"187.386325ms","start":"2026-08-24T08:11:24.599993Z","end":"2026-08-24T08:11:24.787379Z","steps":["trace[141795753] 'read index received' (duration: 175.353422ms)","trace[141795753] 'applied index is now lower than readState.Index' (duration: 12.031923ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:11:24.787486Z","caller":"traceutil/trace.go:171","msg":"trace[48316889] transaction","detail":"{read_only:false; response_revision:21311; number_of_response:1; }","duration":"263.449234ms","start":"2026-08-24T08:11:24.523994Z","end":"2026-08-24T08:11:24.787444Z","steps":["trace[48316889] 'process raft request' (duration: 251.262688ms)","trace[48316889] 'compare' (duration: 11.995952ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:11:24.787643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.056705ms","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-08-24T08:11:24.787643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.695209ms","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-08-24T08:11:24.787685Z","caller":"traceutil/trace.go:171","msg":"trace[383289079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21311; }","duration":"377.145747ms","start":"2026-08-24T08:11:24.410523Z","end":"2026-08-24T08:11:24.787669Z","steps":["trace[383289079] 'agreement among raft nodes before linearized reading' (duration: 376.984604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:11:24.787727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:11:24.410506Z","time spent":"377.213218ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-24T08:11:24.787736Z","caller":"traceutil/trace.go:171","msg":"trace[1853050305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21311; }","duration":"223.825262ms","start":"2026-08-24T08:11:24.563886Z","end":"2026-08-24T08:11:24.787711Z","steps":["trace[1853050305] 'agreement among raft nodes before linearized reading' (duration: 223.590008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:11:24.787903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.871618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T08:11:24.787989Z","caller":"traceutil/trace.go:171","msg":"trace[600592786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21311; }","duration":"286.001971ms","start":"2026-08-24T08:11:24.501964Z","end":"2026-08-24T08:11:24.787966Z","steps":["trace[600592786] 'agreement among raft nodes before linearized reading' (duration: 285.584673ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:11:24.944751Z","caller":"traceutil/trace.go:171","msg":"trace[335563493] transaction","detail":"{read_only:false; response_revision:21314; number_of_response:1; }","duration":"151.10503ms","start":"2026-08-24T08:11:24.79362Z","end":"2026-08-24T08:11:24.944725Z","steps":["trace[335563493] 'process raft request' (duration: 151.057239ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:11:24.944869Z","caller":"traceutil/trace.go:171","msg":"trace[23753514] transaction","detail":"{read_only:false; response_revision:21313; number_of_response:1; }","duration":"151.978917ms","start":"2026-08-24T08:11:24.792856Z","end":"2026-08-24T08:11:24.944835Z","steps":["trace[23753514] 'process raft request' (duration: 151.773933ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:11:24.945381Z","caller":"traceutil/trace.go:171","msg":"trace[1008521730] transaction","detail":"{read_only:false; response_revision:21312; number_of_response:1; }","duration":"152.533178ms","start":"2026-08-24T08:11:24.792823Z","end":"2026-08-24T08:11:24.945356Z","steps":["trace[1008521730] 'process raft request' (duration: 131.082915ms)","trace[1008521730] 'compare' (duration: 20.573126ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:11:36.108774Z","caller":"traceutil/trace.go:171","msg":"trace[135704536] transaction","detail":"{read_only:false; response_revision:21366; number_of_response:1; }","duration":"227.904995ms","start":"2026-08-24T08:11:35.880824Z","end":"2026-08-24T08:11:36.108729Z","steps":["trace[135704536] 'process raft request' (duration: 227.598988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.254354Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.029000396s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T08:12:07.255934Z","caller":"traceutil/trace.go:171","msg":"trace[1124176127] transaction","detail":"{read_only:false; response_revision:21755; number_of_response:1; }","duration":"1.03064919s","start":"2026-08-24T08:12:06.22525Z","end":"2026-08-24T08:12:07.255899Z","steps":["trace[1124176127] 'process raft request' (duration: 1.029392464s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.256122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.225225Z","time spent":"1.030808674s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:07.443142Z","caller":"traceutil/trace.go:171","msg":"trace[1243752768] transaction","detail":"{read_only:false; response_revision:21757; number_of_response:1; }","duration":"927.493731ms","start":"2026-08-24T08:12:06.515622Z","end":"2026-08-24T08:12:07.443116Z","steps":["trace[1243752768] 'process raft request' (duration: 927.401999ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:07.443221Z","caller":"traceutil/trace.go:171","msg":"trace[1527246090] transaction","detail":"{read_only:false; response_revision:21756; number_of_response:1; }","duration":"940.77258ms","start":"2026-08-24T08:12:06.502415Z","end":"2026-08-24T08:12:07.443188Z","steps":["trace[1527246090] 'process raft request' (duration: 914.438107ms)","trace[1527246090] 'compare' (duration: 26.000177ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:12:07.443306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.515593Z","time spent":"927.628273ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:07.443373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.502378Z","time spent":"940.910522ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:07.443628Z","caller":"traceutil/trace.go:171","msg":"trace[1463603297] transaction","detail":"{read_only:false; response_revision:21758; number_of_response:1; }","duration":"925.216145ms","start":"2026-08-24T08:12:06.518392Z","end":"2026-08-24T08:12:07.443608Z","steps":["trace[1463603297] 'process raft request' (duration: 924.688694ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:07.443832Z","caller":"traceutil/trace.go:171","msg":"trace[1687247019] linearizableReadLoop","detail":"{readStateIndex:22488; appliedIndex:22484; }","duration":"646.290007ms","start":"2026-08-24T08:12:06.797503Z","end":"2026-08-24T08:12:07.443793Z","steps":["trace[1687247019] 'read index received' (duration: 457.160747ms)","trace[1687247019] 'applied index is now lower than readState.Index' (duration: 189.12657ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:12:07.443901Z","caller":"traceutil/trace.go:171","msg":"trace[1177710295] transaction","detail":"{read_only:false; response_revision:21759; number_of_response:1; }","duration":"171.506573ms","start":"2026-08-24T08:12:07.272364Z","end":"2026-08-24T08:12:07.44387Z","steps":["trace[1177710295] 'process raft request' (duration: 171.266698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.187849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:07.444096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.742614ms","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-08-24T08:12:07.444112Z","caller":"traceutil/trace.go:171","msg":"trace[619098112] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21759; }","duration":"222.305032ms","start":"2026-08-24T08:12:07.221794Z","end":"2026-08-24T08:12:07.444099Z","steps":["trace[619098112] 'agreement among raft nodes before linearized reading' (duration: 222.198909ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:07.444141Z","caller":"traceutil/trace.go:171","msg":"trace[1129508577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21759; }","duration":"639.826866ms","start":"2026-08-24T08:12:06.804303Z","end":"2026-08-24T08:12:07.44413Z","steps":["trace[1129508577] 'agreement among raft nodes before linearized reading' (duration: 639.635202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.008988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:07.444178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.804282Z","time spent":"639.889007ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T08:12:07.444146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.629114ms","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-08-24T08:12:07.4442Z","caller":"traceutil/trace.go:171","msg":"trace[1062444317] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21759; }","duration":"190.14466ms","start":"2026-08-24T08:12:07.25404Z","end":"2026-08-24T08:12:07.444185Z","steps":["trace[1062444317] 'agreement among raft nodes before linearized reading' (duration: 189.979427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.44423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.51838Z","time spent":"925.351387ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:07.444233Z","caller":"traceutil/trace.go:171","msg":"trace[1009719434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21759; }","duration":"646.761617ms","start":"2026-08-24T08:12:06.797451Z","end":"2026-08-24T08:12:07.444212Z","steps":["trace[1009719434] 'agreement among raft nodes before linearized reading' (duration: 646.455801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:06.797429Z","time spent":"646.846728ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T08:12:07.444294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.211421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:07.44433Z","caller":"traceutil/trace.go:171","msg":"trace[1116701927] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21759; }","duration":"190.306454ms","start":"2026-08-24T08:12:07.254014Z","end":"2026-08-24T08:12:07.44432Z","steps":["trace[1116701927] 'agreement among raft nodes before linearized reading' (duration: 190.252753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.388635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:07.444469Z","caller":"traceutil/trace.go:171","msg":"trace[1169478683] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21759; }","duration":"190.445227ms","start":"2026-08-24T08:12:07.254015Z","end":"2026-08-24T08:12:07.444461Z","steps":["trace[1169478683] 'agreement among raft nodes before linearized reading' (duration: 190.400126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.392523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:07.444606Z","caller":"traceutil/trace.go:171","msg":"trace[1313970798] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:0; response_revision:21759; }","duration":"260.447664ms","start":"2026-08-24T08:12:07.184149Z","end":"2026-08-24T08:12:07.444596Z","steps":["trace[1313970798] 'agreement among raft nodes before linearized reading' (duration: 260.389613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.850735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-24T08:12:07.444647Z","caller":"traceutil/trace.go:171","msg":"trace[1413612960] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21759; }","duration":"190.905895ms","start":"2026-08-24T08:12:07.253733Z","end":"2026-08-24T08:12:07.444639Z","steps":["trace[1413612960] 'agreement among raft nodes before linearized reading' (duration: 190.792503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.901843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:07.444689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.340482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:07.444715Z","caller":"traceutil/trace.go:171","msg":"trace[2046582460] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21759; }","duration":"222.925954ms","start":"2026-08-24T08:12:07.221782Z","end":"2026-08-24T08:12:07.444708Z","steps":["trace[2046582460] 'agreement among raft nodes before linearized reading' (duration: 222.889613ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:07.44475Z","caller":"traceutil/trace.go:171","msg":"trace[894624544] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21759; }","duration":"262.396383ms","start":"2026-08-24T08:12:07.182317Z","end":"2026-08-24T08:12:07.444714Z","steps":["trace[894624544] 'agreement among raft nodes before linearized reading' (duration: 262.341692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.180758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:07.444818Z","caller":"traceutil/trace.go:171","msg":"trace[128901310] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21759; }","duration":"223.23021ms","start":"2026-08-24T08:12:07.221581Z","end":"2026-08-24T08:12:07.444812Z","steps":["trace[128901310] 'agreement among raft nodes before linearized reading' (duration: 223.196639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.330001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-24T08:12:07.444904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.140587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-24T08:12:07.444915Z","caller":"traceutil/trace.go:171","msg":"trace[1289122663] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21759; }","duration":"218.381312ms","start":"2026-08-24T08:12:07.226525Z","end":"2026-08-24T08:12:07.444906Z","steps":["trace[1289122663] 'agreement among raft nodes before linearized reading' (duration: 218.29104ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:07.444938Z","caller":"traceutil/trace.go:171","msg":"trace[1737410355] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21759; }","duration":"262.175848ms","start":"2026-08-24T08:12:07.182754Z","end":"2026-08-24T08:12:07.44493Z","steps":["trace[1737410355] 'agreement among raft nodes before linearized reading' (duration: 262.079456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.444942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.171329ms","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-08-24T08:12:07.444973Z","caller":"traceutil/trace.go:171","msg":"trace[657529585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21759; }","duration":"225.21767ms","start":"2026-08-24T08:12:07.219744Z","end":"2026-08-24T08:12:07.444961Z","steps":["trace[657529585] 'agreement among raft nodes before linearized reading' (duration: 225.137758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.445095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.935583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:07.445131Z","caller":"traceutil/trace.go:171","msg":"trace[830402476] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:0; response_revision:21759; }","duration":"222.982585ms","start":"2026-08-24T08:12:07.22214Z","end":"2026-08-24T08:12:07.445122Z","steps":["trace[830402476] 'agreement among raft nodes before linearized reading' (duration: 222.930964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.445163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.305549ms","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-08-24T08:12:07.445194Z","caller":"traceutil/trace.go:171","msg":"trace[600462173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21759; }","duration":"253.334609ms","start":"2026-08-24T08:12:07.191848Z","end":"2026-08-24T08:12:07.445183Z","steps":["trace[600462173] 'agreement among raft nodes before linearized reading' (duration: 253.254188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.445292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.545806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:07.445362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.497115ms","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-08-24T08:12:07.445375Z","caller":"traceutil/trace.go:171","msg":"trace[391705264] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21759; }","duration":"262.722919ms","start":"2026-08-24T08:12:07.182609Z","end":"2026-08-24T08:12:07.445332Z","steps":["trace[391705264] 'agreement among raft nodes before linearized reading' (duration: 262.439243ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:07.445395Z","caller":"traceutil/trace.go:171","msg":"trace[1794524112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21759; }","duration":"259.570675ms","start":"2026-08-24T08:12:07.185816Z","end":"2026-08-24T08:12:07.445386Z","steps":["trace[1794524112] 'agreement among raft nodes before linearized reading' (duration: 259.490634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.44553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.358483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:07.445625Z","caller":"traceutil/trace.go:171","msg":"trace[286187928] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21759; }","duration":"263.458386ms","start":"2026-08-24T08:12:07.182129Z","end":"2026-08-24T08:12:07.445587Z","steps":["trace[286187928] 'agreement among raft nodes before linearized reading' (duration: 263.357843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.445698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.446996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-24T08:12:07.445734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.5917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:07.445835Z","caller":"traceutil/trace.go:171","msg":"trace[1413515773] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:0; response_revision:21759; }","duration":"191.761884ms","start":"2026-08-24T08:12:07.254051Z","end":"2026-08-24T08:12:07.445813Z","steps":["trace[1413515773] 'agreement among raft nodes before linearized reading' (duration: 191.57776ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:07.445777Z","caller":"traceutil/trace.go:171","msg":"trace[543776488] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:21759; }","duration":"185.719322ms","start":"2026-08-24T08:12:07.260046Z","end":"2026-08-24T08:12:07.445766Z","steps":["trace[543776488] 'agreement among raft nodes before linearized reading' (duration: 185.498607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.446312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.670989ms","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":"info","ts":"2026-08-24T08:12:07.446405Z","caller":"traceutil/trace.go:171","msg":"trace[544398792] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:21759; }","duration":"421.846913ms","start":"2026-08-24T08:12:07.024538Z","end":"2026-08-24T08:12:07.446385Z","steps":["trace[544398792] 'agreement among raft nodes before linearized reading' (duration: 421.005506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:07.447339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.024516Z","time spent":"422.783551ms","remote":"127.0.0.1:40660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":30,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-08-24T08:12:08.066218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728513540325351,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T08:12:08.301163Z","caller":"traceutil/trace.go:171","msg":"trace[1823148903] transaction","detail":"{read_only:false; response_revision:21761; number_of_response:1; }","duration":"851.350799ms","start":"2026-08-24T08:12:07.44979Z","end":"2026-08-24T08:12:08.301141Z","steps":["trace[1823148903] 'process raft request' (duration: 851.187496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.301331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.449765Z","time spent":"851.493562ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:08.301442Z","caller":"traceutil/trace.go:171","msg":"trace[2032766531] transaction","detail":"{read_only:false; response_revision:21762; number_of_response:1; }","duration":"851.205256ms","start":"2026-08-24T08:12:07.450203Z","end":"2026-08-24T08:12:08.301408Z","steps":["trace[2032766531] 'process raft request' (duration: 850.90638ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:08.301549Z","caller":"traceutil/trace.go:171","msg":"trace[1433277244] linearizableReadLoop","detail":"{readStateIndex:22495; appliedIndex:22490; }","duration":"735.509893ms","start":"2026-08-24T08:12:07.566022Z","end":"2026-08-24T08:12:08.301532Z","steps":["trace[1433277244] 'read index received' (duration: 734.999962ms)","trace[1433277244] 'applied index is now lower than readState.Index' (duration: 509.281µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:12:08.301626Z","caller":"traceutil/trace.go:171","msg":"trace[104218983] transaction","detail":"{read_only:false; response_revision:21763; number_of_response:1; }","duration":"851.337798ms","start":"2026-08-24T08:12:07.450281Z","end":"2026-08-24T08:12:08.301619Z","steps":["trace[104218983] 'process raft request' (duration: 851.038822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.301666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.450192Z","time spent":"851.327048ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:08.301706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.450274Z","time spent":"851.378089ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:08.301755Z","caller":"traceutil/trace.go:171","msg":"trace[237312959] transaction","detail":"{read_only:false; response_revision:21765; number_of_response:1; }","duration":"851.200256ms","start":"2026-08-24T08:12:07.450531Z","end":"2026-08-24T08:12:08.301731Z","steps":["trace[237312959] 'process raft request' (duration: 850.966341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.301802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.798549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:08.301823Z","caller":"traceutil/trace.go:171","msg":"trace[469818299] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21765; }","duration":"735.86022ms","start":"2026-08-24T08:12:07.565957Z","end":"2026-08-24T08:12:08.301817Z","steps":["trace[469818299] 'agreement among raft nodes before linearized reading' (duration: 735.82297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.301842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.565934Z","time spent":"735.903702ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" "} {"level":"info","ts":"2026-08-24T08:12:08.301858Z","caller":"traceutil/trace.go:171","msg":"trace[1400099712] transaction","detail":"{read_only:false; response_revision:21764; number_of_response:1; }","duration":"851.511612ms","start":"2026-08-24T08:12:07.45033Z","end":"2026-08-24T08:12:08.301841Z","steps":["trace[1400099712] 'process raft request' (duration: 851.125414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.301903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.450522Z","time spent":"851.297238ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:08.301952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.450323Z","time spent":"851.580594ms","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:08.3427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.742607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:08.342744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.593029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:08.342755Z","caller":"traceutil/trace.go:171","msg":"trace[297468540] transaction","detail":"{read_only:false; response_revision:21766; number_of_response:1; }","duration":"187.16176ms","start":"2026-08-24T08:12:08.155559Z","end":"2026-08-24T08:12:08.342721Z","steps":["trace[297468540] 'process raft request' (duration: 186.945816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.342787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.811339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:08.342806Z","caller":"traceutil/trace.go:171","msg":"trace[170104333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21766; }","duration":"306.65862ms","start":"2026-08-24T08:12:08.036131Z","end":"2026-08-24T08:12:08.342789Z","steps":["trace[170104333] 'agreement among raft nodes before linearized reading' (duration: 306.554958ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:08.342835Z","caller":"traceutil/trace.go:171","msg":"trace[377641294] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21766; }","duration":"773.87294ms","start":"2026-08-24T08:12:07.568949Z","end":"2026-08-24T08:12:08.342822Z","steps":["trace[377641294] 'agreement among raft nodes before linearized reading' (duration: 773.788408ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:08.342788Z","caller":"traceutil/trace.go:171","msg":"trace[1917253542] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:0; response_revision:21766; }","duration":"773.86275ms","start":"2026-08-24T08:12:07.568905Z","end":"2026-08-24T08:12:08.342768Z","steps":["trace[1917253542] 'agreement among raft nodes before linearized reading' (duration: 773.714717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.342815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.117015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:08.34285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:08.036045Z","time spent":"306.796223ms","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T08:12:08.342876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.568946Z","time spent":"773.923521ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"info","ts":"2026-08-24T08:12:08.342894Z","caller":"traceutil/trace.go:171","msg":"trace[191152147] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21766; }","duration":"773.217827ms","start":"2026-08-24T08:12:07.56966Z","end":"2026-08-24T08:12:08.342878Z","steps":["trace[191152147] 'agreement among raft nodes before linearized reading' (duration: 773.082705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.342905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.742574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:08.342886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.568889Z","time spent":"773.984992ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-db-sync\" "} {"level":"warn","ts":"2026-08-24T08:12:08.342948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.56963Z","time spent":"773.308928ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" "} {"level":"warn","ts":"2026-08-24T08:12:08.343035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.440008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T08:12:08.342954Z","caller":"traceutil/trace.go:171","msg":"trace[1198764995] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:21766; }","duration":"558.833616ms","start":"2026-08-24T08:12:07.784104Z","end":"2026-08-24T08:12:08.342938Z","steps":["trace[1198764995] 'agreement among raft nodes before linearized reading' (duration: 558.756245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:08.343156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:07.784047Z","time spent":"559.092471ms","remote":"127.0.0.1:55902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-08-24T08:12:08.343161Z","caller":"traceutil/trace.go:171","msg":"trace[1895783221] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:21766; }","duration":"117.641052ms","start":"2026-08-24T08:12:08.22549Z","end":"2026-08-24T08:12:08.343131Z","steps":["trace[1895783221] 'agreement among raft nodes before linearized reading' (duration: 117.379007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:40.034712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728513540326058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:12:40.534914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728513540326058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:12:41.035731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471728513540326058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T08:12:41.431859Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.068316262s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T08:12:41.432323Z","caller":"traceutil/trace.go:171","msg":"trace[169758450] transaction","detail":"{read_only:false; response_revision:21952; number_of_response:1; }","duration":"2.068899424s","start":"2026-08-24T08:12:39.363396Z","end":"2026-08-24T08:12:41.432296Z","steps":["trace[169758450] 'process raft request' (duration: 2.06871829s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.432479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.363364Z","time spent":"2.069036256s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:41.68413Z","caller":"traceutil/trace.go:171","msg":"trace[1238657619] transaction","detail":"{read_only:false; response_revision:21953; number_of_response:1; }","duration":"2.119427677s","start":"2026-08-24T08:12:39.564669Z","end":"2026-08-24T08:12:41.684097Z","steps":["trace[1238657619] 'process raft request' (duration: 2.119215143s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.684163Z","caller":"traceutil/trace.go:171","msg":"trace[470527398] transaction","detail":"{read_only:false; response_revision:21954; number_of_response:1; }","duration":"2.113699171s","start":"2026-08-24T08:12:39.57042Z","end":"2026-08-24T08:12:41.684119Z","steps":["trace[470527398] 'process raft request' (duration: 2.113573449s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.684228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.150336373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.684262Z","caller":"traceutil/trace.go:171","msg":"trace[1748965072] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21957; }","duration":"2.150413425s","start":"2026-08-24T08:12:39.533843Z","end":"2026-08-24T08:12:41.684256Z","steps":["trace[1748965072] 'agreement among raft nodes before linearized reading' (duration: 2.150340474s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.684283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.533801Z","time spent":"2.150477365s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" "} {"level":"warn","ts":"2026-08-24T08:12:41.684293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.564648Z","time spent":"2.11955286s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:41.684348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.570404Z","time spent":"2.113844974s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T08:12:41.684146Z","caller":"traceutil/trace.go:171","msg":"trace[1178542209] linearizableReadLoop","detail":"{readStateIndex:22694; appliedIndex:22688; }","duration":"2.150246322s","start":"2026-08-24T08:12:39.533884Z","end":"2026-08-24T08:12:41.68413Z","steps":["trace[1178542209] 'read index received' (duration: 1.898241589s)","trace[1178542209] 'applied index is now lower than readState.Index' (duration: 252.004013ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:12:41.684414Z","caller":"traceutil/trace.go:171","msg":"trace[1822944571] transaction","detail":"{read_only:false; response_revision:21955; number_of_response:1; }","duration":"2.110611169s","start":"2026-08-24T08:12:39.573796Z","end":"2026-08-24T08:12:41.684407Z","steps":["trace[1822944571] 'process raft request' (duration: 2.110224461s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.684476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.573787Z","time spent":"2.11064922s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:41.684481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.147161248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.684506Z","caller":"traceutil/trace.go:171","msg":"trace[1950149377] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21957; }","duration":"2.14719821s","start":"2026-08-24T08:12:39.537301Z","end":"2026-08-24T08:12:41.684499Z","steps":["trace[1950149377] 'agreement among raft nodes before linearized reading' (duration: 2.147149269s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.684527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.537294Z","time spent":"2.14722808s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" "} {"level":"info","ts":"2026-08-24T08:12:41.68455Z","caller":"traceutil/trace.go:171","msg":"trace[1554873973] transaction","detail":"{read_only:false; response_revision:21956; number_of_response:1; }","duration":"1.828351093s","start":"2026-08-24T08:12:39.856184Z","end":"2026-08-24T08:12:41.684535Z","steps":["trace[1554873973] 'process raft request' (duration: 1.827862403s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.684553Z","caller":"traceutil/trace.go:171","msg":"trace[1926887741] transaction","detail":"{read_only:false; response_revision:21957; number_of_response:1; }","duration":"1.514691121s","start":"2026-08-24T08:12:40.169829Z","end":"2026-08-24T08:12:41.68452Z","steps":["trace[1926887741] 'process raft request' (duration: 1.514270073s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.68462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.856162Z","time spent":"1.828420785s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:41.684689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209754587s","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-08-24T08:12:41.684708Z","caller":"traceutil/trace.go:171","msg":"trace[718851996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21957; }","duration":"1.209792908s","start":"2026-08-24T08:12:40.474909Z","end":"2026-08-24T08:12:41.684702Z","steps":["trace[718851996] 'agreement among raft nodes before linearized reading' (duration: 1.209706876s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.684741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.474898Z","time spent":"1.209838689s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T08:12:41.684738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.169803Z","time spent":"1.514842035s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T08:12:41.68478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.140180206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"info","ts":"2026-08-24T08:12:41.684798Z","caller":"traceutil/trace.go:171","msg":"trace[1143069482] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21957; }","duration":"2.140210278s","start":"2026-08-24T08:12:39.544583Z","end":"2026-08-24T08:12:41.684793Z","steps":["trace[1143069482] 'agreement among raft nodes before linearized reading' (duration: 2.140134346s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.684811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.544577Z","time spent":"2.140231888s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5162,"request content":"key:\"/registry/jobs/openstack/octavia-db-sync\" "} {"level":"warn","ts":"2026-08-24T08:12:41.684826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.141440923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"info","ts":"2026-08-24T08:12:41.684845Z","caller":"traceutil/trace.go:171","msg":"trace[1807978119] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21957; }","duration":"2.141470324s","start":"2026-08-24T08:12:39.543369Z","end":"2026-08-24T08:12:41.684839Z","steps":["trace[1807978119] 'agreement among raft nodes before linearized reading' (duration: 2.141415063s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.684895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.543365Z","time spent":"2.141525865s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5162,"request content":"key:\"/registry/jobs/openstack/octavia-db-sync\" "} {"level":"warn","ts":"2026-08-24T08:12:41.684929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217963243s","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-08-24T08:12:41.684952Z","caller":"traceutil/trace.go:171","msg":"trace[1174045198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21957; }","duration":"1.218030895s","start":"2026-08-24T08:12:40.466915Z","end":"2026-08-24T08:12:41.684946Z","steps":["trace[1174045198] 'agreement among raft nodes before linearized reading' (duration: 1.217974614s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.68497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.466895Z","time spent":"1.218070645s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T08:12:41.684603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206401539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.685063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227022636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T08:12:41.685083Z","caller":"traceutil/trace.go:171","msg":"trace[176119912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21957; }","duration":"1.227083647s","start":"2026-08-24T08:12:40.457992Z","end":"2026-08-24T08:12:41.685076Z","steps":["trace[176119912] 'agreement among raft nodes before linearized reading' (duration: 1.227029816s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.6851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.457966Z","time spent":"1.227129149s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T08:12:41.685202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228138579s","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-08-24T08:12:41.685227Z","caller":"traceutil/trace.go:171","msg":"trace[1761373075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21957; }","duration":"1.228182789s","start":"2026-08-24T08:12:40.457039Z","end":"2026-08-24T08:12:41.685221Z","steps":["trace[1761373075] 'agreement among raft nodes before linearized reading' (duration: 1.228126278s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.685244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.457028Z","time spent":"1.22821102s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T08:12:41.685435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229512506s","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-08-24T08:12:41.685497Z","caller":"traceutil/trace.go:171","msg":"trace[1112595147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21957; }","duration":"1.229574417s","start":"2026-08-24T08:12:40.455908Z","end":"2026-08-24T08:12:41.685482Z","steps":["trace[1112595147] 'agreement among raft nodes before linearized reading' (duration: 1.229467525s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.68554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.455852Z","time spent":"1.229676959s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T08:12:41.6856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65048998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.685666Z","caller":"traceutil/trace.go:171","msg":"trace[1078319612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21957; }","duration":"1.650597443s","start":"2026-08-24T08:12:40.035052Z","end":"2026-08-24T08:12:41.68565Z","steps":["trace[1078319612] 'agreement among raft nodes before linearized reading' (duration: 1.650491041s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.685715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.035031Z","time spent":"1.650674055s","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T08:12:41.685798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719408147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T08:12:41.685866Z","caller":"traceutil/trace.go:171","msg":"trace[267158083] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:21957; }","duration":"1.719516389s","start":"2026-08-24T08:12:39.966328Z","end":"2026-08-24T08:12:41.685844Z","steps":["trace[267158083] 'agreement among raft nodes before linearized reading' (duration: 1.719390016s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.68583Z","caller":"traceutil/trace.go:171","msg":"trace[1886346799] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:21957; }","duration":"1.206888988s","start":"2026-08-24T08:12:40.478183Z","end":"2026-08-24T08:12:41.685072Z","steps":["trace[1886346799] 'agreement among raft nodes before linearized reading' (duration: 1.206401089s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.685922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.966308Z","time spent":"1.719599621s","remote":"127.0.0.1:51790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":30,"response size":32,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-08-24T08:12:41.686076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.478155Z","time spent":"1.207856858s","remote":"127.0.0.1:54234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-24T08:12:41.686102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255650265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"info","ts":"2026-08-24T08:12:41.686158Z","caller":"traceutil/trace.go:171","msg":"trace[1625363128] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21957; }","duration":"1.255742898s","start":"2026-08-24T08:12:40.430397Z","end":"2026-08-24T08:12:41.68614Z","steps":["trace[1625363128] 'agreement among raft nodes before linearized reading' (duration: 1.255566204s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.686214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.430379Z","time spent":"1.255822679s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5162,"request content":"key:\"/registry/jobs/openstack/octavia-db-sync\" "} {"level":"warn","ts":"2026-08-24T08:12:41.686346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274692411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.684983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.142344861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"info","ts":"2026-08-24T08:12:41.686405Z","caller":"traceutil/trace.go:171","msg":"trace[1820843527] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21957; }","duration":"2.14377098s","start":"2026-08-24T08:12:39.542621Z","end":"2026-08-24T08:12:41.686392Z","steps":["trace[1820843527] 'agreement among raft nodes before linearized reading' (duration: 2.142322391s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.686455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.542614Z","time spent":"2.143828111s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5162,"request content":"key:\"/registry/jobs/openstack/octavia-db-sync\" "} {"level":"warn","ts":"2026-08-24T08:12:41.686527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.147650888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.686584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.143986074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.686598Z","caller":"traceutil/trace.go:171","msg":"trace[1909560170] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21957; }","duration":"2.14778047s","start":"2026-08-24T08:12:39.538802Z","end":"2026-08-24T08:12:41.686582Z","steps":["trace[1909560170] 'agreement among raft nodes before linearized reading' (duration: 2.147678708s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.686616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275042909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.686696Z","caller":"traceutil/trace.go:171","msg":"trace[1611833433] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21957; }","duration":"1.275150781s","start":"2026-08-24T08:12:40.41153Z","end":"2026-08-24T08:12:41.686681Z","steps":["trace[1611833433] 'agreement among raft nodes before linearized reading' (duration: 1.275042568s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.686702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.147532887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.686701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.145108637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.686721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.147879023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.686745Z","caller":"traceutil/trace.go:171","msg":"trace[447156201] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21957; }","duration":"2.147586537s","start":"2026-08-24T08:12:39.53915Z","end":"2026-08-24T08:12:41.686736Z","steps":["trace[447156201] 'agreement among raft nodes before linearized reading' (duration: 2.147530776s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.686652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.144990734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.686763Z","caller":"traceutil/trace.go:171","msg":"trace[131746248] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21957; }","duration":"2.147923824s","start":"2026-08-24T08:12:39.538829Z","end":"2026-08-24T08:12:41.686752Z","steps":["trace[131746248] 'agreement among raft nodes before linearized reading' (duration: 2.147843852s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.686767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311443157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T08:12:41.686702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.148292661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-24T08:12:41.686778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.539144Z","time spent":"2.147626558s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"warn","ts":"2026-08-24T08:12:41.686803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.538822Z","time spent":"2.147971575s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" "} {"level":"info","ts":"2026-08-24T08:12:41.686811Z","caller":"traceutil/trace.go:171","msg":"trace[1765335741] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21957; }","duration":"2.145176528s","start":"2026-08-24T08:12:39.541612Z","end":"2026-08-24T08:12:41.686788Z","steps":["trace[1765335741] 'agreement among raft nodes before linearized reading' (duration: 2.144907652s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.686791Z","caller":"traceutil/trace.go:171","msg":"trace[2094457814] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21957; }","duration":"1.275150391s","start":"2026-08-24T08:12:40.411628Z","end":"2026-08-24T08:12:41.686779Z","steps":["trace[2094457814] 'agreement among raft nodes before linearized reading' (duration: 1.274683331s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.686839Z","caller":"traceutil/trace.go:171","msg":"trace[193070726] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21957; }","duration":"2.148423234s","start":"2026-08-24T08:12:39.538384Z","end":"2026-08-24T08:12:41.686807Z","steps":["trace[193070726] 'agreement among raft nodes before linearized reading' (duration: 2.148191608s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.686751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.411517Z","time spent":"1.275221533s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"warn","ts":"2026-08-24T08:12:41.68686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.541608Z","time spent":"2.14524265s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" "} {"level":"warn","ts":"2026-08-24T08:12:41.686883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.538366Z","time spent":"2.148506555s","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-24T08:12:41.686879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.411623Z","time spent":"1.275228003s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" "} {"level":"warn","ts":"2026-08-24T08:12:41.686646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.538758Z","time spent":"2.147877983s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"info","ts":"2026-08-24T08:12:41.686759Z","caller":"traceutil/trace.go:171","msg":"trace[2031506731] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21957; }","duration":"2.145175369s","start":"2026-08-24T08:12:39.541572Z","end":"2026-08-24T08:12:41.686748Z","steps":["trace[2031506731] 'agreement among raft nodes before linearized reading' (duration: 2.145098407s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.686979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.541566Z","time spent":"2.145404553s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" "} {"level":"info","ts":"2026-08-24T08:12:41.686645Z","caller":"traceutil/trace.go:171","msg":"trace[541011572] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21957; }","duration":"2.144064116s","start":"2026-08-24T08:12:39.542562Z","end":"2026-08-24T08:12:41.686626Z","steps":["trace[541011572] 'agreement among raft nodes before linearized reading' (duration: 2.143975693s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.687006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.149625329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-24T08:12:41.687037Z","caller":"traceutil/trace.go:171","msg":"trace[1164856028] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21957; }","duration":"2.149662559s","start":"2026-08-24T08:12:39.537364Z","end":"2026-08-24T08:12:41.687027Z","steps":["trace[1164856028] 'agreement among raft nodes before linearized reading' (duration: 2.149551837s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.687071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.53736Z","time spent":"2.149705061s","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-24T08:12:41.687104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.142882701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-24T08:12:41.687068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.542551Z","time spent":"2.144477474s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"warn","ts":"2026-08-24T08:12:41.686887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.149480626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T08:12:41.686817Z","caller":"traceutil/trace.go:171","msg":"trace[1519738865] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21957; }","duration":"1.311537239s","start":"2026-08-24T08:12:40.375262Z","end":"2026-08-24T08:12:41.686799Z","steps":["trace[1519738865] 'agreement among raft nodes before linearized reading' (duration: 1.311466877s)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:12:41.687255Z","caller":"traceutil/trace.go:171","msg":"trace[1833674205] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21957; }","duration":"2.149880743s","start":"2026-08-24T08:12:39.537362Z","end":"2026-08-24T08:12:41.687243Z","steps":["trace[1833674205] 'agreement among raft nodes before linearized reading' (duration: 2.149506286s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.687276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:40.375234Z","time spent":"1.312030368s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" "} {"level":"info","ts":"2026-08-24T08:12:41.687142Z","caller":"traceutil/trace.go:171","msg":"trace[1789409301] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:21957; }","duration":"2.144564186s","start":"2026-08-24T08:12:39.542567Z","end":"2026-08-24T08:12:41.687132Z","steps":["trace[1789409301] 'agreement among raft nodes before linearized reading' (duration: 2.142655937s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.687329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.542557Z","time spent":"2.14476515s","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-24T08:12:41.687298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.537342Z","time spent":"2.149945175s","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" "} {"level":"warn","ts":"2026-08-24T08:12:41.687818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.894938561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:30 size:266922"} {"level":"info","ts":"2026-08-24T08:12:41.687879Z","caller":"traceutil/trace.go:171","msg":"trace[1769231078] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:30; response_revision:21957; }","duration":"1.895021753s","start":"2026-08-24T08:12:39.792843Z","end":"2026-08-24T08:12:41.687865Z","steps":["trace[1769231078] 'agreement among raft nodes before linearized reading' (duration: 1.893280017s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:12:41.687922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T08:12:39.792792Z","time spent":"1.895118855s","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":30,"response size":266946,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-08-24T08:12:50.827753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19916} {"level":"info","ts":"2026-08-24T08:12:50.937257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19916,"took":"105.881014ms","hash":2318224257,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":30306304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T08:12:50.937332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2318224257,"revision":19916,"compact-revision":17481} {"level":"info","ts":"2026-08-24T08:13:13.820805Z","caller":"traceutil/trace.go:171","msg":"trace[946531408] linearizableReadLoop","detail":"{readStateIndex:22917; appliedIndex:22916; }","duration":"253.936821ms","start":"2026-08-24T08:13:13.566846Z","end":"2026-08-24T08:13:13.820783Z","steps":["trace[946531408] 'read index received' (duration: 253.719157ms)","trace[946531408] 'applied index is now lower than readState.Index' (duration: 216.824µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:13:13.820941Z","caller":"traceutil/trace.go:171","msg":"trace[632758518] transaction","detail":"{read_only:false; response_revision:22172; number_of_response:1; }","duration":"258.953933ms","start":"2026-08-24T08:13:13.561956Z","end":"2026-08-24T08:13:13.82091Z","steps":["trace[632758518] 'process raft request' (duration: 258.607766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:13.82111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.252928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-24T08:13:13.821176Z","caller":"traceutil/trace.go:171","msg":"trace[1482777620] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:22172; }","duration":"254.374431ms","start":"2026-08-24T08:13:13.566788Z","end":"2026-08-24T08:13:13.821162Z","steps":["trace[1482777620] 'agreement among raft nodes before linearized reading' (duration: 254.135855ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:13:14.076713Z","caller":"traceutil/trace.go:171","msg":"trace[1939362628] linearizableReadLoop","detail":"{readStateIndex:22921; appliedIndex:22920; }","duration":"204.747566ms","start":"2026-08-24T08:13:13.871927Z","end":"2026-08-24T08:13:14.076675Z","steps":["trace[1939362628] 'read index received' (duration: 203.49537ms)","trace[1939362628] 'applied index is now lower than readState.Index' (duration: 1.249766ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:13:14.076804Z","caller":"traceutil/trace.go:171","msg":"trace[1568035401] transaction","detail":"{read_only:false; response_revision:22176; number_of_response:1; }","duration":"218.057735ms","start":"2026-08-24T08:13:13.858705Z","end":"2026-08-24T08:13:14.076763Z","steps":["trace[1568035401] 'process raft request' (duration: 216.767019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:14.077006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.066022ms","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-08-24T08:13:14.077092Z","caller":"traceutil/trace.go:171","msg":"trace[1883232566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22176; }","duration":"205.192954ms","start":"2026-08-24T08:13:13.871879Z","end":"2026-08-24T08:13:14.077072Z","steps":["trace[1883232566] 'agreement among raft nodes before linearized reading' (duration: 204.97502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:14.077427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.316577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-24T08:13:14.077483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.566031ms","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-08-24T08:13:14.077618Z","caller":"traceutil/trace.go:171","msg":"trace[449716886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22176; }","duration":"204.588223ms","start":"2026-08-24T08:13:13.873012Z","end":"2026-08-24T08:13:14.077601Z","steps":["trace[449716886] 'agreement among raft nodes before linearized reading' (duration: 204.162725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:14.077314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.199825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-24T08:13:14.077636Z","caller":"traceutil/trace.go:171","msg":"trace[1503672772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22176; }","duration":"198.749224ms","start":"2026-08-24T08:13:13.878865Z","end":"2026-08-24T08:13:14.077615Z","steps":["trace[1503672772] 'agreement among raft nodes before linearized reading' (duration: 198.418628ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:13:14.077775Z","caller":"traceutil/trace.go:171","msg":"trace[971143439] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:22176; }","duration":"162.714265ms","start":"2026-08-24T08:13:13.915035Z","end":"2026-08-24T08:13:14.07775Z","steps":["trace[971143439] 'agreement among raft nodes before linearized reading' (duration: 162.136503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:13:14.079469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.574673ms","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-08-24T08:13:14.079544Z","caller":"traceutil/trace.go:171","msg":"trace[643027116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22176; }","duration":"202.683325ms","start":"2026-08-24T08:13:13.876842Z","end":"2026-08-24T08:13:14.079525Z","steps":["trace[643027116] 'agreement among raft nodes before linearized reading' (duration: 200.510141ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:15:35.676739Z","caller":"traceutil/trace.go:171","msg":"trace[2035334694] transaction","detail":"{read_only:false; response_revision:23848; number_of_response:1; }","duration":"192.999959ms","start":"2026-08-24T08:15:35.483712Z","end":"2026-08-24T08:15:35.676712Z","steps":["trace[2035334694] 'process raft request' (duration: 192.855605ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:17:50.833567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22030} {"level":"info","ts":"2026-08-24T08:17:50.945401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22030,"took":"108.860205ms","hash":247822972,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":44191744,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-24T08:17:50.94546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":247822972,"revision":22030,"compact-revision":19916} {"level":"info","ts":"2026-08-24T08:19:53.576427Z","caller":"traceutil/trace.go:171","msg":"trace[1068695807] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"137.56323ms","start":"2026-08-24T08:19:53.438839Z","end":"2026-08-24T08:19:53.576402Z","steps":["trace[1068695807] 'process raft request' (duration: 137.516149ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:19:53.576648Z","caller":"traceutil/trace.go:171","msg":"trace[101873982] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"138.795176ms","start":"2026-08-24T08:19:53.43782Z","end":"2026-08-24T08:19:53.576615Z","steps":["trace[101873982] 'process raft request' (duration: 99.687007ms)","trace[101873982] 'compare' (duration: 38.570738ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:19:53.57669Z","caller":"traceutil/trace.go:171","msg":"trace[91389573] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"138.067631ms","start":"2026-08-24T08:19:53.438591Z","end":"2026-08-24T08:19:53.576659Z","steps":["trace[91389573] 'process raft request' (duration: 137.712524ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T08:20:08.950357Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006991.wal"} {"level":"info","ts":"2026-08-24T08:22:50.840011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25137} {"level":"info","ts":"2026-08-24T08:22:50.978997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25137,"took":"135.660042ms","hash":18579250,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T08:22:50.979063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":18579250,"revision":25137,"compact-revision":22030} {"level":"warn","ts":"2026-08-24T08:23:57.977033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.208743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T08:23:57.97717Z","caller":"traceutil/trace.go:171","msg":"trace[1989833369] linearizableReadLoop","detail":"{readStateIndex:28754; appliedIndex:28753; }","duration":"179.364535ms","start":"2026-08-24T08:23:57.797788Z","end":"2026-08-24T08:23:57.977152Z","steps":["trace[1989833369] 'read index received' (duration: 18.827245ms)","trace[1989833369] 'applied index is now lower than readState.Index' (duration: 160.53551ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T08:23:57.977269Z","caller":"traceutil/trace.go:171","msg":"trace[1070115377] transaction","detail":"{read_only:false; response_revision:27856; number_of_response:1; }","duration":"197.724201ms","start":"2026-08-24T08:23:57.779514Z","end":"2026-08-24T08:23:57.977238Z","steps":["trace[1070115377] 'process raft request' (duration: 37.198371ms)","trace[1070115377] 'compare' (duration: 159.969828ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T08:23:57.977282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.478327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-08-24T08:23:57.977485Z","caller":"traceutil/trace.go:171","msg":"trace[33486142] range","detail":"{range_begin:/registry/rolebindings/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:27856; }","duration":"179.699792ms","start":"2026-08-24T08:23:57.797755Z","end":"2026-08-24T08:23:57.977455Z","steps":["trace[33486142] 'agreement among raft nodes before linearized reading' (duration: 179.463177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T08:23:57.977559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.679227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/rabbitmq-neutron-server\" ","response":"range_response_count:1 size:904"} {"level":"info","ts":"2026-08-24T08:23:57.97768Z","caller":"traceutil/trace.go:171","msg":"trace[1062525975] range","detail":"{range_begin:/registry/rolebindings/openstack/rabbitmq-neutron-server; range_end:; response_count:1; response_revision:27856; }","duration":"168.8687ms","start":"2026-08-24T08:23:57.808795Z","end":"2026-08-24T08:23:57.977664Z","steps":["trace[1062525975] 'agreement among raft nodes before linearized reading' (duration: 168.483302ms)"],"step_count":1}