{"level":"warn","ts":"2026-10-15T06:30:06.665522Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T06:30:06.665638Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.58: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.58:2380","--initial-cluster=instance=https://199.204.45.58:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.58:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.58:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T06:30:06.665714Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T06:30:06.665729Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.58:2380"]} {"level":"info","ts":"2026-10-15T06:30:06.665753Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T06:30:06.666509Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.58:2379"]} {"level":"info","ts":"2026-10-15T06:30:06.666603Z","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.58:2380"],"listen-peer-urls":["https://199.204.45.58:2380"],"advertise-client-urls":["https://199.204.45.58:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.58:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.58:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T06:30:06.671872Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.994594ms"} {"level":"info","ts":"2026-10-15T06:30:06.678104Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd81c1a075562f5","cluster-id":"c68f6709fa617676"} {"level":"info","ts":"2026-10-15T06:30:06.678228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T06:30:06.678264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 became follower at term 0"} {"level":"info","ts":"2026-10-15T06:30:06.678278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd81c1a075562f5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T06:30:06.678289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 became follower at term 1"} {"level":"info","ts":"2026-10-15T06:30:06.678341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 switched to configuration voters=(11229756568966095605)"} {"level":"warn","ts":"2026-10-15T06:30:06.691349Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T06:30:06.693159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T06:30:06.694497Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-15T06:30:06.697454Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd81c1a075562f5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T06:30:06.697714Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd81c1a075562f5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T06:30:06.697897Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T06:30:06.697974Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T06:30:06.697993Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T06:30:06.70051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 switched to configuration voters=(11229756568966095605)"} {"level":"info","ts":"2026-10-15T06:30:06.701469Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c68f6709fa617676","local-member-id":"9bd81c1a075562f5","added-peer-id":"9bd81c1a075562f5","added-peer-peer-urls":["https://199.204.45.58:2380"]} {"level":"info","ts":"2026-10-15T06:30:06.704149Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T06:30:06.704291Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.58:2380"} {"level":"info","ts":"2026-10-15T06:30:06.704372Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.58:2380"} {"level":"info","ts":"2026-10-15T06:30:06.704534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd81c1a075562f5","initial-advertise-peer-urls":["https://199.204.45.58:2380"],"listen-peer-urls":["https://199.204.45.58:2380"],"advertise-client-urls":["https://199.204.45.58:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.58:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T06:30:06.704574Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T06:30:07.678697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T06:30:07.678775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T06:30:07.678819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 received MsgPreVoteResp from 9bd81c1a075562f5 at term 1"} {"level":"info","ts":"2026-10-15T06:30:07.67884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 became candidate at term 2"} {"level":"info","ts":"2026-10-15T06:30:07.678851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 received MsgVoteResp from 9bd81c1a075562f5 at term 2"} {"level":"info","ts":"2026-10-15T06:30:07.67887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd81c1a075562f5 became leader at term 2"} {"level":"info","ts":"2026-10-15T06:30:07.678884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd81c1a075562f5 elected leader 9bd81c1a075562f5 at term 2"} {"level":"info","ts":"2026-10-15T06:30:07.68022Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd81c1a075562f5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.58:2379]}","request-path":"/0/members/9bd81c1a075562f5/attributes","cluster-id":"c68f6709fa617676","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T06:30:07.68024Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:30:07.680281Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T06:30:07.680329Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T06:30:07.680557Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T06:30:07.680675Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T06:30:07.682303Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c68f6709fa617676","local-member-id":"9bd81c1a075562f5","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:30:07.682529Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:30:07.682604Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:30:07.684113Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T06:30:07.68422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.58:2379"} {"level":"info","ts":"2026-10-15T06:30:25.342921Z","caller":"traceutil/trace.go:171","msg":"trace[1237307169] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"263.852754ms","start":"2026-10-15T06:30:25.07904Z","end":"2026-10-15T06:30:25.342893Z","steps":["trace[1237307169] 'process raft request' (duration: 263.65822ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:31:01.934696Z","caller":"traceutil/trace.go:171","msg":"trace[1410293789] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"138.981587ms","start":"2026-10-15T06:31:01.795684Z","end":"2026-10-15T06:31:01.934666Z","steps":["trace[1410293789] 'process raft request' (duration: 138.778763ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:31:02.319082Z","caller":"traceutil/trace.go:171","msg":"trace[922756109] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"245.468506ms","start":"2026-10-15T06:31:02.073578Z","end":"2026-10-15T06:31:02.319047Z","steps":["trace[922756109] 'read index received' (duration: 244.309179ms)","trace[922756109] 'applied index is now lower than readState.Index' (duration: 1.157597ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:31:02.319196Z","caller":"traceutil/trace.go:171","msg":"trace[1990111071] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"265.779544ms","start":"2026-10-15T06:31:02.053363Z","end":"2026-10-15T06:31:02.319142Z","steps":["trace[1990111071] 'process raft request' (duration: 264.952085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:31:02.319416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.667258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:31:02.319519Z","caller":"traceutil/trace.go:171","msg":"trace[627611100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"214.930994ms","start":"2026-10-15T06:31:02.104568Z","end":"2026-10-15T06:31:02.319499Z","steps":["trace[627611100] 'agreement among raft nodes before linearized reading' (duration: 214.677579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:31:02.319461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.870096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g84z2\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-15T06:31:02.319669Z","caller":"traceutil/trace.go:171","msg":"trace[1087794802] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g84z2; range_end:; response_count:1; response_revision:658; }","duration":"246.092011ms","start":"2026-10-15T06:31:02.073519Z","end":"2026-10-15T06:31:02.319611Z","steps":["trace[1087794802] 'agreement among raft nodes before linearized reading' (duration: 245.722572ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:31:02.95434Z","caller":"traceutil/trace.go:171","msg":"trace[762824182] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"610.823012ms","start":"2026-10-15T06:31:02.34344Z","end":"2026-10-15T06:31:02.954263Z","steps":["trace[762824182] 'process raft request' (duration: 514.957366ms)","trace[762824182] 'compare' (duration: 95.246691ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:31:02.954472Z","caller":"traceutil/trace.go:171","msg":"trace[663497482] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"551.501617ms","start":"2026-10-15T06:31:02.402913Z","end":"2026-10-15T06:31:02.954414Z","steps":["trace[663497482] 'process raft request' (duration: 550.955184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:31:02.955284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:31:02.402889Z","time spent":"551.712761ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T06:31:02.955407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:31:02.343351Z","time spent":"611.140059ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:31:04.43695Z","caller":"traceutil/trace.go:171","msg":"trace[1636358644] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"137.79598ms","start":"2026-10-15T06:31:04.299104Z","end":"2026-10-15T06:31:04.4369Z","steps":["trace[1636358644] 'process raft request' (duration: 103.93106ms)","trace[1636358644] 'compare' (duration: 33.511991ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:31:16.212591Z","caller":"traceutil/trace.go:171","msg":"trace[187996494] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"171.585157ms","start":"2026-10-15T06:31:16.040978Z","end":"2026-10-15T06:31:16.212563Z","steps":["trace[187996494] 'read index received' (duration: 171.338272ms)","trace[187996494] 'applied index is now lower than readState.Index' (duration: 245.855µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:31:16.212724Z","caller":"traceutil/trace.go:171","msg":"trace[2031057081] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"201.382513ms","start":"2026-10-15T06:31:16.01129Z","end":"2026-10-15T06:31:16.212673Z","steps":["trace[2031057081] 'process raft request' (duration: 201.121067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:31:16.212835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.834533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T06:31:16.212885Z","caller":"traceutil/trace.go:171","msg":"trace[790856895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"171.922445ms","start":"2026-10-15T06:31:16.040949Z","end":"2026-10-15T06:31:16.212872Z","steps":["trace[790856895] 'agreement among raft nodes before linearized reading' (duration: 171.765862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:31:16.213179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.780602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-15T06:31:16.213319Z","caller":"traceutil/trace.go:171","msg":"trace[141131435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"159.027448ms","start":"2026-10-15T06:31:16.054264Z","end":"2026-10-15T06:31:16.213292Z","steps":["trace[141131435] 'agreement among raft nodes before linearized reading' (duration: 158.605938ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:31:19.872992Z","caller":"traceutil/trace.go:171","msg":"trace[368066606] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"141.260149ms","start":"2026-10-15T06:31:19.731699Z","end":"2026-10-15T06:31:19.872959Z","steps":["trace[368066606] 'process raft request' (duration: 141.045555ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:31:49.236038Z","caller":"traceutil/trace.go:171","msg":"trace[591769013] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"206.141605ms","start":"2026-10-15T06:31:49.02987Z","end":"2026-10-15T06:31:49.236011Z","steps":["trace[591769013] 'read index received' (duration: 205.89076ms)","trace[591769013] 'applied index is now lower than readState.Index' (duration: 249.555µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:31:49.236153Z","caller":"traceutil/trace.go:171","msg":"trace[311179135] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"230.403953ms","start":"2026-10-15T06:31:49.005696Z","end":"2026-10-15T06:31:49.2361Z","steps":["trace[311179135] 'process raft request' (duration: 230.136257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:31:49.236278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.359049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:31:49.236346Z","caller":"traceutil/trace.go:171","msg":"trace[717026906] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1230; }","duration":"206.470362ms","start":"2026-10-15T06:31:49.029859Z","end":"2026-10-15T06:31:49.236329Z","steps":["trace[717026906] 'agreement among raft nodes before linearized reading' (duration: 206.322539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:31:49.23659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.028961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:31:49.236696Z","caller":"traceutil/trace.go:171","msg":"trace[403459109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"131.231085ms","start":"2026-10-15T06:31:49.105442Z","end":"2026-10-15T06:31:49.236674Z","steps":["trace[403459109] 'agreement among raft nodes before linearized reading' (duration: 130.888958ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:32:39.952377Z","caller":"traceutil/trace.go:171","msg":"trace[687459466] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"257.773468ms","start":"2026-10-15T06:32:39.69454Z","end":"2026-10-15T06:32:39.952314Z","steps":["trace[687459466] 'read index received' (duration: 257.631175ms)","trace[687459466] 'applied index is now lower than readState.Index' (duration: 140.373µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:32:39.952494Z","caller":"traceutil/trace.go:171","msg":"trace[574505387] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"307.95779ms","start":"2026-10-15T06:32:39.644503Z","end":"2026-10-15T06:32:39.952461Z","steps":["trace[574505387] 'process raft request' (duration: 307.588992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:32:39.952768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.184218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T06:32:39.952823Z","caller":"traceutil/trace.go:171","msg":"trace[216334712] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1905; }","duration":"258.352472ms","start":"2026-10-15T06:32:39.694453Z","end":"2026-10-15T06:32:39.952806Z","steps":["trace[216334712] 'agreement among raft nodes before linearized reading' (duration: 258.095595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:32:39.952882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:32:39.644482Z","time spent":"308.094033ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:32:55.759184Z","caller":"traceutil/trace.go:171","msg":"trace[972062630] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2118; }","duration":"116.602753ms","start":"2026-10-15T06:32:55.642556Z","end":"2026-10-15T06:32:55.759158Z","steps":["trace[972062630] 'read index received' (duration: 116.359726ms)","trace[972062630] 'applied index is now lower than readState.Index' (duration: 242.026µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:32:55.759219Z","caller":"traceutil/trace.go:171","msg":"trace[2015184762] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"126.194736ms","start":"2026-10-15T06:32:55.633001Z","end":"2026-10-15T06:32:55.759196Z","steps":["trace[2015184762] 'process raft request' (duration: 125.949481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:32:55.75946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.882198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T06:32:55.759536Z","caller":"traceutil/trace.go:171","msg":"trace[2107002427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2041; }","duration":"117.029091ms","start":"2026-10-15T06:32:55.642492Z","end":"2026-10-15T06:32:55.759521Z","steps":["trace[2107002427] 'agreement among raft nodes before linearized reading' (duration: 116.776446ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:33:30.91391Z","caller":"traceutil/trace.go:171","msg":"trace[1053875854] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"106.216659ms","start":"2026-10-15T06:33:30.80762Z","end":"2026-10-15T06:33:30.913836Z","steps":["trace[1053875854] 'process raft request' (duration: 106.007515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:34:53.790647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.625167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T06:34:53.790769Z","caller":"traceutil/trace.go:171","msg":"trace[1203094101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2876; }","duration":"201.829881ms","start":"2026-10-15T06:34:53.588915Z","end":"2026-10-15T06:34:53.790744Z","steps":["trace[1203094101] 'range keys from in-memory index tree' (duration: 201.354779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:34:53.790661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.125406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T06:34:53.79087Z","caller":"traceutil/trace.go:171","msg":"trace[896305387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2876; }","duration":"194.406682ms","start":"2026-10-15T06:34:53.596445Z","end":"2026-10-15T06:34:53.790851Z","steps":["trace[896305387] 'range keys from in-memory index tree' (duration: 193.90521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:34:54.178731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.021876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:34:54.179183Z","caller":"traceutil/trace.go:171","msg":"trace[2140851949] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"176.372502ms","start":"2026-10-15T06:34:54.002768Z","end":"2026-10-15T06:34:54.179141Z","steps":["trace[2140851949] 'compare' (duration: 165.900703ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:34:54.421003Z","caller":"traceutil/trace.go:171","msg":"trace[1285279087] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"129.362903ms","start":"2026-10-15T06:34:54.291612Z","end":"2026-10-15T06:34:54.420974Z","steps":["trace[1285279087] 'process raft request' (duration: 129.21002ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:35:05.109164Z","caller":"traceutil/trace.go:171","msg":"trace[155600909] transaction","detail":"{read_only:false; response_revision:2940; number_of_response:1; }","duration":"116.19892ms","start":"2026-10-15T06:35:04.992903Z","end":"2026-10-15T06:35:05.109102Z","steps":["trace[155600909] 'process raft request' (duration: 115.912843ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:37:46.241199Z","caller":"traceutil/trace.go:171","msg":"trace[499620056] transaction","detail":"{read_only:false; response_revision:3910; number_of_response:1; }","duration":"110.027893ms","start":"2026-10-15T06:37:46.131145Z","end":"2026-10-15T06:37:46.241173Z","steps":["trace[499620056] 'process raft request' (duration: 109.977662ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:37:46.241262Z","caller":"traceutil/trace.go:171","msg":"trace[645272726] linearizableReadLoop","detail":"{readStateIndex:4061; appliedIndex:4060; }","duration":"110.872022ms","start":"2026-10-15T06:37:46.130311Z","end":"2026-10-15T06:37:46.241183Z","steps":["trace[645272726] 'read index received' (duration: 107.596568ms)","trace[645272726] 'applied index is now lower than readState.Index' (duration: 3.272564ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:37:46.241591Z","caller":"traceutil/trace.go:171","msg":"trace[1373715145] transaction","detail":"{read_only:false; response_revision:3909; number_of_response:1; }","duration":"111.079567ms","start":"2026-10-15T06:37:46.130276Z","end":"2026-10-15T06:37:46.241356Z","steps":["trace[1373715145] 'process raft request' (duration: 107.565618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:37:46.241741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.353795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/secretexports.secretgen.carvel.dev\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:37:46.24179Z","caller":"traceutil/trace.go:171","msg":"trace[1236020079] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/secretexports.secretgen.carvel.dev; range_end:; response_count:0; response_revision:3910; }","duration":"111.545498ms","start":"2026-10-15T06:37:46.13023Z","end":"2026-10-15T06:37:46.241775Z","steps":["trace[1236020079] 'agreement among raft nodes before linearized reading' (duration: 111.142268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:39:53.177434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.57756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-10-15T06:39:53.177582Z","caller":"traceutil/trace.go:171","msg":"trace[1086251989] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5358; }","duration":"164.774645ms","start":"2026-10-15T06:39:53.012761Z","end":"2026-10-15T06:39:53.177536Z","steps":["trace[1086251989] 'range keys from in-memory index tree' (duration: 164.342023ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:53.294786Z","caller":"traceutil/trace.go:171","msg":"trace[1416224390] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"100.495942ms","start":"2026-10-15T06:39:53.194097Z","end":"2026-10-15T06:39:53.294593Z","steps":["trace[1416224390] 'process raft request' (duration: 99.796655ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:55.640202Z","caller":"traceutil/trace.go:171","msg":"trace[630501499] linearizableReadLoop","detail":"{readStateIndex:5606; appliedIndex:5605; }","duration":"133.564619ms","start":"2026-10-15T06:39:55.506586Z","end":"2026-10-15T06:39:55.64015Z","steps":["trace[630501499] 'read index received' (duration: 92.324685ms)","trace[630501499] 'applied index is now lower than readState.Index' (duration: 41.238854ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:39:55.640395Z","caller":"traceutil/trace.go:171","msg":"trace[1685216347] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"151.127121ms","start":"2026-10-15T06:39:55.489189Z","end":"2026-10-15T06:39:55.640316Z","steps":["trace[1685216347] 'process raft request' (duration: 109.739784ms)","trace[1685216347] 'compare' (duration: 41.06847ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:39:55.640563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.953208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"info","ts":"2026-10-15T06:39:55.640607Z","caller":"traceutil/trace.go:171","msg":"trace[70277047] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5398; }","duration":"134.06936ms","start":"2026-10-15T06:39:55.506526Z","end":"2026-10-15T06:39:55.640595Z","steps":["trace[70277047] 'agreement among raft nodes before linearized reading' (duration: 133.844415ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:07.71387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2955} {"level":"info","ts":"2026-10-15T06:40:07.791427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2955,"took":"76.115944ms","hash":1943898159,"current-db-size-bytes":55058432,"current-db-size":"55 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T06:40:07.791511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1943898159,"revision":2955,"compact-revision":-1} {"level":"info","ts":"2026-10-15T06:40:09.548582Z","caller":"traceutil/trace.go:171","msg":"trace[706166850] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"137.48981ms","start":"2026-10-15T06:40:09.411056Z","end":"2026-10-15T06:40:09.548545Z","steps":["trace[706166850] 'process raft request' (duration: 137.282184ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:09.565122Z","caller":"traceutil/trace.go:171","msg":"trace[1271337962] linearizableReadLoop","detail":"{readStateIndex:5852; appliedIndex:5851; }","duration":"134.824337ms","start":"2026-10-15T06:40:09.430271Z","end":"2026-10-15T06:40:09.565095Z","steps":["trace[1271337962] 'read index received' (duration: 118.825921ms)","trace[1271337962] 'applied index is now lower than readState.Index' (duration: 15.997356ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:40:09.565388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.123035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T06:40:09.565448Z","caller":"traceutil/trace.go:171","msg":"trace[414359150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5636; }","duration":"135.225307ms","start":"2026-10-15T06:40:09.430191Z","end":"2026-10-15T06:40:09.565417Z","steps":["trace[414359150] 'agreement among raft nodes before linearized reading' (duration: 135.008122ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:09.565839Z","caller":"traceutil/trace.go:171","msg":"trace[736518873] transaction","detail":"{read_only:false; response_revision:5636; number_of_response:1; }","duration":"150.009375ms","start":"2026-10-15T06:40:09.415812Z","end":"2026-10-15T06:40:09.565822Z","steps":["trace[736518873] 'process raft request' (duration: 149.130285ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:16.838578Z","caller":"traceutil/trace.go:171","msg":"trace[1840351286] transaction","detail":"{read_only:false; response_revision:5801; number_of_response:1; }","duration":"136.397534ms","start":"2026-10-15T06:40:16.702147Z","end":"2026-10-15T06:40:16.838544Z","steps":["trace[1840351286] 'process raft request' (duration: 135.937594ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:17.134764Z","caller":"traceutil/trace.go:171","msg":"trace[1889581359] transaction","detail":"{read_only:false; response_revision:5802; number_of_response:1; }","duration":"139.611548ms","start":"2026-10-15T06:40:16.995115Z","end":"2026-10-15T06:40:17.134726Z","steps":["trace[1889581359] 'process raft request' (duration: 139.400814ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:18.702723Z","caller":"traceutil/trace.go:171","msg":"trace[1124887939] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"123.826136ms","start":"2026-10-15T06:40:18.578864Z","end":"2026-10-15T06:40:18.70269Z","steps":["trace[1124887939] 'process raft request' (duration: 123.634241ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:07.723249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5597} {"level":"info","ts":"2026-10-15T06:45:07.821236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5597,"took":"96.012598ms","hash":311700456,"current-db-size-bytes":55058432,"current-db-size":"55 MB","current-db-size-in-use-bytes":29085696,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T06:45:07.821301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":311700456,"revision":5597,"compact-revision":2955} {"level":"info","ts":"2026-10-15T06:46:23.719298Z","caller":"traceutil/trace.go:171","msg":"trace[2101773039] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"309.902014ms","start":"2026-10-15T06:46:23.409379Z","end":"2026-10-15T06:46:23.719281Z","steps":["trace[2101773039] 'process raft request' (duration: 309.784171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:23.719429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:46:23.409362Z","time spent":"309.998497ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:46:23.964991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.895669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:46:23.965064Z","caller":"traceutil/trace.go:171","msg":"trace[863596851] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8640; }","duration":"174.024351ms","start":"2026-10-15T06:46:23.791023Z","end":"2026-10-15T06:46:23.965047Z","steps":["trace[863596851] 'count revisions from in-memory index tree' (duration: 173.815677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:23.965453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.485908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T06:46:23.965726Z","caller":"traceutil/trace.go:171","msg":"trace[93421696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8640; }","duration":"127.625322ms","start":"2026-10-15T06:46:23.837898Z","end":"2026-10-15T06:46:23.965523Z","steps":["trace[93421696] 'range keys from in-memory index tree' (duration: 127.313955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:23.965734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.144255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T06:46:23.965781Z","caller":"traceutil/trace.go:171","msg":"trace[438025141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8640; }","duration":"128.214876ms","start":"2026-10-15T06:46:23.837553Z","end":"2026-10-15T06:46:23.965768Z","steps":["trace[438025141] 'range keys from in-memory index tree' (duration: 127.896459ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:46:35.111355Z","caller":"traceutil/trace.go:171","msg":"trace[1312363093] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"187.303429ms","start":"2026-10-15T06:46:34.92403Z","end":"2026-10-15T06:46:35.111334Z","steps":["trace[1312363093] 'process raft request' (duration: 187.154755ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:46:35.242539Z","caller":"traceutil/trace.go:171","msg":"trace[25563205] linearizableReadLoop","detail":"{readStateIndex:9012; appliedIndex:9010; }","duration":"207.236538ms","start":"2026-10-15T06:46:35.035277Z","end":"2026-10-15T06:46:35.242514Z","steps":["trace[25563205] 'read index received' (duration: 75.90738ms)","trace[25563205] 'applied index is now lower than readState.Index' (duration: 131.328018ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:46:35.242717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.416682ms","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:7"} {"level":"info","ts":"2026-10-15T06:46:35.242715Z","caller":"traceutil/trace.go:171","msg":"trace[660188028] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"208.68003ms","start":"2026-10-15T06:46:35.033989Z","end":"2026-10-15T06:46:35.242669Z","steps":["trace[660188028] 'process raft request' (duration: 208.310402ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:46:35.242758Z","caller":"traceutil/trace.go:171","msg":"trace[572302724] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8701; }","duration":"207.512284ms","start":"2026-10-15T06:46:35.035233Z","end":"2026-10-15T06:46:35.242746Z","steps":["trace[572302724] 'agreement among raft nodes before linearized reading' (duration: 207.415632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:35.242844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.801067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T06:46:35.242884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.849434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:46:35.243131Z","caller":"traceutil/trace.go:171","msg":"trace[1866625991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8701; }","duration":"137.94272ms","start":"2026-10-15T06:46:35.104969Z","end":"2026-10-15T06:46:35.242912Z","steps":["trace[1866625991] 'agreement among raft nodes before linearized reading' (duration: 137.769436ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:46:35.243155Z","caller":"traceutil/trace.go:171","msg":"trace[1750702842] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8701; }","duration":"114.193182ms","start":"2026-10-15T06:46:35.128943Z","end":"2026-10-15T06:46:35.243137Z","steps":["trace[1750702842] 'agreement among raft nodes before linearized reading' (duration: 113.837964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:36.02092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.022817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:46:36.021036Z","caller":"traceutil/trace.go:171","msg":"trace[78513684] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8703; }","duration":"110.17786ms","start":"2026-10-15T06:46:35.910835Z","end":"2026-10-15T06:46:36.021013Z","steps":["trace[78513684] 'range keys from in-memory index tree' (duration: 109.893054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:36.689648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.229792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:46:36.690104Z","caller":"traceutil/trace.go:171","msg":"trace[135436528] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"638.041669ms","start":"2026-10-15T06:46:36.052009Z","end":"2026-10-15T06:46:36.690051Z","steps":["trace[135436528] 'process raft request' (duration: 232.283225ms)","trace[135436528] 'compare' (duration: 404.974455ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:46:36.690269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:46:36.051992Z","time spent":"638.185302ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:46:36.690289Z","caller":"traceutil/trace.go:171","msg":"trace[1710690232] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"636.576225ms","start":"2026-10-15T06:46:36.053671Z","end":"2026-10-15T06:46:36.690247Z","steps":["trace[1710690232] 'process raft request' (duration: 636.103404ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:46:36.690311Z","caller":"traceutil/trace.go:171","msg":"trace[1329442633] linearizableReadLoop","detail":"{readStateIndex:9017; appliedIndex:9014; }","duration":"628.271163ms","start":"2026-10-15T06:46:36.061984Z","end":"2026-10-15T06:46:36.690255Z","steps":["trace[1329442633] 'read index received' (duration: 69.585904ms)","trace[1329442633] 'applied index is now lower than readState.Index' (duration: 558.681379ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:46:36.690523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:46:36.053651Z","time spent":"636.732909ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:46:36.763019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.00265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:46:36.763139Z","caller":"traceutil/trace.go:171","msg":"trace[248389494] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8706; }","duration":"701.196155ms","start":"2026-10-15T06:46:36.06191Z","end":"2026-10-15T06:46:36.763106Z","steps":["trace[248389494] 'agreement among raft nodes before linearized reading' (duration: 628.437447ms)","trace[248389494] 'count revisions from in-memory index tree' (duration: 72.574063ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:46:36.763197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:46:36.061883Z","time spent":"701.297997ms","remote":"127.0.0.1:43224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-10-15T06:46:36.76337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.922074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T06:46:36.763469Z","caller":"traceutil/trace.go:171","msg":"trace[1797088251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8706; }","duration":"624.056817ms","start":"2026-10-15T06:46:36.139389Z","end":"2026-10-15T06:46:36.763446Z","steps":["trace[1797088251] 'agreement among raft nodes before linearized reading' (duration: 623.824082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:36.763509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.724225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T06:46:36.763551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:46:36.139373Z","time spent":"624.169209ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T06:46:36.763592Z","caller":"traceutil/trace.go:171","msg":"trace[1674292227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8707; }","duration":"658.876338ms","start":"2026-10-15T06:46:36.104695Z","end":"2026-10-15T06:46:36.763571Z","steps":["trace[1674292227] 'agreement among raft nodes before linearized reading' (duration: 658.709324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:36.763636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:46:36.104678Z","time spent":"658.94885ms","remote":"127.0.0.1:42788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T06:46:36.763822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.612563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:46:36.763904Z","caller":"traceutil/trace.go:171","msg":"trace[1482666249] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:8707; }","duration":"134.703535ms","start":"2026-10-15T06:46:36.629174Z","end":"2026-10-15T06:46:36.763878Z","steps":["trace[1482666249] 'agreement among raft nodes before linearized reading' (duration: 134.577542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:36.763904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.738092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:46:36.763969Z","caller":"traceutil/trace.go:171","msg":"trace[932115518] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8707; }","duration":"241.814564ms","start":"2026-10-15T06:46:36.522137Z","end":"2026-10-15T06:46:36.763952Z","steps":["trace[932115518] 'agreement among raft nodes before linearized reading' (duration: 241.6508ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:46:37.253719Z","caller":"traceutil/trace.go:171","msg":"trace[1724698256] transaction","detail":"{read_only:false; response_revision:8709; number_of_response:1; }","duration":"132.017583ms","start":"2026-10-15T06:46:37.12167Z","end":"2026-10-15T06:46:37.253688Z","steps":["trace[1724698256] 'process raft request' (duration: 131.719717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:37.584556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.713138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:46:37.58464Z","caller":"traceutil/trace.go:171","msg":"trace[6035030] linearizableReadLoop","detail":"{readStateIndex:9022; appliedIndex:9021; }","duration":"294.041458ms","start":"2026-10-15T06:46:37.290585Z","end":"2026-10-15T06:46:37.584627Z","steps":["trace[6035030] 'read index received' (duration: 152.054674ms)","trace[6035030] 'applied index is now lower than readState.Index' (duration: 141.985714ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:46:37.584823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.245553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T06:46:37.584862Z","caller":"traceutil/trace.go:171","msg":"trace[138898118] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8710; }","duration":"294.326865ms","start":"2026-10-15T06:46:37.29052Z","end":"2026-10-15T06:46:37.584847Z","steps":["trace[138898118] 'agreement among raft nodes before linearized reading' (duration: 294.186492ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:46:37.585068Z","caller":"traceutil/trace.go:171","msg":"trace[810886949] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"336.562388ms","start":"2026-10-15T06:46:37.248469Z","end":"2026-10-15T06:46:37.585031Z","steps":["trace[810886949] 'process raft request' (duration: 194.271367ms)","trace[810886949] 'compare' (duration: 141.611825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:46:37.585334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:46:37.24844Z","time spent":"336.704021ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:46:37.960956Z","caller":"traceutil/trace.go:171","msg":"trace[599844908] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"126.510546ms","start":"2026-10-15T06:46:37.834419Z","end":"2026-10-15T06:46:37.96093Z","steps":["trace[599844908] 'process raft request' (duration: 126.346112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:46:38.218061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.780249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:46:38.218158Z","caller":"traceutil/trace.go:171","msg":"trace[765456786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8712; }","duration":"112.937874ms","start":"2026-10-15T06:46:38.105196Z","end":"2026-10-15T06:46:38.218134Z","steps":["trace[765456786] 'range keys from in-memory index tree' (duration: 112.597655ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:07.559075Z","caller":"traceutil/trace.go:171","msg":"trace[559444271] linearizableReadLoop","detail":"{readStateIndex:9242; appliedIndex:9241; }","duration":"453.30896ms","start":"2026-10-15T06:47:07.105743Z","end":"2026-10-15T06:47:07.559052Z","steps":["trace[559444271] 'read index received' (duration: 453.114646ms)","trace[559444271] 'applied index is now lower than readState.Index' (duration: 193.404µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:47:07.559283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.529806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwngx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-15T06:47:07.55932Z","caller":"traceutil/trace.go:171","msg":"trace[976367155] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwngx; range_end:; response_count:1; response_revision:8922; }","duration":"453.603997ms","start":"2026-10-15T06:47:07.105707Z","end":"2026-10-15T06:47:07.559311Z","steps":["trace[976367155] 'agreement among raft nodes before linearized reading' (duration: 453.461974ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:07.559121Z","caller":"traceutil/trace.go:171","msg":"trace[1535226252] transaction","detail":"{read_only:false; response_revision:8922; number_of_response:1; }","duration":"504.187533ms","start":"2026-10-15T06:47:07.054911Z","end":"2026-10-15T06:47:07.559099Z","steps":["trace[1535226252] 'process raft request' (duration: 503.990599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:07.559384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.925926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T06:47:07.559448Z","caller":"traceutil/trace.go:171","msg":"trace[647027288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8922; }","duration":"104.024528ms","start":"2026-10-15T06:47:07.455409Z","end":"2026-10-15T06:47:07.559434Z","steps":["trace[647027288] 'agreement among raft nodes before linearized reading' (duration: 103.898166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:07.559352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:47:07.105692Z","time spent":"453.650108ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-zwngx\" "} {"level":"warn","ts":"2026-10-15T06:47:07.559277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.955569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:47:07.559818Z","caller":"traceutil/trace.go:171","msg":"trace[1911934175] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8922; }","duration":"367.540443ms","start":"2026-10-15T06:47:07.192262Z","end":"2026-10-15T06:47:07.559803Z","steps":["trace[1911934175] 'agreement among raft nodes before linearized reading' (duration: 366.96395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:07.559871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:47:07.192246Z","time spent":"367.607114ms","remote":"127.0.0.1:33484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-10-15T06:47:07.559776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:47:07.054895Z","time spent":"504.52948ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:47:07.884653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.049072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T06:47:07.88476Z","caller":"traceutil/trace.go:171","msg":"trace[1363191495] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8924; }","duration":"105.254786ms","start":"2026-10-15T06:47:07.77948Z","end":"2026-10-15T06:47:07.884735Z","steps":["trace[1363191495] 'range keys from in-memory index tree' (duration: 104.692134ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:09.063894Z","caller":"traceutil/trace.go:171","msg":"trace[497249550] transaction","detail":"{read_only:false; response_revision:8929; number_of_response:1; }","duration":"194.843593ms","start":"2026-10-15T06:47:08.86902Z","end":"2026-10-15T06:47:09.063864Z","steps":["trace[497249550] 'process raft request' (duration: 116.25883ms)","trace[497249550] 'compare' (duration: 78.377937ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:47:09.0642Z","caller":"traceutil/trace.go:171","msg":"trace[825730738] transaction","detail":"{read_only:false; response_revision:8930; number_of_response:1; }","duration":"170.329817ms","start":"2026-10-15T06:47:08.89367Z","end":"2026-10-15T06:47:09.063999Z","steps":["trace[825730738] 'process raft request' (duration: 170.143903ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:15.236678Z","caller":"traceutil/trace.go:171","msg":"trace[1574270415] linearizableReadLoop","detail":"{readStateIndex:9286; appliedIndex:9285; }","duration":"126.442295ms","start":"2026-10-15T06:47:15.110186Z","end":"2026-10-15T06:47:15.236628Z","steps":["trace[1574270415] 'read index received' (duration: 89.644247ms)","trace[1574270415] 'applied index is now lower than readState.Index' (duration: 36.795968ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:47:15.23674Z","caller":"traceutil/trace.go:171","msg":"trace[505519311] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"138.451771ms","start":"2026-10-15T06:47:15.098239Z","end":"2026-10-15T06:47:15.23669Z","steps":["trace[505519311] 'process raft request' (duration: 101.600312ms)","trace[505519311] 'compare' (duration: 36.531502ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:47:15.236778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.949813ms","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-10-15T06:47:15.236878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.67268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:47:15.23689Z","caller":"traceutil/trace.go:171","msg":"trace[1108396759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8964; }","duration":"139.089326ms","start":"2026-10-15T06:47:15.097773Z","end":"2026-10-15T06:47:15.236862Z","steps":["trace[1108396759] 'range keys from in-memory index tree' (duration: 138.752638ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:15.236935Z","caller":"traceutil/trace.go:171","msg":"trace[1475718415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8965; }","duration":"126.790592ms","start":"2026-10-15T06:47:15.110127Z","end":"2026-10-15T06:47:15.236917Z","steps":["trace[1475718415] 'agreement among raft nodes before linearized reading' (duration: 126.661759ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:16.107785Z","caller":"traceutil/trace.go:171","msg":"trace[146144638] transaction","detail":"{read_only:false; response_revision:8970; number_of_response:1; }","duration":"124.159881ms","start":"2026-10-15T06:47:15.983602Z","end":"2026-10-15T06:47:16.107762Z","steps":["trace[146144638] 'process raft request' (duration: 124.018848ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:16.417093Z","caller":"traceutil/trace.go:171","msg":"trace[1723587637] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"210.649065ms","start":"2026-10-15T06:47:16.206389Z","end":"2026-10-15T06:47:16.417038Z","steps":["trace[1723587637] 'process raft request' (duration: 190.760927ms)","trace[1723587637] 'compare' (duration: 19.53285ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:47:21.642851Z","caller":"traceutil/trace.go:171","msg":"trace[2064483594] transaction","detail":"{read_only:false; response_revision:9006; number_of_response:1; }","duration":"263.412022ms","start":"2026-10-15T06:47:21.3794Z","end":"2026-10-15T06:47:21.642812Z","steps":["trace[2064483594] 'process raft request' (duration: 215.304762ms)","trace[2064483594] 'compare' (duration: 47.323461ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:47:21.642927Z","caller":"traceutil/trace.go:171","msg":"trace[1684303547] linearizableReadLoop","detail":"{readStateIndex:9330; appliedIndex:9328; }","duration":"232.944141ms","start":"2026-10-15T06:47:21.409949Z","end":"2026-10-15T06:47:21.642893Z","steps":["trace[1684303547] 'read index received' (duration: 184.668997ms)","trace[1684303547] 'applied index is now lower than readState.Index' (duration: 48.273674ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:47:21.643266Z","caller":"traceutil/trace.go:171","msg":"trace[463578978] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"248.727505ms","start":"2026-10-15T06:47:21.394517Z","end":"2026-10-15T06:47:21.643244Z","steps":["trace[463578978] 'process raft request' (duration: 248.208253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:21.643819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.831161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-rqj4r\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-10-15T06:47:21.643939Z","caller":"traceutil/trace.go:171","msg":"trace[1825792459] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-rqj4r; range_end:; response_count:1; response_revision:9007; }","duration":"233.990674ms","start":"2026-10-15T06:47:21.409919Z","end":"2026-10-15T06:47:21.64391Z","steps":["trace[1825792459] 'agreement among raft nodes before linearized reading' (duration: 233.452732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:21.655566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.480147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:47:21.655621Z","caller":"traceutil/trace.go:171","msg":"trace[1680137646] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:9008; }","duration":"231.60151ms","start":"2026-10-15T06:47:21.424004Z","end":"2026-10-15T06:47:21.655605Z","steps":["trace[1680137646] 'agreement among raft nodes before linearized reading' (duration: 231.511408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:21.953188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.241106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:47:21.953288Z","caller":"traceutil/trace.go:171","msg":"trace[971441273] linearizableReadLoop","detail":"{readStateIndex:9333; appliedIndex:9332; }","duration":"280.707431ms","start":"2026-10-15T06:47:21.672563Z","end":"2026-10-15T06:47:21.953271Z","steps":["trace[971441273] 'read index received' (duration: 165.215559ms)","trace[971441273] 'applied index is now lower than readState.Index' (duration: 115.490592ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:47:21.953372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.814193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rqj4r\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-10-15T06:47:21.953403Z","caller":"traceutil/trace.go:171","msg":"trace[93657942] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rqj4r; range_end:; response_count:1; response_revision:9010; }","duration":"280.907976ms","start":"2026-10-15T06:47:21.672486Z","end":"2026-10-15T06:47:21.953394Z","steps":["trace[93657942] 'agreement among raft nodes before linearized reading' (duration: 280.849124ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:21.953378Z","caller":"traceutil/trace.go:171","msg":"trace[1001989385] transaction","detail":"{read_only:false; response_revision:9010; number_of_response:1; }","duration":"288.604773ms","start":"2026-10-15T06:47:21.664695Z","end":"2026-10-15T06:47:21.9533Z","steps":["trace[1001989385] 'process raft request' (duration: 173.162512ms)","trace[1001989385] 'compare' (duration: 114.96661ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:47:22.012552Z","caller":"traceutil/trace.go:171","msg":"trace[1492639741] transaction","detail":"{read_only:false; response_revision:9011; number_of_response:1; }","duration":"165.604559ms","start":"2026-10-15T06:47:21.84692Z","end":"2026-10-15T06:47:22.012524Z","steps":["trace[1492639741] 'process raft request' (duration: 165.144048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:22.012933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.533852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T06:47:22.013017Z","caller":"traceutil/trace.go:171","msg":"trace[1574761223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9011; }","duration":"298.687015ms","start":"2026-10-15T06:47:21.714311Z","end":"2026-10-15T06:47:22.012998Z","steps":["trace[1574761223] 'agreement among raft nodes before linearized reading' (duration: 298.45378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:22.013465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.485407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T06:47:22.013551Z","caller":"traceutil/trace.go:171","msg":"trace[576641553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9011; }","duration":"264.62115ms","start":"2026-10-15T06:47:21.748915Z","end":"2026-10-15T06:47:22.013536Z","steps":["trace[576641553] 'agreement among raft nodes before linearized reading' (duration: 264.432016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:22.013673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.663273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwngx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-15T06:47:22.013718Z","caller":"traceutil/trace.go:171","msg":"trace[454930228] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwngx; range_end:; response_count:1; response_revision:9011; }","duration":"258.738765ms","start":"2026-10-15T06:47:21.754969Z","end":"2026-10-15T06:47:22.013707Z","steps":["trace[454930228] 'agreement among raft nodes before linearized reading' (duration: 258.622242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:22.25302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.034406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:47:22.253209Z","caller":"traceutil/trace.go:171","msg":"trace[96867831] transaction","detail":"{read_only:false; response_revision:9013; number_of_response:1; }","duration":"228.104199ms","start":"2026-10-15T06:47:22.025082Z","end":"2026-10-15T06:47:22.253187Z","steps":["trace[96867831] 'process raft request' (duration: 228.048397ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:22.253645Z","caller":"traceutil/trace.go:171","msg":"trace[243034131] transaction","detail":"{read_only:false; response_revision:9012; number_of_response:1; }","duration":"229.343618ms","start":"2026-10-15T06:47:22.024283Z","end":"2026-10-15T06:47:22.253627Z","steps":["trace[243034131] 'process raft request' (duration: 128.600545ms)","trace[243034131] 'compare' (duration: 99.879242ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:47:22.253973Z","caller":"traceutil/trace.go:171","msg":"trace[1199783438] linearizableReadLoop","detail":"{readStateIndex:9335; appliedIndex:9334; }","duration":"229.422049ms","start":"2026-10-15T06:47:22.024527Z","end":"2026-10-15T06:47:22.253949Z","steps":["trace[1199783438] 'read index received' (duration: 128.351609ms)","trace[1199783438] 'applied index is now lower than readState.Index' (duration: 101.06727ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:47:22.25425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.114915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"info","ts":"2026-10-15T06:47:22.254308Z","caller":"traceutil/trace.go:171","msg":"trace[399162529] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9013; }","duration":"217.196686ms","start":"2026-10-15T06:47:22.037097Z","end":"2026-10-15T06:47:22.254294Z","steps":["trace[399162529] 'agreement among raft nodes before linearized reading' (duration: 217.038183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:22.254394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.336457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-10-15T06:47:22.25447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.928471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228420"} {"level":"warn","ts":"2026-10-15T06:47:22.254458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.141491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:47:22.254575Z","caller":"traceutil/trace.go:171","msg":"trace[1586382223] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9013; }","duration":"230.078394ms","start":"2026-10-15T06:47:22.024481Z","end":"2026-10-15T06:47:22.25456Z","steps":["trace[1586382223] 'agreement among raft nodes before linearized reading' (duration: 229.603903ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:22.254595Z","caller":"traceutil/trace.go:171","msg":"trace[738574804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9013; }","duration":"150.352427ms","start":"2026-10-15T06:47:22.104219Z","end":"2026-10-15T06:47:22.254572Z","steps":["trace[738574804] 'agreement among raft nodes before linearized reading' (duration: 150.072479ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:22.254511Z","caller":"traceutil/trace.go:171","msg":"trace[474532675] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9013; }","duration":"121.505931ms","start":"2026-10-15T06:47:22.132965Z","end":"2026-10-15T06:47:22.254471Z","steps":["trace[474532675] 'agreement among raft nodes before linearized reading' (duration: 121.218124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:47:34.00359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.718344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:47:34.003693Z","caller":"traceutil/trace.go:171","msg":"trace[588087939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9136; }","duration":"137.882648ms","start":"2026-10-15T06:47:33.865785Z","end":"2026-10-15T06:47:34.003668Z","steps":["trace[588087939] 'count revisions from in-memory index tree' (duration: 137.592422ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:47:36.562345Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000251b.wal"} {"level":"info","ts":"2026-10-15T06:48:23.261993Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd81c1a075562f5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T06:48:23.273078Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T06:48:23.273183Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-15T06:48:44.668962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.922784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"info","ts":"2026-10-15T06:48:44.669054Z","caller":"traceutil/trace.go:171","msg":"trace[302913075] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9820; }","duration":"215.066177ms","start":"2026-10-15T06:48:44.453967Z","end":"2026-10-15T06:48:44.669033Z","steps":["trace[302913075] 'range keys from in-memory index tree' (duration: 214.701669ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:07.736292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8009} {"level":"info","ts":"2026-10-15T06:50:07.824003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8009,"took":"85.232081ms","hash":447157686,"current-db-size-bytes":55058432,"current-db-size":"55 MB","current-db-size-in-use-bytes":25165824,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-15T06:50:07.824076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":447157686,"revision":8009,"compact-revision":5597} {"level":"info","ts":"2026-10-15T06:55:07.743664Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10397} {"level":"info","ts":"2026-10-15T06:55:07.827634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10397,"took":"81.558375ms","hash":2639646313,"current-db-size-bytes":55058432,"current-db-size":"55 MB","current-db-size-in-use-bytes":27951104,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T06:55:07.827724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639646313,"revision":10397,"compact-revision":8009} {"level":"info","ts":"2026-10-15T06:58:15.908396Z","caller":"traceutil/trace.go:171","msg":"trace[335460838] transaction","detail":"{read_only:false; response_revision:14037; number_of_response:1; }","duration":"103.302506ms","start":"2026-10-15T06:58:15.805069Z","end":"2026-10-15T06:58:15.908372Z","steps":["trace[335460838] 'process raft request' (duration: 102.972109ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:58:18.85862Z","caller":"traceutil/trace.go:171","msg":"trace[950553338] linearizableReadLoop","detail":"{readStateIndex:14540; appliedIndex:14539; }","duration":"114.735279ms","start":"2026-10-15T06:58:18.743856Z","end":"2026-10-15T06:58:18.858591Z","steps":["trace[950553338] 'read index received' (duration: 104.76567ms)","trace[950553338] 'applied index is now lower than readState.Index' (duration: 9.968409ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:58:18.858784Z","caller":"traceutil/trace.go:171","msg":"trace[7983158] transaction","detail":"{read_only:false; response_revision:14055; number_of_response:1; }","duration":"125.600669ms","start":"2026-10-15T06:58:18.7331Z","end":"2026-10-15T06:58:18.858701Z","steps":["trace[7983158] 'process raft request' (duration: 115.570889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:58:18.858836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.957624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T06:58:18.858884Z","caller":"traceutil/trace.go:171","msg":"trace[980248181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14055; }","duration":"115.050246ms","start":"2026-10-15T06:58:18.743822Z","end":"2026-10-15T06:58:18.858872Z","steps":["trace[980248181] 'agreement among raft nodes before linearized reading' (duration: 114.861792ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:58:40.100428Z","caller":"traceutil/trace.go:171","msg":"trace[502947754] transaction","detail":"{read_only:false; response_revision:14167; number_of_response:1; }","duration":"105.22691ms","start":"2026-10-15T06:58:39.995166Z","end":"2026-10-15T06:58:40.100393Z","steps":["trace[502947754] 'process raft request' (duration: 104.988595ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:58:47.408672Z","caller":"traceutil/trace.go:171","msg":"trace[1320930860] transaction","detail":"{read_only:false; response_revision:14207; number_of_response:1; }","duration":"102.129043ms","start":"2026-10-15T06:58:47.306519Z","end":"2026-10-15T06:58:47.408648Z","steps":["trace[1320930860] 'process raft request' (duration: 101.984759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:58:51.487284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.197373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:58:51.487827Z","caller":"traceutil/trace.go:171","msg":"trace[118028935] transaction","detail":"{read_only:false; response_revision:14245; number_of_response:1; }","duration":"182.984404ms","start":"2026-10-15T06:58:51.304821Z","end":"2026-10-15T06:58:51.487806Z","steps":["trace[118028935] 'process raft request' (duration: 64.177608ms)","trace[118028935] 'compare' (duration: 118.014868ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:58:53.520078Z","caller":"traceutil/trace.go:171","msg":"trace[456653440] linearizableReadLoop","detail":"{readStateIndex:14747; appliedIndex:14746; }","duration":"268.165407ms","start":"2026-10-15T06:58:53.251883Z","end":"2026-10-15T06:58:53.520048Z","steps":["trace[456653440] 'read index received' (duration: 190.15401ms)","trace[456653440] 'applied index is now lower than readState.Index' (duration: 78.010067ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:58:53.52021Z","caller":"traceutil/trace.go:171","msg":"trace[178106463] transaction","detail":"{read_only:false; response_revision:14254; number_of_response:1; }","duration":"384.518116ms","start":"2026-10-15T06:58:53.135651Z","end":"2026-10-15T06:58:53.520169Z","steps":["trace[178106463] 'process raft request' (duration: 306.468658ms)","trace[178106463] 'compare' (duration: 77.68922ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:58:53.520275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.378271ms","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:7"} {"level":"info","ts":"2026-10-15T06:58:53.520317Z","caller":"traceutil/trace.go:171","msg":"trace[934645506] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:14254; }","duration":"268.493554ms","start":"2026-10-15T06:58:53.25181Z","end":"2026-10-15T06:58:53.520304Z","steps":["trace[934645506] 'agreement among raft nodes before linearized reading' (duration: 268.390261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:58:53.520404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:58:53.135629Z","time spent":"384.68277ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:58:53.745265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.589947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:58:53.745411Z","caller":"traceutil/trace.go:171","msg":"trace[1775081309] transaction","detail":"{read_only:false; response_revision:14256; number_of_response:1; }","duration":"143.568027ms","start":"2026-10-15T06:58:53.601819Z","end":"2026-10-15T06:58:53.745387Z","steps":["trace[1775081309] 'process raft request' (duration: 35.729353ms)","trace[1775081309] 'compare' (duration: 107.426814ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:58:59.983397Z","caller":"traceutil/trace.go:171","msg":"trace[1401546474] transaction","detail":"{read_only:false; response_revision:14292; number_of_response:1; }","duration":"193.893866ms","start":"2026-10-15T06:58:59.789443Z","end":"2026-10-15T06:58:59.983337Z","steps":["trace[1401546474] 'process raft request' (duration: 193.520958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:59:00.62928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.849562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"warn","ts":"2026-10-15T06:59:00.629326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.811733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:59:00.629363Z","caller":"traceutil/trace.go:171","msg":"trace[1342557688] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14292; }","duration":"641.988805ms","start":"2026-10-15T06:58:59.987353Z","end":"2026-10-15T06:59:00.629342Z","steps":["trace[1342557688] 'range keys from in-memory index tree' (duration: 641.627687ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:59:00.629414Z","caller":"traceutil/trace.go:171","msg":"trace[848109674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14292; }","duration":"523.949117ms","start":"2026-10-15T06:59:00.105448Z","end":"2026-10-15T06:59:00.629397Z","steps":["trace[848109674] 'range keys from in-memory index tree' (duration: 523.697251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:59:00.629427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:58:59.987333Z","time spent":"642.083208ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12232,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-15T06:59:00.629454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:59:00.105419Z","time spent":"524.024988ms","remote":"127.0.0.1:42788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T06:59:00.629926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.538132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:59:00.630072Z","caller":"traceutil/trace.go:171","msg":"trace[769795485] linearizableReadLoop","detail":"{readStateIndex:14787; appliedIndex:14786; }","duration":"393.35263ms","start":"2026-10-15T06:59:00.236694Z","end":"2026-10-15T06:59:00.630047Z","steps":["trace[769795485] 'read index received' (duration: 26.386838ms)","trace[769795485] 'applied index is now lower than readState.Index' (duration: 366.964512ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:59:00.630325Z","caller":"traceutil/trace.go:171","msg":"trace[210948425] transaction","detail":"{read_only:false; response_revision:14293; number_of_response:1; }","duration":"431.756334ms","start":"2026-10-15T06:59:00.198547Z","end":"2026-10-15T06:59:00.630304Z","steps":["trace[210948425] 'process raft request' (duration: 64.70702ms)","trace[210948425] 'compare' (duration: 365.962018ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:59:00.630424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.712617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-15T06:59:00.630493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.170352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwngx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-15T06:59:00.630511Z","caller":"traceutil/trace.go:171","msg":"trace[1338577816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14293; }","duration":"393.864141ms","start":"2026-10-15T06:59:00.236625Z","end":"2026-10-15T06:59:00.63049Z","steps":["trace[1338577816] 'agreement among raft nodes before linearized reading' (duration: 393.571494ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:59:00.630535Z","caller":"traceutil/trace.go:171","msg":"trace[231359736] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwngx; range_end:; response_count:1; response_revision:14293; }","duration":"273.247932ms","start":"2026-10-15T06:59:00.357274Z","end":"2026-10-15T06:59:00.630521Z","steps":["trace[231359736] 'agreement among raft nodes before linearized reading' (duration: 273.060618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:59:00.630552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:59:00.236595Z","time spent":"393.949433ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T06:59:00.630455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:59:00.198516Z","time spent":"431.854536ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:59:00.630619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.294705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-15T06:59:00.630668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.523777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T06:59:00.630723Z","caller":"traceutil/trace.go:171","msg":"trace[112945388] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14293; }","duration":"125.428008ms","start":"2026-10-15T06:59:00.505264Z","end":"2026-10-15T06:59:00.630692Z","steps":["trace[112945388] 'agreement among raft nodes before linearized reading' (duration: 125.146922ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:59:00.630774Z","caller":"traceutil/trace.go:171","msg":"trace[2123434783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14293; }","duration":"259.610729ms","start":"2026-10-15T06:59:00.371107Z","end":"2026-10-15T06:59:00.630718Z","steps":["trace[2123434783] 'agreement among raft nodes before linearized reading' (duration: 259.379634ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:59:00.870812Z","caller":"traceutil/trace.go:171","msg":"trace[2070582931] transaction","detail":"{read_only:false; response_revision:14294; number_of_response:1; }","duration":"234.999073ms","start":"2026-10-15T06:59:00.635789Z","end":"2026-10-15T06:59:00.870789Z","steps":["trace[2070582931] 'process raft request' (duration: 188.804978ms)","trace[2070582931] 'compare' (duration: 44.599888ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:59:00.871582Z","caller":"traceutil/trace.go:171","msg":"trace[422993583] transaction","detail":"{read_only:false; response_revision:14295; number_of_response:1; }","duration":"235.523754ms","start":"2026-10-15T06:59:00.636012Z","end":"2026-10-15T06:59:00.871536Z","steps":["trace[422993583] 'process raft request' (duration: 235.34295ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:59:00.872035Z","caller":"traceutil/trace.go:171","msg":"trace[780602931] transaction","detail":"{read_only:false; response_revision:14296; number_of_response:1; }","duration":"234.294086ms","start":"2026-10-15T06:59:00.637704Z","end":"2026-10-15T06:59:00.871998Z","steps":["trace[780602931] 'process raft request' (duration: 233.789084ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:59:00.872275Z","caller":"traceutil/trace.go:171","msg":"trace[1910768235] linearizableReadLoop","detail":"{readStateIndex:14789; appliedIndex:14787; }","duration":"235.970555ms","start":"2026-10-15T06:59:00.636289Z","end":"2026-10-15T06:59:00.87226Z","steps":["trace[1910768235] 'read index received' (duration: 188.273807ms)","trace[1910768235] 'applied index is now lower than readState.Index' (duration: 47.695898ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:59:00.872418Z","caller":"traceutil/trace.go:171","msg":"trace[357623447] transaction","detail":"{read_only:false; response_revision:14297; number_of_response:1; }","duration":"231.691097ms","start":"2026-10-15T06:59:00.640717Z","end":"2026-10-15T06:59:00.872408Z","steps":["trace[357623447] 'process raft request' (duration: 231.167064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:59:00.872974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.236402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:59:00.873069Z","caller":"traceutil/trace.go:171","msg":"trace[1196501527] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14297; }","duration":"164.58445ms","start":"2026-10-15T06:59:00.708467Z","end":"2026-10-15T06:59:00.873051Z","steps":["trace[1196501527] 'agreement among raft nodes before linearized reading' (duration: 164.429437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:59:00.873479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.164373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:595063"} {"level":"info","ts":"2026-10-15T06:59:00.873531Z","caller":"traceutil/trace.go:171","msg":"trace[1204452918] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14297; }","duration":"237.258725ms","start":"2026-10-15T06:59:00.636259Z","end":"2026-10-15T06:59:00.873518Z","steps":["trace[1204452918] 'agreement among raft nodes before linearized reading' (duration: 236.566819ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:00:07.768223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12744} {"level":"info","ts":"2026-10-15T07:00:07.870959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12744,"took":"84.364633ms","hash":3933436323,"current-db-size-bytes":55058432,"current-db-size":"55 MB","current-db-size-in-use-bytes":31002624,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T07:00:07.871043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3933436323,"revision":12744,"compact-revision":10397} {"level":"warn","ts":"2026-10-15T07:00:12.241681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.973881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:00:12.241832Z","caller":"traceutil/trace.go:171","msg":"trace[1437979709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15252; }","duration":"136.242827ms","start":"2026-10-15T07:00:12.105562Z","end":"2026-10-15T07:00:12.241805Z","steps":["trace[1437979709] 'range keys from in-memory index tree' (duration: 135.740216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:12.241934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.8306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9pndm\" ","response":"range_response_count:1 size:20656"} {"level":"info","ts":"2026-10-15T07:00:12.242027Z","caller":"traceutil/trace.go:171","msg":"trace[1860463918] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9pndm; range_end:; response_count:1; response_revision:15252; }","duration":"119.966754ms","start":"2026-10-15T07:00:12.122029Z","end":"2026-10-15T07:00:12.241996Z","steps":["trace[1860463918] 'range keys from in-memory index tree' (duration: 119.555674ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:33.237267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.90365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-10-15T07:00:33.237352Z","caller":"traceutil/trace.go:171","msg":"trace[1108286065] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:15360; }","duration":"120.047455ms","start":"2026-10-15T07:00:33.117286Z","end":"2026-10-15T07:00:33.237333Z","steps":["trace[1108286065] 'range keys from in-memory index tree' (duration: 119.767638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.372135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.199446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-10-15T07:00:34.372214Z","caller":"traceutil/trace.go:171","msg":"trace[1471144480] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:15366; }","duration":"403.336199ms","start":"2026-10-15T07:00:33.968859Z","end":"2026-10-15T07:00:34.372195Z","steps":["trace[1471144480] 'range keys from in-memory index tree' (duration: 403.052763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.372245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:33.968842Z","time spent":"403.3956ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-10-15T07:00:34.372374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.014621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9rrqq\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-15T07:00:34.372486Z","caller":"traceutil/trace.go:171","msg":"trace[370535948] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9rrqq; range_end:; response_count:1; response_revision:15366; }","duration":"439.182235ms","start":"2026-10-15T07:00:33.933275Z","end":"2026-10-15T07:00:34.372458Z","steps":["trace[370535948] 'range keys from in-memory index tree' (duration: 438.592622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.372528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:33.933252Z","time spent":"439.266147ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22152,"request content":"key:\"/registry/pods/openstack/nova-compute-default-9rrqq\" "} {"level":"info","ts":"2026-10-15T07:00:34.466846Z","caller":"traceutil/trace.go:171","msg":"trace[1440347731] linearizableReadLoop","detail":"{readStateIndex:15896; appliedIndex:15895; }","duration":"379.557422ms","start":"2026-10-15T07:00:34.08726Z","end":"2026-10-15T07:00:34.466818Z","steps":["trace[1440347731] 'read index received' (duration: 379.257675ms)","trace[1440347731] 'applied index is now lower than readState.Index' (duration: 298.747µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:00:34.467397Z","caller":"traceutil/trace.go:171","msg":"trace[1819730992] transaction","detail":"{read_only:false; response_revision:15367; number_of_response:1; }","duration":"394.324572ms","start":"2026-10-15T07:00:34.073057Z","end":"2026-10-15T07:00:34.467382Z","steps":["trace[1819730992] 'process raft request' (duration: 393.523353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.467582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.073034Z","time spent":"394.463205ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:00:34.467951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.691558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T07:00:34.468035Z","caller":"traceutil/trace.go:171","msg":"trace[2128160778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15367; }","duration":"380.806331ms","start":"2026-10-15T07:00:34.087215Z","end":"2026-10-15T07:00:34.468022Z","steps":["trace[2128160778] 'agreement among raft nodes before linearized reading' (duration: 380.634437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.468104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.087196Z","time spent":"380.896242ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T07:00:34.468154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.375946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:00:34.468293Z","caller":"traceutil/trace.go:171","msg":"trace[1726790903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15367; }","duration":"362.57124ms","start":"2026-10-15T07:00:34.10569Z","end":"2026-10-15T07:00:34.468262Z","steps":["trace[1726790903] 'agreement among raft nodes before linearized reading' (duration: 362.323814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.468357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.105666Z","time spent":"362.678393ms","remote":"127.0.0.1:42788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T07:00:34.469281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.113045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:00:34.469399Z","caller":"traceutil/trace.go:171","msg":"trace[141822787] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15367; }","duration":"344.254869ms","start":"2026-10-15T07:00:34.125127Z","end":"2026-10-15T07:00:34.469382Z","steps":["trace[141822787] 'agreement among raft nodes before linearized reading' (duration: 344.094205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.469507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.125113Z","time spent":"344.379401ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-15T07:00:34.470313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.607179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:00:34.470368Z","caller":"traceutil/trace.go:171","msg":"trace[923366544] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15367; }","duration":"345.672652ms","start":"2026-10-15T07:00:34.124682Z","end":"2026-10-15T07:00:34.470354Z","steps":["trace[923366544] 'agreement among raft nodes before linearized reading' (duration: 345.59318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.470399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.124672Z","time spent":"345.719992ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-10-15T07:00:34.470493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.859915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:00:34.470526Z","caller":"traceutil/trace.go:171","msg":"trace[1605351559] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15367; }","duration":"345.908446ms","start":"2026-10-15T07:00:34.124609Z","end":"2026-10-15T07:00:34.470517Z","steps":["trace[1605351559] 'agreement among raft nodes before linearized reading' (duration: 345.863035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.470549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.124602Z","time spent":"345.941887ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-10-15T07:00:34.470668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.121221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T07:00:34.470704Z","caller":"traceutil/trace.go:171","msg":"trace[114628902] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15367; }","duration":"346.176352ms","start":"2026-10-15T07:00:34.124517Z","end":"2026-10-15T07:00:34.470694Z","steps":["trace[114628902] 'agreement among raft nodes before linearized reading' (duration: 346.08515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.470725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.124508Z","time spent":"346.212604ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5547,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-15T07:00:34.470979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.96364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T07:00:34.471016Z","caller":"traceutil/trace.go:171","msg":"trace[962836358] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15367; }","duration":"347.073693ms","start":"2026-10-15T07:00:34.123932Z","end":"2026-10-15T07:00:34.471005Z","steps":["trace[962836358] 'agreement among raft nodes before linearized reading' (duration: 346.970031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.471041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.123895Z","time spent":"347.140305ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-15T07:00:34.471243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.804691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:00:34.471301Z","caller":"traceutil/trace.go:171","msg":"trace[255874314] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15367; }","duration":"347.936543ms","start":"2026-10-15T07:00:34.123351Z","end":"2026-10-15T07:00:34.471288Z","steps":["trace[255874314] 'agreement among raft nodes before linearized reading' (duration: 347.84605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.47134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.123323Z","time spent":"348.007355ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-15T07:00:34.471641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.032737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T07:00:34.471766Z","caller":"traceutil/trace.go:171","msg":"trace[1292628726] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15367; }","duration":"104.19562ms","start":"2026-10-15T07:00:34.367529Z","end":"2026-10-15T07:00:34.471725Z","steps":["trace[1292628726] 'agreement among raft nodes before linearized reading' (duration: 103.999976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.472052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.384396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:00:34.472146Z","caller":"traceutil/trace.go:171","msg":"trace[693326663] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15367; }","duration":"105.53305ms","start":"2026-10-15T07:00:34.366601Z","end":"2026-10-15T07:00:34.472134Z","steps":["trace[693326663] 'agreement among raft nodes before linearized reading' (duration: 105.418538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.472453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.989105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T07:00:34.472554Z","caller":"traceutil/trace.go:171","msg":"trace[797314313] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15367; }","duration":"345.108687ms","start":"2026-10-15T07:00:34.127432Z","end":"2026-10-15T07:00:34.472541Z","steps":["trace[797314313] 'agreement among raft nodes before linearized reading' (duration: 344.921373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:00:34.472633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:00:34.127399Z","time spent":"345.21575ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5547,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-10-15T07:02:05.016157Z","caller":"traceutil/trace.go:171","msg":"trace[1586249594] linearizableReadLoop","detail":"{readStateIndex:16502; appliedIndex:16501; }","duration":"135.698245ms","start":"2026-10-15T07:02:04.880431Z","end":"2026-10-15T07:02:05.01613Z","steps":["trace[1586249594] 'read index received' (duration: 135.506901ms)","trace[1586249594] 'applied index is now lower than readState.Index' (duration: 190.094µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:02:05.016266Z","caller":"traceutil/trace.go:171","msg":"trace[411213903] transaction","detail":"{read_only:false; response_revision:15954; number_of_response:1; }","duration":"201.554372ms","start":"2026-10-15T07:02:04.814682Z","end":"2026-10-15T07:02:05.016236Z","steps":["trace[411213903] 'process raft request' (duration: 201.286606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:05.016494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.129919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:1 size:12594"} {"level":"info","ts":"2026-10-15T07:02:05.016564Z","caller":"traceutil/trace.go:171","msg":"trace[197995486] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:1; response_revision:15954; }","duration":"127.23518ms","start":"2026-10-15T07:02:04.889311Z","end":"2026-10-15T07:02:05.016546Z","steps":["trace[197995486] 'agreement among raft nodes before linearized reading' (duration: 127.078167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:05.01648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.020763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:1 size:7291"} {"level":"info","ts":"2026-10-15T07:02:05.016625Z","caller":"traceutil/trace.go:171","msg":"trace[526725588] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:1; response_revision:15954; }","duration":"136.220326ms","start":"2026-10-15T07:02:04.880384Z","end":"2026-10-15T07:02:05.016605Z","steps":["trace[526725588] 'agreement among raft nodes before linearized reading' (duration: 135.882969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:05.016695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.901233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zwngx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-15T07:02:05.016841Z","caller":"traceutil/trace.go:171","msg":"trace[761804088] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zwngx; range_end:; response_count:1; response_revision:15954; }","duration":"114.051976ms","start":"2026-10-15T07:02:04.902725Z","end":"2026-10-15T07:02:05.016777Z","steps":["trace[761804088] 'agreement among raft nodes before linearized reading' (duration: 113.807811ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:05:07.778135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15228} {"level":"info","ts":"2026-10-15T07:05:07.893118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15228,"took":"112.502754ms","hash":3049007553,"current-db-size-bytes":55058432,"current-db-size":"55 MB","current-db-size-in-use-bytes":32575488,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:05:07.893199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3049007553,"revision":15228,"compact-revision":12744} {"level":"warn","ts":"2026-10-15T07:06:36.062109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.335729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9pndm\" ","response":"range_response_count:1 size:20759"} {"level":"info","ts":"2026-10-15T07:06:36.062223Z","caller":"traceutil/trace.go:171","msg":"trace[128984104] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9pndm; range_end:; response_count:1; response_revision:18071; }","duration":"183.488852ms","start":"2026-10-15T07:06:35.878705Z","end":"2026-10-15T07:06:36.062194Z","steps":["trace[128984104] 'range keys from in-memory index tree' (duration: 183.154864ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:36.520756Z","caller":"traceutil/trace.go:171","msg":"trace[693004215] transaction","detail":"{read_only:false; response_revision:18072; number_of_response:1; }","duration":"432.026864ms","start":"2026-10-15T07:06:36.088701Z","end":"2026-10-15T07:06:36.520728Z","steps":["trace[693004215] 'process raft request' (duration: 431.648225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:36.521008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.979101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:06:36.521128Z","caller":"traceutil/trace.go:171","msg":"trace[429363999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18072; }","duration":"415.142024ms","start":"2026-10-15T07:06:36.105962Z","end":"2026-10-15T07:06:36.521104Z","steps":["trace[429363999] 'agreement among raft nodes before linearized reading' (duration: 414.891559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:36.521067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:06:36.088663Z","time spent":"432.296649ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:06:36.521182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:06:36.105942Z","time spent":"415.226736ms","remote":"127.0.0.1:42788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T07:06:36.520654Z","caller":"traceutil/trace.go:171","msg":"trace[945100435] linearizableReadLoop","detail":"{readStateIndex:18689; appliedIndex:18688; }","duration":"414.614523ms","start":"2026-10-15T07:06:36.106009Z","end":"2026-10-15T07:06:36.520624Z","steps":["trace[945100435] 'read index received' (duration: 414.371646ms)","trace[945100435] 'applied index is now lower than readState.Index' (duration: 241.747µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:06:36.528839Z","caller":"traceutil/trace.go:171","msg":"trace[1525254234] transaction","detail":"{read_only:false; response_revision:18073; number_of_response:1; }","duration":"378.388126ms","start":"2026-10-15T07:06:36.15042Z","end":"2026-10-15T07:06:36.528808Z","steps":["trace[1525254234] 'process raft request' (duration: 376.142434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:36.529019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:06:36.150383Z","time spent":"378.55587ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:06:36.558323Z","caller":"traceutil/trace.go:171","msg":"trace[238537127] transaction","detail":"{read_only:false; response_revision:18074; number_of_response:1; }","duration":"100.653571ms","start":"2026-10-15T07:06:36.457642Z","end":"2026-10-15T07:06:36.558296Z","steps":["trace[238537127] 'process raft request' (duration: 100.504297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:36.558691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.597357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29867460-r52r6\" ","response":"range_response_count:1 size:7040"} {"level":"info","ts":"2026-10-15T07:06:36.558789Z","caller":"traceutil/trace.go:171","msg":"trace[209266672] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29867460-r52r6; range_end:; response_count:1; response_revision:18074; }","duration":"223.704489ms","start":"2026-10-15T07:06:36.335067Z","end":"2026-10-15T07:06:36.558772Z","steps":["trace[209266672] 'agreement among raft nodes before linearized reading' (duration: 223.503335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:07:21.438614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.799158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-cfn\" ","response":"range_response_count:1 size:519"} {"level":"info","ts":"2026-10-15T07:07:21.438695Z","caller":"traceutil/trace.go:171","msg":"trace[803894369] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-cfn; range_end:; response_count:1; response_revision:18622; }","duration":"117.921681ms","start":"2026-10-15T07:07:21.320752Z","end":"2026-10-15T07:07:21.438673Z","steps":["trace[803894369] 'agreement among raft nodes before linearized reading' (duration: 117.713946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:07:21.43875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.009356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-15T07:07:21.438839Z","caller":"traceutil/trace.go:171","msg":"trace[1845949726] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine; range_end:; response_count:1; response_revision:18622; }","duration":"116.146489ms","start":"2026-10-15T07:07:21.322672Z","end":"2026-10-15T07:07:21.438819Z","steps":["trace[1845949726] 'agreement among raft nodes before linearized reading' (duration: 115.884163ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:07:21.438894Z","caller":"traceutil/trace.go:171","msg":"trace[786349115] transaction","detail":"{read_only:false; response_revision:18622; number_of_response:1; }","duration":"117.92116ms","start":"2026-10-15T07:07:21.320949Z","end":"2026-10-15T07:07:21.43887Z","steps":["trace[786349115] 'process raft request' (duration: 117.378237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:07:21.439055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.500321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:07:21.439094Z","caller":"traceutil/trace.go:171","msg":"trace[1938847228] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:18622; }","duration":"114.568502ms","start":"2026-10-15T07:07:21.324513Z","end":"2026-10-15T07:07:21.439081Z","steps":["trace[1938847228] 'agreement among raft nodes before linearized reading' (duration: 114.512021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:07:21.439403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.750665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-api\" ","response":"range_response_count:1 size:519"} {"level":"info","ts":"2026-10-15T07:07:21.439482Z","caller":"traceutil/trace.go:171","msg":"trace[1424755575] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-api; range_end:; response_count:1; response_revision:18622; }","duration":"117.853858ms","start":"2026-10-15T07:07:21.321598Z","end":"2026-10-15T07:07:21.439451Z","steps":["trace[1424755575] 'agreement among raft nodes before linearized reading' (duration: 117.642393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:07:21.444792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.541581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-10-15T07:07:21.445035Z","caller":"traceutil/trace.go:171","msg":"trace[220348284] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:18622; }","duration":"117.715896ms","start":"2026-10-15T07:07:21.327168Z","end":"2026-10-15T07:07:21.444884Z","steps":["trace[220348284] 'agreement among raft nodes before linearized reading' (duration: 111.362638ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:07:21.491307Z","caller":"traceutil/trace.go:171","msg":"trace[1079710007] transaction","detail":"{read_only:false; response_revision:18623; number_of_response:1; }","duration":"114.704575ms","start":"2026-10-15T07:07:21.376571Z","end":"2026-10-15T07:07:21.491276Z","steps":["trace[1079710007] 'process raft request' (duration: 114.495551ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:07:21.491454Z","caller":"traceutil/trace.go:171","msg":"trace[127587358] transaction","detail":"{read_only:false; response_revision:18625; number_of_response:1; }","duration":"110.823145ms","start":"2026-10-15T07:07:21.380622Z","end":"2026-10-15T07:07:21.491445Z","steps":["trace[127587358] 'process raft request' (duration: 110.61468ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:07:21.491588Z","caller":"traceutil/trace.go:171","msg":"trace[1757212123] transaction","detail":"{read_only:false; response_revision:18624; number_of_response:1; }","duration":"113.185869ms","start":"2026-10-15T07:07:21.378392Z","end":"2026-10-15T07:07:21.491578Z","steps":["trace[1757212123] 'process raft request' (duration: 112.8027ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:08:57.57918Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd81c1a075562f5","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:08:57.585373Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T07:08:57.585585Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T07:10:07.785697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17463} {"level":"info","ts":"2026-10-15T07:10:07.892316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17463,"took":"103.275259ms","hash":3759516577,"current-db-size-bytes":55058432,"current-db-size":"55 MB","current-db-size-in-use-bytes":35414016,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T07:10:07.89238Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3759516577,"revision":17463,"compact-revision":15228} {"level":"info","ts":"2026-10-15T07:11:48.159616Z","caller":"traceutil/trace.go:171","msg":"trace[1853595006] transaction","detail":"{read_only:false; response_revision:20587; number_of_response:1; }","duration":"190.829247ms","start":"2026-10-15T07:11:47.968763Z","end":"2026-10-15T07:11:48.159593Z","steps":["trace[1853595006] 'process raft request' (duration: 190.617442ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:11:48.322488Z","caller":"traceutil/trace.go:171","msg":"trace[1574621841] transaction","detail":"{read_only:false; response_revision:20588; number_of_response:1; }","duration":"353.176286ms","start":"2026-10-15T07:11:47.969266Z","end":"2026-10-15T07:11:48.322442Z","steps":["trace[1574621841] 'process raft request' (duration: 279.664877ms)","trace[1574621841] 'compare' (duration: 72.996957ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:11:48.322663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:11:47.969253Z","time spent":"353.316878ms","remote":"127.0.0.1:43070","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-10-15T07:12:19.873382Z","caller":"traceutil/trace.go:171","msg":"trace[171352224] linearizableReadLoop","detail":"{readStateIndex:21454; appliedIndex:21453; }","duration":"227.114254ms","start":"2026-10-15T07:12:19.646236Z","end":"2026-10-15T07:12:19.87335Z","steps":["trace[171352224] 'read index received' (duration: 183.877925ms)","trace[171352224] 'applied index is now lower than readState.Index' (duration: 43.234899ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:12:19.873602Z","caller":"traceutil/trace.go:171","msg":"trace[350933220] transaction","detail":"{read_only:false; response_revision:20747; number_of_response:1; }","duration":"228.023615ms","start":"2026-10-15T07:12:19.645561Z","end":"2026-10-15T07:12:19.873584Z","steps":["trace[350933220] 'process raft request' (duration: 184.588422ms)","trace[350933220] 'compare' (duration: 42.594194ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:12:19.873851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.593845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-15T07:12:19.873847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.450858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T07:12:19.873915Z","caller":"traceutil/trace.go:171","msg":"trace[993330751] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20747; }","duration":"227.722699ms","start":"2026-10-15T07:12:19.646174Z","end":"2026-10-15T07:12:19.873897Z","steps":["trace[993330751] 'agreement among raft nodes before linearized reading' (duration: 227.511243ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:19.873927Z","caller":"traceutil/trace.go:171","msg":"trace[1666992991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20747; }","duration":"226.581702ms","start":"2026-10-15T07:12:19.647324Z","end":"2026-10-15T07:12:19.873906Z","steps":["trace[1666992991] 'agreement among raft nodes before linearized reading' (duration: 226.344456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:19.87396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.49317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T07:12:19.874142Z","caller":"traceutil/trace.go:171","msg":"trace[398831977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20747; }","duration":"226.655404ms","start":"2026-10-15T07:12:19.647375Z","end":"2026-10-15T07:12:19.874031Z","steps":["trace[398831977] 'agreement among raft nodes before linearized reading' (duration: 226.348106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:20.233785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.801984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:12:20.233895Z","caller":"traceutil/trace.go:171","msg":"trace[231425208] transaction","detail":"{read_only:false; response_revision:20748; number_of_response:1; }","duration":"354.780043ms","start":"2026-10-15T07:12:19.879092Z","end":"2026-10-15T07:12:20.233872Z","steps":["trace[231425208] 'process raft request' (duration: 157.802304ms)","trace[231425208] 'compare' (duration: 196.532128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:12:20.233946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:19.879066Z","time spent":"354.858614ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:12:20.25535Z","caller":"traceutil/trace.go:171","msg":"trace[1627593638] transaction","detail":"{read_only:false; response_revision:20749; number_of_response:1; }","duration":"376.166517ms","start":"2026-10-15T07:12:19.879143Z","end":"2026-10-15T07:12:20.255309Z","steps":["trace[1627593638] 'process raft request' (duration: 375.991443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:20.255489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:19.879112Z","time spent":"376.301119ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:12:20.258777Z","caller":"traceutil/trace.go:171","msg":"trace[1994956660] linearizableReadLoop","detail":"{readStateIndex:21457; appliedIndex:21454; }","duration":"263.912594ms","start":"2026-10-15T07:12:19.994823Z","end":"2026-10-15T07:12:20.258736Z","steps":["trace[1994956660] 'read index received' (duration: 42.182024ms)","trace[1994956660] 'applied index is now lower than readState.Index' (duration: 221.728769ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:12:20.258795Z","caller":"traceutil/trace.go:171","msg":"trace[110473119] transaction","detail":"{read_only:false; response_revision:20750; number_of_response:1; }","duration":"377.093737ms","start":"2026-10-15T07:12:19.88167Z","end":"2026-10-15T07:12:20.258764Z","steps":["trace[110473119] 'process raft request' (duration: 373.608627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:20.258933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:19.881658Z","time spent":"377.195069ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:12:20.258949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.109789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-15T07:12:20.25901Z","caller":"traceutil/trace.go:171","msg":"trace[1065764126] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:20750; }","duration":"264.202471ms","start":"2026-10-15T07:12:19.99479Z","end":"2026-10-15T07:12:20.258992Z","steps":["trace[1065764126] 'agreement among raft nodes before linearized reading' (duration: 264.046007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:20.258959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.389452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T07:12:20.259129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.396622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-10-15T07:12:20.25914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.816731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-10-15T07:12:20.259164Z","caller":"traceutil/trace.go:171","msg":"trace[726885139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20750; }","duration":"153.637458ms","start":"2026-10-15T07:12:20.10552Z","end":"2026-10-15T07:12:20.259157Z","steps":["trace[726885139] 'agreement among raft nodes before linearized reading' (duration: 153.386461ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:20.259184Z","caller":"traceutil/trace.go:171","msg":"trace[496132640] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20750; }","duration":"153.481784ms","start":"2026-10-15T07:12:20.105687Z","end":"2026-10-15T07:12:20.259168Z","steps":["trace[496132640] 'agreement among raft nodes before linearized reading' (duration: 153.29932ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:20.25919Z","caller":"traceutil/trace.go:171","msg":"trace[262255403] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20750; }","duration":"117.897753ms","start":"2026-10-15T07:12:20.141278Z","end":"2026-10-15T07:12:20.259175Z","steps":["trace[262255403] 'agreement among raft nodes before linearized reading' (duration: 117.698439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:20.649826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.039826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:12:20.649957Z","caller":"traceutil/trace.go:171","msg":"trace[169390924] linearizableReadLoop","detail":"{readStateIndex:21458; appliedIndex:21457; }","duration":"310.07069ms","start":"2026-10-15T07:12:20.339867Z","end":"2026-10-15T07:12:20.649938Z","steps":["trace[169390924] 'read index received' (duration: 123.707997ms)","trace[169390924] 'applied index is now lower than readState.Index' (duration: 186.361363ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:12:20.650148Z","caller":"traceutil/trace.go:171","msg":"trace[632656415] transaction","detail":"{read_only:false; response_revision:20751; number_of_response:1; }","duration":"336.824338ms","start":"2026-10-15T07:12:20.313288Z","end":"2026-10-15T07:12:20.650112Z","steps":["trace[632656415] 'process raft request' (duration: 150.393243ms)","trace[632656415] 'compare' (duration: 185.917434ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:12:20.650321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:20.31327Z","time spent":"336.964511ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:12:20.65033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.429719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:12:20.650429Z","caller":"traceutil/trace.go:171","msg":"trace[516438868] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20751; }","duration":"310.599282ms","start":"2026-10-15T07:12:20.339809Z","end":"2026-10-15T07:12:20.650408Z","steps":["trace[516438868] 'agreement among raft nodes before linearized reading' (duration: 310.424838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:20.650498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:20.339792Z","time spent":"310.697285ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-15T07:12:20.651089Z","caller":"traceutil/trace.go:171","msg":"trace[1881082812] transaction","detail":"{read_only:false; response_revision:20752; number_of_response:1; }","duration":"141.839376ms","start":"2026-10-15T07:12:20.509233Z","end":"2026-10-15T07:12:20.651072Z","steps":["trace[1881082812] 'process raft request' (duration: 141.64821ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:20.664755Z","caller":"traceutil/trace.go:171","msg":"trace[1366632155] transaction","detail":"{read_only:false; response_revision:20753; number_of_response:1; }","duration":"137.076805ms","start":"2026-10-15T07:12:20.527649Z","end":"2026-10-15T07:12:20.664726Z","steps":["trace[1366632155] 'process raft request' (duration: 136.768348ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:20.932297Z","caller":"traceutil/trace.go:171","msg":"trace[106740660] transaction","detail":"{read_only:false; response_revision:20755; number_of_response:1; }","duration":"210.622353ms","start":"2026-10-15T07:12:20.721649Z","end":"2026-10-15T07:12:20.932272Z","steps":["trace[106740660] 'process raft request' (duration: 210.457459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:21.255706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.383119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:12:21.255963Z","caller":"traceutil/trace.go:171","msg":"trace[1626557531] transaction","detail":"{read_only:false; response_revision:20756; number_of_response:1; }","duration":"531.176846ms","start":"2026-10-15T07:12:20.724773Z","end":"2026-10-15T07:12:21.25595Z","steps":["trace[1626557531] 'process raft request' (duration: 310.488779ms)","trace[1626557531] 'compare' (duration: 220.196375ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:12:21.25601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:20.724754Z","time spent":"531.235447ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:12:21.618615Z","caller":"traceutil/trace.go:171","msg":"trace[1374129820] linearizableReadLoop","detail":"{readStateIndex:21464; appliedIndex:21463; }","duration":"124.809092ms","start":"2026-10-15T07:12:21.493765Z","end":"2026-10-15T07:12:21.618574Z","steps":["trace[1374129820] 'read index received' (duration: 58.542152ms)","trace[1374129820] 'applied index is now lower than readState.Index' (duration: 66.26535ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:12:21.618978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.200812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:12:21.619029Z","caller":"traceutil/trace.go:171","msg":"trace[1279489999] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:20756; }","duration":"125.312264ms","start":"2026-10-15T07:12:21.4937Z","end":"2026-10-15T07:12:21.619013Z","steps":["trace[1279489999] 'agreement among raft nodes before linearized reading' (duration: 125.151941ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:13:57.64451Z","caller":"traceutil/trace.go:171","msg":"trace[1122706942] transaction","detail":"{read_only:false; response_revision:21569; number_of_response:1; }","duration":"160.061516ms","start":"2026-10-15T07:13:57.48443Z","end":"2026-10-15T07:13:57.644491Z","steps":["trace[1122706942] 'process raft request' (duration: 159.949074ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:13:57.662882Z","caller":"traceutil/trace.go:171","msg":"trace[841339382] transaction","detail":"{read_only:false; response_revision:21570; number_of_response:1; }","duration":"160.479836ms","start":"2026-10-15T07:13:57.502373Z","end":"2026-10-15T07:13:57.662853Z","steps":["trace[841339382] 'process raft request' (duration: 160.272021ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:14:49.382905Z","caller":"traceutil/trace.go:171","msg":"trace[1076110704] transaction","detail":"{read_only:false; response_revision:21894; number_of_response:1; }","duration":"115.296152ms","start":"2026-10-15T07:14:49.267578Z","end":"2026-10-15T07:14:49.382874Z","steps":["trace[1076110704] 'process raft request' (duration: 46.219487ms)","trace[1076110704] 'compare' (duration: 68.949902ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:14:49.430193Z","caller":"traceutil/trace.go:171","msg":"trace[1371140787] transaction","detail":"{read_only:false; response_revision:21895; number_of_response:1; }","duration":"135.621742ms","start":"2026-10-15T07:14:49.294544Z","end":"2026-10-15T07:14:49.430166Z","steps":["trace[1371140787] 'process raft request' (duration: 135.477629ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:14:49.435858Z","caller":"traceutil/trace.go:171","msg":"trace[347273500] transaction","detail":"{read_only:false; response_revision:21896; number_of_response:1; }","duration":"121.65429ms","start":"2026-10-15T07:14:49.314168Z","end":"2026-10-15T07:14:49.435822Z","steps":["trace[347273500] 'process raft request' (duration: 121.394484ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:07.793551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19860} {"level":"info","ts":"2026-10-15T07:15:07.941001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19860,"took":"142.680055ms","hash":4031195176,"current-db-size-bytes":55058432,"current-db-size":"55 MB","current-db-size-in-use-bytes":29794304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T07:15:07.941072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4031195176,"revision":19860,"compact-revision":17463} {"level":"info","ts":"2026-10-15T07:15:12.959517Z","caller":"traceutil/trace.go:171","msg":"trace[1105017792] transaction","detail":"{read_only:false; response_revision:22092; number_of_response:1; }","duration":"106.53278ms","start":"2026-10-15T07:15:12.852945Z","end":"2026-10-15T07:15:12.959478Z","steps":["trace[1105017792] 'process raft request' (duration: 80.069809ms)","trace[1105017792] 'compare' (duration: 26.158604ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:15:21.751747Z","caller":"traceutil/trace.go:171","msg":"trace[1667865170] transaction","detail":"{read_only:false; response_revision:22165; number_of_response:1; }","duration":"144.412565ms","start":"2026-10-15T07:15:21.607295Z","end":"2026-10-15T07:15:21.751708Z","steps":["trace[1667865170] 'process raft request' (duration: 144.107158ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:21.751799Z","caller":"traceutil/trace.go:171","msg":"trace[1723773935] transaction","detail":"{read_only:false; response_revision:22162; number_of_response:1; }","duration":"160.289021ms","start":"2026-10-15T07:15:21.591497Z","end":"2026-10-15T07:15:21.751786Z","steps":["trace[1723773935] 'process raft request' (duration: 117.693117ms)","trace[1723773935] 'compare' (duration: 41.884388ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:15:21.751885Z","caller":"traceutil/trace.go:171","msg":"trace[85408646] transaction","detail":"{read_only:false; response_revision:22166; number_of_response:1; }","duration":"141.361945ms","start":"2026-10-15T07:15:21.610509Z","end":"2026-10-15T07:15:21.751871Z","steps":["trace[85408646] 'process raft request' (duration: 140.925625ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:21.751941Z","caller":"traceutil/trace.go:171","msg":"trace[2068526102] transaction","detail":"{read_only:false; response_revision:22163; number_of_response:1; }","duration":"159.958593ms","start":"2026-10-15T07:15:21.591974Z","end":"2026-10-15T07:15:21.751932Z","steps":["trace[2068526102] 'process raft request' (duration: 159.344819ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:21.751997Z","caller":"traceutil/trace.go:171","msg":"trace[598831897] transaction","detail":"{read_only:false; response_revision:22164; number_of_response:1; }","duration":"149.516033ms","start":"2026-10-15T07:15:21.602448Z","end":"2026-10-15T07:15:21.751964Z","steps":["trace[598831897] 'process raft request' (duration: 148.919078ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:51.489233Z","caller":"traceutil/trace.go:171","msg":"trace[1531140686] transaction","detail":"{read_only:false; response_revision:22451; number_of_response:1; }","duration":"146.129595ms","start":"2026-10-15T07:15:51.343076Z","end":"2026-10-15T07:15:51.489205Z","steps":["trace[1531140686] 'process raft request' (duration: 130.412372ms)","trace[1531140686] 'compare' (duration: 15.545009ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:15:51.749559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.582796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T07:15:51.750073Z","caller":"traceutil/trace.go:171","msg":"trace[1679895943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22451; }","duration":"125.191611ms","start":"2026-10-15T07:15:51.624862Z","end":"2026-10-15T07:15:51.750053Z","steps":["trace[1679895943] 'range keys from in-memory index tree' (duration: 124.395563ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:52.099082Z","caller":"traceutil/trace.go:171","msg":"trace[180776999] linearizableReadLoop","detail":"{readStateIndex:23223; appliedIndex:23222; }","duration":"152.00919ms","start":"2026-10-15T07:15:51.947052Z","end":"2026-10-15T07:15:52.099061Z","steps":["trace[180776999] 'read index received' (duration: 151.784415ms)","trace[180776999] 'applied index is now lower than readState.Index' (duration: 223.905µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:15:52.099204Z","caller":"traceutil/trace.go:171","msg":"trace[1328194813] transaction","detail":"{read_only:false; response_revision:22455; number_of_response:1; }","duration":"159.324409ms","start":"2026-10-15T07:15:51.939838Z","end":"2026-10-15T07:15:52.099163Z","steps":["trace[1328194813] 'process raft request' (duration: 159.064244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:15:52.099428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.371099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-15T07:15:52.099475Z","caller":"traceutil/trace.go:171","msg":"trace[354268811] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:22455; }","duration":"152.460921ms","start":"2026-10-15T07:15:51.947Z","end":"2026-10-15T07:15:52.099461Z","steps":["trace[354268811] 'agreement among raft nodes before linearized reading' (duration: 152.167504ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:52.200851Z","caller":"traceutil/trace.go:171","msg":"trace[1958063797] linearizableReadLoop","detail":"{readStateIndex:23224; appliedIndex:23223; }","duration":"101.684738ms","start":"2026-10-15T07:15:52.099142Z","end":"2026-10-15T07:15:52.200826Z","steps":["trace[1958063797] 'read index received' (duration: 83.15424ms)","trace[1958063797] 'applied index is now lower than readState.Index' (duration: 18.529388ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:15:52.201028Z","caller":"traceutil/trace.go:171","msg":"trace[1848713777] transaction","detail":"{read_only:false; response_revision:22456; number_of_response:1; }","duration":"229.896089ms","start":"2026-10-15T07:15:51.971111Z","end":"2026-10-15T07:15:52.201007Z","steps":["trace[1848713777] 'process raft request' (duration: 211.255468ms)","trace[1848713777] 'compare' (duration: 18.325963ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:15:52.201281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.635508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T07:15:52.201348Z","caller":"traceutil/trace.go:171","msg":"trace[1241093676] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22456; }","duration":"150.781702ms","start":"2026-10-15T07:15:52.050547Z","end":"2026-10-15T07:15:52.201329Z","steps":["trace[1241093676] 'agreement among raft nodes before linearized reading' (duration: 150.581357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:15:52.201335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.747763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:15:52.201447Z","caller":"traceutil/trace.go:171","msg":"trace[612389338] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:22456; }","duration":"115.944898ms","start":"2026-10-15T07:15:52.085473Z","end":"2026-10-15T07:15:52.201418Z","steps":["trace[612389338] 'agreement among raft nodes before linearized reading' (duration: 115.709222ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:56.001116Z","caller":"traceutil/trace.go:171","msg":"trace[709941498] transaction","detail":"{read_only:false; response_revision:22474; number_of_response:1; }","duration":"159.065375ms","start":"2026-10-15T07:15:55.842017Z","end":"2026-10-15T07:15:56.001082Z","steps":["trace[709941498] 'process raft request' (duration: 98.107791ms)","trace[709941498] 'compare' (duration: 60.76996ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:02.406888Z","caller":"traceutil/trace.go:171","msg":"trace[756941599] linearizableReadLoop","detail":"{readStateIndex:23330; appliedIndex:23328; }","duration":"118.963321ms","start":"2026-10-15T07:16:02.287889Z","end":"2026-10-15T07:16:02.406853Z","steps":["trace[756941599] 'read index received' (duration: 53.590925ms)","trace[756941599] 'applied index is now lower than readState.Index' (duration: 65.371396ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:02.407017Z","caller":"traceutil/trace.go:171","msg":"trace[430227793] transaction","detail":"{read_only:false; response_revision:22560; number_of_response:1; }","duration":"139.904223ms","start":"2026-10-15T07:16:02.267094Z","end":"2026-10-15T07:16:02.406998Z","steps":["trace[430227793] 'process raft request' (duration: 139.571816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:02.407078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.168266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:16:02.40713Z","caller":"traceutil/trace.go:171","msg":"trace[5448497] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:22561; }","duration":"119.264348ms","start":"2026-10-15T07:16:02.287852Z","end":"2026-10-15T07:16:02.407116Z","steps":["trace[5448497] 'agreement among raft nodes before linearized reading' (duration: 119.174316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:10.305202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.295718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/capi-kubeadm-bootstrap-system/bootstrap-kubeadm\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:16:10.305285Z","caller":"traceutil/trace.go:171","msg":"trace[1655432556] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/capi-kubeadm-bootstrap-system/bootstrap-kubeadm; range_end:; response_count:0; response_revision:22861; }","duration":"135.433801ms","start":"2026-10-15T07:16:10.169837Z","end":"2026-10-15T07:16:10.30527Z","steps":["trace[1655432556] 'agreement among raft nodes before linearized reading' (duration: 135.297668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:10.305326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.985946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager-6f6766ffcc-trst7\" ","response":"range_response_count:1 size:4305"} {"level":"info","ts":"2026-10-15T07:16:10.305395Z","caller":"traceutil/trace.go:171","msg":"trace[2039227021] range","detail":"{range_begin:/registry/pods/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager-6f6766ffcc-trst7; range_end:; response_count:1; response_revision:22861; }","duration":"137.076239ms","start":"2026-10-15T07:16:10.168303Z","end":"2026-10-15T07:16:10.305379Z","steps":["trace[2039227021] 'agreement among raft nodes before linearized reading' (duration: 136.875565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:10.305222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.307908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager\" ","response":"range_response_count:1 size:4945"} {"level":"info","ts":"2026-10-15T07:16:10.305552Z","caller":"traceutil/trace.go:171","msg":"trace[328432224] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager; range_end:; response_count:1; response_revision:22861; }","duration":"135.628775ms","start":"2026-10-15T07:16:10.169886Z","end":"2026-10-15T07:16:10.305514Z","steps":["trace[328432224] 'agreement among raft nodes before linearized reading' (duration: 135.196656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:10.305654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.869784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-10-15T07:16:10.305683Z","caller":"traceutil/trace.go:171","msg":"trace[8138370] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:22861; }","duration":"133.911565ms","start":"2026-10-15T07:16:10.171764Z","end":"2026-10-15T07:16:10.305675Z","steps":["trace[8138370] 'agreement among raft nodes before linearized reading' (duration: 133.314082ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:11.692601Z","caller":"traceutil/trace.go:171","msg":"trace[1909842287] linearizableReadLoop","detail":"{readStateIndex:23788; appliedIndex:23787; }","duration":"141.01858ms","start":"2026-10-15T07:16:11.551565Z","end":"2026-10-15T07:16:11.692584Z","steps":["trace[1909842287] 'read index received' (duration: 140.983159ms)","trace[1909842287] 'applied index is now lower than readState.Index' (duration: 34.631µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:11.692669Z","caller":"traceutil/trace.go:171","msg":"trace[2126121340] transaction","detail":"{read_only:false; response_revision:23007; number_of_response:1; }","duration":"144.08499ms","start":"2026-10-15T07:16:11.548556Z","end":"2026-10-15T07:16:11.692641Z","steps":["trace[2126121340] 'process raft request' (duration: 143.908766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:11.692887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.303246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmconfigs.bootstrap.cluster.x-k8s.io\" ","response":"range_response_count:1 size:159521"} {"level":"info","ts":"2026-10-15T07:16:11.692947Z","caller":"traceutil/trace.go:171","msg":"trace[1176246277] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmconfigs.bootstrap.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23007; }","duration":"141.376738ms","start":"2026-10-15T07:16:11.551555Z","end":"2026-10-15T07:16:11.692931Z","steps":["trace[1176246277] 'agreement among raft nodes before linearized reading' (duration: 141.152103ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:11.763972Z","caller":"traceutil/trace.go:171","msg":"trace[937563798] transaction","detail":"{read_only:false; response_revision:23010; number_of_response:1; }","duration":"123.218809ms","start":"2026-10-15T07:16:11.640725Z","end":"2026-10-15T07:16:11.763944Z","steps":["trace[937563798] 'process raft request' (duration: 123.150328ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:11.764163Z","caller":"traceutil/trace.go:171","msg":"trace[1875477043] transaction","detail":"{read_only:false; response_revision:23009; number_of_response:1; }","duration":"174.117582ms","start":"2026-10-15T07:16:11.590014Z","end":"2026-10-15T07:16:11.764131Z","steps":["trace[1875477043] 'process raft request' (duration: 173.787444ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:11.764107Z","caller":"traceutil/trace.go:171","msg":"trace[471867492] transaction","detail":"{read_only:false; response_revision:23008; number_of_response:1; }","duration":"190.660534ms","start":"2026-10-15T07:16:11.573303Z","end":"2026-10-15T07:16:11.763964Z","steps":["trace[471867492] 'process raft request' (duration: 190.281235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:11.764632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.110873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128259"} {"level":"info","ts":"2026-10-15T07:16:11.764695Z","caller":"traceutil/trace.go:171","msg":"trace[53061237] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:23011; }","duration":"171.221126ms","start":"2026-10-15T07:16:11.593458Z","end":"2026-10-15T07:16:11.764679Z","steps":["trace[53061237] 'agreement among raft nodes before linearized reading' (duration: 170.97842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:11.765069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.390093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-10-15T07:16:11.765141Z","caller":"traceutil/trace.go:171","msg":"trace[2013082250] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:23011; }","duration":"185.498696ms","start":"2026-10-15T07:16:11.579625Z","end":"2026-10-15T07:16:11.765124Z","steps":["trace[2013082250] 'agreement among raft nodes before linearized reading' (duration: 184.618255ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:11.885479Z","caller":"traceutil/trace.go:171","msg":"trace[319460393] transaction","detail":"{read_only:false; response_revision:23014; number_of_response:1; }","duration":"116.414633ms","start":"2026-10-15T07:16:11.769046Z","end":"2026-10-15T07:16:11.885461Z","steps":["trace[319460393] 'process raft request' (duration: 116.214188ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:11.999221Z","caller":"traceutil/trace.go:171","msg":"trace[445956234] transaction","detail":"{read_only:false; response_revision:23018; number_of_response:1; }","duration":"102.582304ms","start":"2026-10-15T07:16:11.896603Z","end":"2026-10-15T07:16:11.999186Z","steps":["trace[445956234] 'process raft request' (duration: 46.580654ms)","trace[445956234] 'compare' (duration: 55.866237ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:12.009353Z","caller":"traceutil/trace.go:171","msg":"trace[1250803581] transaction","detail":"{read_only:false; response_revision:23019; number_of_response:1; }","duration":"108.825548ms","start":"2026-10-15T07:16:11.900492Z","end":"2026-10-15T07:16:12.009317Z","steps":["trace[1250803581] 'process raft request' (duration: 108.625324ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:12.00947Z","caller":"traceutil/trace.go:171","msg":"trace[540721043] transaction","detail":"{read_only:false; response_revision:23020; number_of_response:1; }","duration":"108.809648ms","start":"2026-10-15T07:16:11.900614Z","end":"2026-10-15T07:16:12.009424Z","steps":["trace[540721043] 'process raft request' (duration: 108.600163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:12.009728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.901323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-10-15T07:16:12.009537Z","caller":"traceutil/trace.go:171","msg":"trace[989859876] linearizableReadLoop","detail":"{readStateIndex:23801; appliedIndex:23798; }","duration":"106.715899ms","start":"2026-10-15T07:16:11.902803Z","end":"2026-10-15T07:16:12.009519Z","steps":["trace[989859876] 'read index received' (duration: 40.391831ms)","trace[989859876] 'applied index is now lower than readState.Index' (duration: 66.322218ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:12.009775Z","caller":"traceutil/trace.go:171","msg":"trace[886643218] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:23022; }","duration":"106.982325ms","start":"2026-10-15T07:16:11.902777Z","end":"2026-10-15T07:16:12.009759Z","steps":["trace[886643218] 'agreement among raft nodes before linearized reading' (duration: 106.789721ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:12.094814Z","caller":"traceutil/trace.go:171","msg":"trace[1222781616] transaction","detail":"{read_only:false; response_revision:23023; number_of_response:1; }","duration":"124.171862ms","start":"2026-10-15T07:16:11.970615Z","end":"2026-10-15T07:16:12.094787Z","steps":["trace[1222781616] 'process raft request' (duration: 124.008788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:12.09502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.803518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-15T07:16:12.094992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.137732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capo-system/capo-controller-manager\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:16:12.095103Z","caller":"traceutil/trace.go:171","msg":"trace[971221805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23023; }","duration":"174.938591ms","start":"2026-10-15T07:16:11.920141Z","end":"2026-10-15T07:16:12.09508Z","steps":["trace[971221805] 'agreement among raft nodes before linearized reading' (duration: 174.659645ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:12.095113Z","caller":"traceutil/trace.go:171","msg":"trace[740876482] range","detail":"{range_begin:/registry/deployments/capo-system/capo-controller-manager; range_end:; response_count:0; response_revision:23023; }","duration":"187.301076ms","start":"2026-10-15T07:16:11.907792Z","end":"2026-10-15T07:16:12.095093Z","steps":["trace[740876482] 'agreement among raft nodes before linearized reading' (duration: 187.107001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:12.129925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.715116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-10-15T07:16:12.13002Z","caller":"traceutil/trace.go:171","msg":"trace[42144122] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:23024; }","duration":"115.813628ms","start":"2026-10-15T07:16:12.014166Z","end":"2026-10-15T07:16:12.12998Z","steps":["trace[42144122] 'agreement among raft nodes before linearized reading' (duration: 115.562002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:12.130129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.169537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanes.controlplane.cluster.x-k8s.io\" ","response":"range_response_count:1 size:199154"} {"level":"info","ts":"2026-10-15T07:16:12.130213Z","caller":"traceutil/trace.go:171","msg":"trace[1561461767] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanes.controlplane.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23024; }","duration":"116.287579ms","start":"2026-10-15T07:16:12.013903Z","end":"2026-10-15T07:16:12.13019Z","steps":["trace[1561461767] 'agreement among raft nodes before linearized reading' (duration: 115.967722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:12.306516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.6733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capo-system/capo-controller-manager-8c5df4f-8xjjm\" ","response":"range_response_count:1 size:3536"} {"level":"warn","ts":"2026-10-15T07:16:12.306573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.954987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111939"} {"level":"warn","ts":"2026-10-15T07:16:12.306567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.018037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-10-15T07:16:12.306589Z","caller":"traceutil/trace.go:171","msg":"trace[489534419] range","detail":"{range_begin:/registry/pods/capo-system/capo-controller-manager-8c5df4f-8xjjm; range_end:; response_count:1; response_revision:23044; }","duration":"100.754632ms","start":"2026-10-15T07:16:12.205815Z","end":"2026-10-15T07:16:12.30657Z","steps":["trace[489534419] 'agreement among raft nodes before linearized reading' (duration: 100.580468ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:12.306601Z","caller":"traceutil/trace.go:171","msg":"trace[1790082562] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:23044; }","duration":"101.013497ms","start":"2026-10-15T07:16:12.205581Z","end":"2026-10-15T07:16:12.306594Z","steps":["trace[1790082562] 'agreement among raft nodes before linearized reading' (duration: 100.812603ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:12.306618Z","caller":"traceutil/trace.go:171","msg":"trace[1534938710] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:23044; }","duration":"101.10811ms","start":"2026-10-15T07:16:12.205498Z","end":"2026-10-15T07:16:12.306606Z","steps":["trace[1534938710] 'agreement among raft nodes before linearized reading' (duration: 100.997357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:12.306517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.44691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-10-15T07:16:12.306703Z","caller":"traceutil/trace.go:171","msg":"trace[731152046] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:23044; }","duration":"105.701916ms","start":"2026-10-15T07:16:12.200993Z","end":"2026-10-15T07:16:12.306695Z","steps":["trace[731152046] 'agreement among raft nodes before linearized reading' (duration: 105.351088ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:12.365815Z","caller":"traceutil/trace.go:171","msg":"trace[1631570413] transaction","detail":"{read_only:false; response_revision:23045; number_of_response:1; }","duration":"118.337627ms","start":"2026-10-15T07:16:12.247444Z","end":"2026-10-15T07:16:12.365781Z","steps":["trace[1631570413] 'process raft request' (duration: 95.91863ms)","trace[1631570413] 'compare' (duration: 22.14985ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:12.365895Z","caller":"traceutil/trace.go:171","msg":"trace[257837519] transaction","detail":"{read_only:false; response_revision:23046; number_of_response:1; }","duration":"113.800773ms","start":"2026-10-15T07:16:12.252075Z","end":"2026-10-15T07:16:12.365876Z","steps":["trace[257837519] 'process raft request' (duration: 113.618939ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:20.676707Z","caller":"traceutil/trace.go:171","msg":"trace[844278435] linearizableReadLoop","detail":"{readStateIndex:24025; appliedIndex:24022; }","duration":"113.496885ms","start":"2026-10-15T07:16:20.563193Z","end":"2026-10-15T07:16:20.67669Z","steps":["trace[844278435] 'read index received' (duration: 73.917463ms)","trace[844278435] 'applied index is now lower than readState.Index' (duration: 39.578022ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:20.676814Z","caller":"traceutil/trace.go:171","msg":"trace[838089063] transaction","detail":"{read_only:false; response_revision:23238; number_of_response:1; }","duration":"204.055943ms","start":"2026-10-15T07:16:20.472748Z","end":"2026-10-15T07:16:20.676804Z","steps":["trace[838089063] 'process raft request' (duration: 164.375228ms)","trace[838089063] 'compare' (duration: 39.358577ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:20.676881Z","caller":"traceutil/trace.go:171","msg":"trace[1168817105] transaction","detail":"{read_only:false; response_revision:23240; number_of_response:1; }","duration":"140.447117ms","start":"2026-10-15T07:16:20.536396Z","end":"2026-10-15T07:16:20.676843Z","steps":["trace[1168817105] 'process raft request' (duration: 140.264532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:20.677218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.977997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T07:16:20.677276Z","caller":"traceutil/trace.go:171","msg":"trace[1416206311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23240; }","duration":"114.10812ms","start":"2026-10-15T07:16:20.563159Z","end":"2026-10-15T07:16:20.677268Z","steps":["trace[1416206311] 'agreement among raft nodes before linearized reading' (duration: 113.872185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:20.677207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.947366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T07:16:20.677346Z","caller":"traceutil/trace.go:171","msg":"trace[1687368121] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23240; }","duration":"114.11091ms","start":"2026-10-15T07:16:20.563226Z","end":"2026-10-15T07:16:20.677337Z","steps":["trace[1687368121] 'agreement among raft nodes before linearized reading' (duration: 113.713291ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:20.677179Z","caller":"traceutil/trace.go:171","msg":"trace[1555609858] transaction","detail":"{read_only:false; response_revision:23239; number_of_response:1; }","duration":"145.844481ms","start":"2026-10-15T07:16:20.531283Z","end":"2026-10-15T07:16:20.677127Z","steps":["trace[1555609858] 'process raft request' (duration: 145.323638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:21.226828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.579216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:16:21.22698Z","caller":"traceutil/trace.go:171","msg":"trace[1572013480] transaction","detail":"{read_only:false; response_revision:23241; number_of_response:1; }","duration":"545.715744ms","start":"2026-10-15T07:16:20.681243Z","end":"2026-10-15T07:16:21.226959Z","steps":["trace[1572013480] 'process raft request' (duration: 96.934843ms)","trace[1572013480] 'compare' (duration: 448.367282ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:16:21.227046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:16:20.681223Z","time spent":"545.795686ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:16:21.227955Z","caller":"traceutil/trace.go:171","msg":"trace[1448533771] linearizableReadLoop","detail":"{readStateIndex:24027; appliedIndex:24025; }","duration":"345.311466ms","start":"2026-10-15T07:16:20.882635Z","end":"2026-10-15T07:16:21.227946Z","steps":["trace[1448533771] 'read index received' (duration: 256.440949ms)","trace[1448533771] 'applied index is now lower than readState.Index' (duration: 88.869877ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:21.22803Z","caller":"traceutil/trace.go:171","msg":"trace[61002467] transaction","detail":"{read_only:false; response_revision:23242; number_of_response:1; }","duration":"545.350736ms","start":"2026-10-15T07:16:20.682672Z","end":"2026-10-15T07:16:21.228023Z","steps":["trace[61002467] 'process raft request' (duration: 544.23035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:21.22809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:16:20.682637Z","time spent":"545.428308ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:16:21.22822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.733046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-15T07:16:21.22847Z","caller":"traceutil/trace.go:171","msg":"trace[1988024828] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:23242; }","duration":"303.017054ms","start":"2026-10-15T07:16:20.925438Z","end":"2026-10-15T07:16:21.228455Z","steps":["trace[1988024828] 'agreement among raft nodes before linearized reading' (duration: 302.659834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:21.22856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:16:20.925422Z","time spent":"303.128116ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-10-15T07:16:21.228235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.603543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-15T07:16:21.229145Z","caller":"traceutil/trace.go:171","msg":"trace[1232111431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23242; }","duration":"346.539086ms","start":"2026-10-15T07:16:20.882598Z","end":"2026-10-15T07:16:21.229138Z","steps":["trace[1232111431] 'agreement among raft nodes before linearized reading' (duration: 345.554233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:21.229179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:16:20.882585Z","time spent":"346.585297ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T07:16:21.228233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.378017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"warn","ts":"2026-10-15T07:16:21.228241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.108646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:16:21.229568Z","caller":"traceutil/trace.go:171","msg":"trace[161138111] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:23242; }","duration":"137.448487ms","start":"2026-10-15T07:16:21.092098Z","end":"2026-10-15T07:16:21.229547Z","steps":["trace[161138111] 'agreement among raft nodes before linearized reading' (duration: 136.048195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:21.228325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.007721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:16:21.22971Z","caller":"traceutil/trace.go:171","msg":"trace[1916765797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23242; }","duration":"123.429414ms","start":"2026-10-15T07:16:21.106272Z","end":"2026-10-15T07:16:21.229701Z","steps":["trace[1916765797] 'agreement among raft nodes before linearized reading' (duration: 122.018582ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:21.230151Z","caller":"traceutil/trace.go:171","msg":"trace[1735585113] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23242; }","duration":"201.526374ms","start":"2026-10-15T07:16:21.027798Z","end":"2026-10-15T07:16:21.229324Z","steps":["trace[1735585113] 'agreement among raft nodes before linearized reading' (duration: 200.345036ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:27.426475Z","caller":"traceutil/trace.go:171","msg":"trace[1041091969] linearizableReadLoop","detail":"{readStateIndex:24094; appliedIndex:24093; }","duration":"156.331572ms","start":"2026-10-15T07:16:27.270095Z","end":"2026-10-15T07:16:27.426427Z","steps":["trace[1041091969] 'read index received' (duration: 128.4664ms)","trace[1041091969] 'applied index is now lower than readState.Index' (duration: 27.863952ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:27.42656Z","caller":"traceutil/trace.go:171","msg":"trace[382856903] transaction","detail":"{read_only:false; response_revision:23307; number_of_response:1; }","duration":"157.537509ms","start":"2026-10-15T07:16:27.268994Z","end":"2026-10-15T07:16:27.426531Z","steps":["trace[382856903] 'process raft request' (duration: 129.464022ms)","trace[382856903] 'compare' (duration: 27.793581ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:16:27.426646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.535156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:16:27.426687Z","caller":"traceutil/trace.go:171","msg":"trace[1743042148] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:23307; }","duration":"156.62927ms","start":"2026-10-15T07:16:27.270045Z","end":"2026-10-15T07:16:27.426674Z","steps":["trace[1743042148] 'agreement among raft nodes before linearized reading' (duration: 156.536688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:27.42706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.210384ms","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-10-15T07:16:27.427112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.897742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T07:16:27.427181Z","caller":"traceutil/trace.go:171","msg":"trace[574505878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23307; }","duration":"149.018334ms","start":"2026-10-15T07:16:27.278143Z","end":"2026-10-15T07:16:27.427161Z","steps":["trace[574505878] 'agreement among raft nodes before linearized reading' (duration: 148.775029ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:27.427164Z","caller":"traceutil/trace.go:171","msg":"trace[1305067906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23307; }","duration":"147.333406ms","start":"2026-10-15T07:16:27.279813Z","end":"2026-10-15T07:16:27.427147Z","steps":["trace[1305067906] 'agreement among raft nodes before linearized reading' (duration: 147.114711ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:27.613262Z","caller":"traceutil/trace.go:171","msg":"trace[694409389] transaction","detail":"{read_only:false; response_revision:23308; number_of_response:1; }","duration":"181.149795ms","start":"2026-10-15T07:16:27.432079Z","end":"2026-10-15T07:16:27.613229Z","steps":["trace[694409389] 'process raft request' (duration: 96.384261ms)","trace[694409389] 'compare' (duration: 84.484227ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:27.613626Z","caller":"traceutil/trace.go:171","msg":"trace[1570607623] transaction","detail":"{read_only:false; response_revision:23309; number_of_response:1; }","duration":"181.442412ms","start":"2026-10-15T07:16:27.432155Z","end":"2026-10-15T07:16:27.613598Z","steps":["trace[1570607623] 'process raft request' (duration: 181.009102ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:27.613649Z","caller":"traceutil/trace.go:171","msg":"trace[1858514996] transaction","detail":"{read_only:false; response_revision:23310; number_of_response:1; }","duration":"129.556346ms","start":"2026-10-15T07:16:27.484069Z","end":"2026-10-15T07:16:27.613626Z","steps":["trace[1858514996] 'process raft request' (duration: 129.413063ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:29.0678Z","caller":"traceutil/trace.go:171","msg":"trace[1125512404] linearizableReadLoop","detail":"{readStateIndex:24108; appliedIndex:24107; }","duration":"100.259229ms","start":"2026-10-15T07:16:28.967515Z","end":"2026-10-15T07:16:29.067774Z","steps":["trace[1125512404] 'read index received' (duration: 100.069565ms)","trace[1125512404] 'applied index is now lower than readState.Index' (duration: 188.744µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:16:29.067915Z","caller":"traceutil/trace.go:171","msg":"trace[1610460627] transaction","detail":"{read_only:false; response_revision:23321; number_of_response:1; }","duration":"103.941856ms","start":"2026-10-15T07:16:28.963947Z","end":"2026-10-15T07:16:29.067889Z","steps":["trace[1610460627] 'process raft request' (duration: 103.654028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:16:29.067994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.479136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-10-15T07:16:29.068032Z","caller":"traceutil/trace.go:171","msg":"trace[1347250079] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23321; }","duration":"100.562898ms","start":"2026-10-15T07:16:28.967457Z","end":"2026-10-15T07:16:29.068019Z","steps":["trace[1347250079] 'agreement among raft nodes before linearized reading' (duration: 100.424275ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:38.983709Z","caller":"traceutil/trace.go:171","msg":"trace[1300788388] transaction","detail":"{read_only:false; response_revision:23409; number_of_response:1; }","duration":"103.185468ms","start":"2026-10-15T07:16:38.880474Z","end":"2026-10-15T07:16:38.983659Z","steps":["trace[1300788388] 'process raft request' (duration: 47.994235ms)","trace[1300788388] 'compare' (duration: 54.978647ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:17:05.660973Z","caller":"traceutil/trace.go:171","msg":"trace[661906571] transaction","detail":"{read_only:false; response_revision:23665; number_of_response:1; }","duration":"175.511565ms","start":"2026-10-15T07:17:05.485439Z","end":"2026-10-15T07:17:05.66095Z","steps":["trace[661906571] 'process raft request' (duration: 175.258399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:25.789853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7130782873760915867,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T07:17:26.113217Z","caller":"traceutil/trace.go:171","msg":"trace[1317243936] transaction","detail":"{read_only:false; response_revision:23958; number_of_response:1; }","duration":"896.818955ms","start":"2026-10-15T07:17:25.216366Z","end":"2026-10-15T07:17:26.113185Z","steps":["trace[1317243936] 'process raft request' (duration: 896.58976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.113564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:25.216342Z","time spent":"896.983759ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:17:26.197843Z","caller":"traceutil/trace.go:171","msg":"trace[1256090468] transaction","detail":"{read_only:false; response_revision:23959; number_of_response:1; }","duration":"495.910028ms","start":"2026-10-15T07:17:25.701911Z","end":"2026-10-15T07:17:26.197821Z","steps":["trace[1256090468] 'process raft request' (duration: 493.646426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.197992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:25.701889Z","time spent":"496.025641ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:17:26.198321Z","caller":"traceutil/trace.go:171","msg":"trace[812357445] transaction","detail":"{read_only:false; response_revision:23961; number_of_response:1; }","duration":"243.305797ms","start":"2026-10-15T07:17:25.954976Z","end":"2026-10-15T07:17:26.198281Z","steps":["trace[812357445] 'process raft request' (duration: 243.01875ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:26.19846Z","caller":"traceutil/trace.go:171","msg":"trace[1617745066] linearizableReadLoop","detail":"{readStateIndex:24760; appliedIndex:24758; }","duration":"908.984016ms","start":"2026-10-15T07:17:25.289302Z","end":"2026-10-15T07:17:26.198286Z","steps":["trace[1617745066] 'read index received' (duration: 823.619148ms)","trace[1617745066] 'applied index is now lower than readState.Index' (duration: 85.363688ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:17:26.198447Z","caller":"traceutil/trace.go:171","msg":"trace[231734351] transaction","detail":"{read_only:false; response_revision:23960; number_of_response:1; }","duration":"245.144139ms","start":"2026-10-15T07:17:25.953258Z","end":"2026-10-15T07:17:26.198403Z","steps":["trace[231734351] 'process raft request' (duration: 244.509475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.198613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.945358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:17:26.198656Z","caller":"traceutil/trace.go:171","msg":"trace[1861408287] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:23962; }","duration":"857.029989ms","start":"2026-10-15T07:17:25.341616Z","end":"2026-10-15T07:17:26.198646Z","steps":["trace[1861408287] 'agreement among raft nodes before linearized reading' (duration: 856.943757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.198688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:25.341597Z","time spent":"857.08238ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":32,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:17:26.198654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.039158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:17:26.198732Z","caller":"traceutil/trace.go:171","msg":"trace[1335006851] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:23962; }","duration":"150.1678ms","start":"2026-10-15T07:17:26.04855Z","end":"2026-10-15T07:17:26.198718Z","steps":["trace[1335006851] 'agreement among raft nodes before linearized reading' (duration: 150.064438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.198846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.366191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T07:17:26.198878Z","caller":"traceutil/trace.go:171","msg":"trace[691437792] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23962; }","duration":"438.478394ms","start":"2026-10-15T07:17:25.760389Z","end":"2026-10-15T07:17:26.198867Z","steps":["trace[691437792] 'agreement among raft nodes before linearized reading' (duration: 438.368281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.198903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:25.760365Z","time spent":"438.528935ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T07:17:26.198898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.818743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-15T07:17:26.198888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.987575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:17:26.199002Z","caller":"traceutil/trace.go:171","msg":"trace[1063113873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23962; }","duration":"432.971846ms","start":"2026-10-15T07:17:25.766017Z","end":"2026-10-15T07:17:26.198989Z","steps":["trace[1063113873] 'agreement among raft nodes before linearized reading' (duration: 432.635489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.199012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.427468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T07:17:26.19905Z","caller":"traceutil/trace.go:171","msg":"trace[537151101] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:23962; }","duration":"544.21516ms","start":"2026-10-15T07:17:25.654809Z","end":"2026-10-15T07:17:26.199024Z","steps":["trace[537151101] 'agreement among raft nodes before linearized reading' (duration: 543.962595ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:26.199099Z","caller":"traceutil/trace.go:171","msg":"trace[1689539379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23962; }","duration":"121.56139ms","start":"2026-10-15T07:17:26.077521Z","end":"2026-10-15T07:17:26.199083Z","steps":["trace[1689539379] 'agreement among raft nodes before linearized reading' (duration: 121.362356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.199117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:25.654786Z","time spent":"544.311353ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:17:26.198986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.823386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:17:26.199204Z","caller":"traceutil/trace.go:171","msg":"trace[109578531] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:23962; }","duration":"342.094764ms","start":"2026-10-15T07:17:25.857083Z","end":"2026-10-15T07:17:26.199178Z","steps":["trace[109578531] 'agreement among raft nodes before linearized reading' (duration: 341.735815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.199266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:25.857063Z","time spent":"342.183096ms","remote":"127.0.0.1:48514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:17:26.199039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:25.766005Z","time spent":"433.025598ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T07:17:26.199245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.839357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:17:26.199441Z","caller":"traceutil/trace.go:171","msg":"trace[969962836] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:23962; }","duration":"910.150543ms","start":"2026-10-15T07:17:25.289252Z","end":"2026-10-15T07:17:26.199403Z","steps":["trace[969962836] 'agreement among raft nodes before linearized reading' (duration: 909.258802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:26.199495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:25.289236Z","time spent":"910.234595ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:17:26.199612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.353471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-15T07:17:26.199713Z","caller":"traceutil/trace.go:171","msg":"trace[896423944] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:23962; }","duration":"182.496465ms","start":"2026-10-15T07:17:26.017187Z","end":"2026-10-15T07:17:26.199684Z","steps":["trace[896423944] 'agreement among raft nodes before linearized reading' (duration: 181.625114ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:00.484636Z","caller":"traceutil/trace.go:171","msg":"trace[582752158] transaction","detail":"{read_only:false; response_revision:24276; number_of_response:1; }","duration":"136.67893ms","start":"2026-10-15T07:18:00.347932Z","end":"2026-10-15T07:18:00.484611Z","steps":["trace[582752158] 'process raft request' (duration: 74.972339ms)","trace[582752158] 'compare' (duration: 61.535658ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:18:00.484649Z","caller":"traceutil/trace.go:171","msg":"trace[962579884] transaction","detail":"{read_only:false; response_revision:24277; number_of_response:1; }","duration":"124.806516ms","start":"2026-10-15T07:18:00.359824Z","end":"2026-10-15T07:18:00.484631Z","steps":["trace[962579884] 'process raft request' (duration: 124.727724ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:00.484823Z","caller":"traceutil/trace.go:171","msg":"trace[728118699] transaction","detail":"{read_only:false; response_revision:24278; number_of_response:1; }","duration":"111.251494ms","start":"2026-10-15T07:18:00.373564Z","end":"2026-10-15T07:18:00.484815Z","steps":["trace[728118699] 'process raft request' (duration: 111.039119ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:01.691249Z","caller":"traceutil/trace.go:171","msg":"trace[1192037401] transaction","detail":"{read_only:false; response_revision:24300; number_of_response:1; }","duration":"126.502746ms","start":"2026-10-15T07:18:01.564716Z","end":"2026-10-15T07:18:01.691219Z","steps":["trace[1192037401] 'process raft request' (duration: 121.266445ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:21.856302Z","caller":"traceutil/trace.go:171","msg":"trace[785601594] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"170.809596ms","start":"2026-10-15T07:18:21.685466Z","end":"2026-10-15T07:18:21.856276Z","steps":["trace[785601594] 'process raft request' (duration: 170.557421ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:45.396562Z","caller":"traceutil/trace.go:171","msg":"trace[440163602] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"252.524889ms","start":"2026-10-15T07:19:45.143983Z","end":"2026-10-15T07:19:45.396508Z","steps":["trace[440163602] 'process raft request' (duration: 252.253583ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:45.396479Z","caller":"traceutil/trace.go:171","msg":"trace[2028855136] linearizableReadLoop","detail":"{readStateIndex:26063; appliedIndex:26062; }","duration":"250.476293ms","start":"2026-10-15T07:19:45.14597Z","end":"2026-10-15T07:19:45.396446Z","steps":["trace[2028855136] 'read index received' (duration: 250.192306ms)","trace[2028855136] 'applied index is now lower than readState.Index' (duration: 283.106µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:19:45.396892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.901273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:19:45.396943Z","caller":"traceutil/trace.go:171","msg":"trace[1005679295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25230; }","duration":"251.022725ms","start":"2026-10-15T07:19:45.145906Z","end":"2026-10-15T07:19:45.396929Z","steps":["trace[1005679295] 'agreement among raft nodes before linearized reading' (duration: 250.893682ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:46.003442Z","caller":"traceutil/trace.go:171","msg":"trace[356870609] linearizableReadLoop","detail":"{readStateIndex:26064; appliedIndex:26063; }","duration":"523.525544ms","start":"2026-10-15T07:19:45.479884Z","end":"2026-10-15T07:19:46.00341Z","steps":["trace[356870609] 'read index received' (duration: 483.481951ms)","trace[356870609] 'applied index is now lower than readState.Index' (duration: 40.041593ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:19:46.003507Z","caller":"traceutil/trace.go:171","msg":"trace[1313440583] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"560.806664ms","start":"2026-10-15T07:19:45.442658Z","end":"2026-10-15T07:19:46.003465Z","steps":["trace[1313440583] 'process raft request' (duration: 520.598497ms)","trace[1313440583] 'compare' (duration: 39.296376ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:19:46.004212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.442617Z","time spent":"561.259923ms","remote":"127.0.0.1:43070","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-10-15T07:19:46.005677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.806854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T07:19:46.00579Z","caller":"traceutil/trace.go:171","msg":"trace[1902578181] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25231; }","duration":"526.139923ms","start":"2026-10-15T07:19:45.479625Z","end":"2026-10-15T07:19:46.005765Z","steps":["trace[1902578181] 'agreement among raft nodes before linearized reading' (duration: 524.127277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.005861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.479603Z","time spent":"526.239276ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T07:19:46.089878Z","caller":"traceutil/trace.go:171","msg":"trace[1502721425] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"478.461046ms","start":"2026-10-15T07:19:45.611381Z","end":"2026-10-15T07:19:46.089842Z","steps":["trace[1502721425] 'process raft request' (duration: 478.203559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.089924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.871465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-15T07:19:46.089963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.713331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T07:19:46.090027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.611356Z","time spent":"478.599139ms","remote":"127.0.0.1:43070","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-10-15T07:19:46.090107Z","caller":"traceutil/trace.go:171","msg":"trace[1013993545] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25232; }","duration":"376.863364ms","start":"2026-10-15T07:19:45.713195Z","end":"2026-10-15T07:19:46.090058Z","steps":["trace[1013993545] 'agreement among raft nodes before linearized reading' (duration: 376.712221ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:46.090013Z","caller":"traceutil/trace.go:171","msg":"trace[229087807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25232; }","duration":"334.011278ms","start":"2026-10-15T07:19:45.755976Z","end":"2026-10-15T07:19:46.089988Z","steps":["trace[229087807] 'agreement among raft nodes before linearized reading' (duration: 333.792872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.09014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.713175Z","time spent":"376.956886ms","remote":"127.0.0.1:48706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:19:46.090217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.835889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"warn","ts":"2026-10-15T07:19:46.09021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.755957Z","time spent":"334.205832ms","remote":"127.0.0.1:43070","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-10-15T07:19:46.090199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.253025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T07:19:46.090267Z","caller":"traceutil/trace.go:171","msg":"trace[1456159856] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25232; }","duration":"496.927651ms","start":"2026-10-15T07:19:45.593325Z","end":"2026-10-15T07:19:46.090252Z","steps":["trace[1456159856] 'agreement among raft nodes before linearized reading' (duration: 496.728996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.090314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.593304Z","time spent":"496.998573ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-15T07:19:46.090347Z","caller":"traceutil/trace.go:171","msg":"trace[824911439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25232; }","duration":"269.397719ms","start":"2026-10-15T07:19:45.820893Z","end":"2026-10-15T07:19:46.090291Z","steps":["trace[824911439] 'agreement among raft nodes before linearized reading' (duration: 269.096532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.090358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.839044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-15T07:19:46.09046Z","caller":"traceutil/trace.go:171","msg":"trace[710434309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25232; }","duration":"606.964977ms","start":"2026-10-15T07:19:45.483475Z","end":"2026-10-15T07:19:46.09044Z","steps":["trace[710434309] 'agreement among raft nodes before linearized reading' (duration: 606.65304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.090511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.483469Z","time spent":"607.033148ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T07:19:46.08987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.394525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T07:19:46.090576Z","caller":"traceutil/trace.go:171","msg":"trace[853388118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25232; }","duration":"607.122291ms","start":"2026-10-15T07:19:45.483432Z","end":"2026-10-15T07:19:46.090555Z","steps":["trace[853388118] 'agreement among raft nodes before linearized reading' (duration: 606.316812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.090672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.483425Z","time spent":"607.228953ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T07:19:46.090828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.280574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:19:46.091047Z","caller":"traceutil/trace.go:171","msg":"trace[19636992] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25232; }","duration":"423.792535ms","start":"2026-10-15T07:19:45.667224Z","end":"2026-10-15T07:19:46.091017Z","steps":["trace[19636992] 'agreement among raft nodes before linearized reading' (duration: 422.66221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.091174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:45.667196Z","time spent":"423.960889ms","remote":"127.0.0.1:55348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:19:46.746857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.061364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:19:46.747025Z","caller":"traceutil/trace.go:171","msg":"trace[1953107778] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"729.107911ms","start":"2026-10-15T07:19:46.01788Z","end":"2026-10-15T07:19:46.746988Z","steps":["trace[1953107778] 'process raft request' (duration: 205.800822ms)","trace[1953107778] 'compare' (duration: 522.786458ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:19:46.747204Z","caller":"traceutil/trace.go:171","msg":"trace[922476777] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"651.628876ms","start":"2026-10-15T07:19:46.095555Z","end":"2026-10-15T07:19:46.747184Z","steps":["trace[922476777] 'process raft request' (duration: 651.505743ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:46.747219Z","caller":"traceutil/trace.go:171","msg":"trace[951442580] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"652.197499ms","start":"2026-10-15T07:19:46.094958Z","end":"2026-10-15T07:19:46.747155Z","steps":["trace[951442580] 'process raft request' (duration: 652.014165ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:46.74711Z","caller":"traceutil/trace.go:171","msg":"trace[1172362465] linearizableReadLoop","detail":"{readStateIndex:26066; appliedIndex:26065; }","duration":"652.637079ms","start":"2026-10-15T07:19:46.094425Z","end":"2026-10-15T07:19:46.747062Z","steps":["trace[1172362465] 'read index received' (duration: 129.264888ms)","trace[1172362465] 'applied index is now lower than readState.Index' (duration: 523.370241ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:19:46.747298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.865354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"warn","ts":"2026-10-15T07:19:46.747298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:46.09553Z","time spent":"651.719838ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:19:46.747335Z","caller":"traceutil/trace.go:171","msg":"trace[1918239496] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25235; }","duration":"652.927046ms","start":"2026-10-15T07:19:46.094398Z","end":"2026-10-15T07:19:46.747325Z","steps":["trace[1918239496] 'agreement among raft nodes before linearized reading' (duration: 652.748862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.747364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:46.094382Z","time spent":"652.975717ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-15T07:19:46.747254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:46.017856Z","time spent":"729.311446ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:19:46.747394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:46.094937Z","time spent":"652.367343ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:19:46.747438Z","caller":"traceutil/trace.go:171","msg":"trace[552201204] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"651.482472ms","start":"2026-10-15T07:19:46.095936Z","end":"2026-10-15T07:19:46.747419Z","steps":["trace[552201204] 'process raft request' (duration: 651.202956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.747504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:46.095916Z","time spent":"651.554184ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:19:46.747552Z","caller":"traceutil/trace.go:171","msg":"trace[1352121857] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"651.548004ms","start":"2026-10-15T07:19:46.09596Z","end":"2026-10-15T07:19:46.747508Z","steps":["trace[1352121857] 'process raft request' (duration: 651.3683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.747661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:46.095948Z","time spent":"651.655726ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:19:46.89561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.401295ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-15T07:19:46.895947Z","caller":"traceutil/trace.go:171","msg":"trace[1293525183] linearizableReadLoop","detail":"{readStateIndex:26072; appliedIndex:26070; }","duration":"148.626685ms","start":"2026-10-15T07:19:46.747295Z","end":"2026-10-15T07:19:46.895922Z","steps":["trace[1293525183] 'read index received' (duration: 17.905523ms)","trace[1293525183] 'applied index is now lower than readState.Index' (duration: 130.719272ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:19:46.895995Z","caller":"traceutil/trace.go:171","msg":"trace[1596245628] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"198.73223ms","start":"2026-10-15T07:19:46.697237Z","end":"2026-10-15T07:19:46.895969Z","steps":["trace[1596245628] 'process raft request' (duration: 198.485035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.896197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.594632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T07:19:46.896242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.489759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18984"} {"level":"warn","ts":"2026-10-15T07:19:46.896256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.813168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:19:46.896281Z","caller":"traceutil/trace.go:171","msg":"trace[368229788] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25238; }","duration":"729.741305ms","start":"2026-10-15T07:19:46.166518Z","end":"2026-10-15T07:19:46.896259Z","steps":["trace[368229788] 'agreement among raft nodes before linearized reading' (duration: 729.542391ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:46.896291Z","caller":"traceutil/trace.go:171","msg":"trace[673373592] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25238; }","duration":"147.571401ms","start":"2026-10-15T07:19:46.748706Z","end":"2026-10-15T07:19:46.896278Z","steps":["trace[673373592] 'agreement among raft nodes before linearized reading' (duration: 147.343985ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:46.896322Z","caller":"traceutil/trace.go:171","msg":"trace[1390005734] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25238; }","duration":"361.900931ms","start":"2026-10-15T07:19:46.534404Z","end":"2026-10-15T07:19:46.896305Z","steps":["trace[1390005734] 'agreement among raft nodes before linearized reading' (duration: 361.775287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:46.896335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:46.1665Z","time spent":"729.825269ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:19:46.896368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:46.534391Z","time spent":"361.963722ms","remote":"127.0.0.1:48726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"info","ts":"2026-10-15T07:20:07.804917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22048} {"level":"info","ts":"2026-10-15T07:20:07.918578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22048,"took":"109.982755ms","hash":1076970538,"current-db-size-bytes":56340480,"current-db-size":"56 MB","current-db-size-in-use-bytes":45686784,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-15T07:20:07.918623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1076970538,"revision":22048,"compact-revision":19860} {"level":"warn","ts":"2026-10-15T07:20:49.582201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.047668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:20:49.582719Z","caller":"traceutil/trace.go:171","msg":"trace[1128139461] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"456.624945ms","start":"2026-10-15T07:20:49.126061Z","end":"2026-10-15T07:20:49.582685Z","steps":["trace[1128139461] 'process raft request' (duration: 456.234236ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:20:49.582784Z","caller":"traceutil/trace.go:171","msg":"trace[781097681] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"456.37586ms","start":"2026-10-15T07:20:49.126386Z","end":"2026-10-15T07:20:49.582762Z","steps":["trace[781097681] 'process raft request' (duration: 456.014172ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:20:49.582801Z","caller":"traceutil/trace.go:171","msg":"trace[616107093] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"457.183818ms","start":"2026-10-15T07:20:49.125584Z","end":"2026-10-15T07:20:49.582768Z","steps":["trace[616107093] 'process raft request' (duration: 335.490275ms)","trace[616107093] 'compare' (duration: 120.682419ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:20:49.58309Z","caller":"traceutil/trace.go:171","msg":"trace[362461063] linearizableReadLoop","detail":"{readStateIndex:26675; appliedIndex:26672; }","duration":"418.147559ms","start":"2026-10-15T07:20:49.164931Z","end":"2026-10-15T07:20:49.583079Z","steps":["trace[362461063] 'read index received' (duration: 296.110959ms)","trace[362461063] 'applied index is now lower than readState.Index' (duration: 122.03541ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:20:49.582886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.126038Z","time spent":"456.770609ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:20:49.583205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.268851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T07:20:49.58289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.126375Z","time spent":"456.468282ms","remote":"127.0.0.1:43070","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-10-15T07:20:49.583233Z","caller":"traceutil/trace.go:171","msg":"trace[1471673650] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25828; }","duration":"418.341133ms","start":"2026-10-15T07:20:49.164882Z","end":"2026-10-15T07:20:49.583224Z","steps":["trace[1471673650] 'agreement among raft nodes before linearized reading' (duration: 418.267861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:20:49.583262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.164864Z","time spent":"418.389424ms","remote":"127.0.0.1:33458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:20:49.583907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.125566Z","time spent":"457.558116ms","remote":"127.0.0.1:43070","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-10-15T07:20:50.007709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.043242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:20:50.008011Z","caller":"traceutil/trace.go:171","msg":"trace[2080030763] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"809.01744ms","start":"2026-10-15T07:20:49.198961Z","end":"2026-10-15T07:20:50.007978Z","steps":["trace[2080030763] 'process raft request' (duration: 547.60885ms)","trace[2080030763] 'compare' (duration: 260.480578ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:20:50.008115Z","caller":"traceutil/trace.go:171","msg":"trace[662901464] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"700.112933ms","start":"2026-10-15T07:20:49.307981Z","end":"2026-10-15T07:20:50.008094Z","steps":["trace[662901464] 'process raft request' (duration: 699.897008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:20:50.008223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.307965Z","time spent":"700.197614ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:20:50.008206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.198942Z","time spent":"809.162024ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:20:50.008238Z","caller":"traceutil/trace.go:171","msg":"trace[1346040744] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"281.083914ms","start":"2026-10-15T07:20:49.727138Z","end":"2026-10-15T07:20:50.008222Z","steps":["trace[1346040744] 'process raft request' (duration: 281.030112ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:20:50.008357Z","caller":"traceutil/trace.go:171","msg":"trace[702192883] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"636.442975ms","start":"2026-10-15T07:20:49.371867Z","end":"2026-10-15T07:20:50.00831Z","steps":["trace[702192883] 'process raft request' (duration: 636.19163ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:20:50.008221Z","caller":"traceutil/trace.go:171","msg":"trace[229464180] transaction","detail":"{read_only:false; response_revision:25831; number_of_response:1; }","duration":"696.115001ms","start":"2026-10-15T07:20:49.312065Z","end":"2026-10-15T07:20:50.00818Z","steps":["trace[229464180] 'process raft request' (duration: 695.910966ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:20:50.008422Z","caller":"traceutil/trace.go:171","msg":"trace[114624] linearizableReadLoop","detail":"{readStateIndex:26680; appliedIndex:26675; }","duration":"425.289754ms","start":"2026-10-15T07:20:49.58312Z","end":"2026-10-15T07:20:50.00841Z","steps":["trace[114624] 'read index received' (duration: 163.466625ms)","trace[114624] 'applied index is now lower than readState.Index' (duration: 261.821719ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:20:50.008399Z","caller":"traceutil/trace.go:171","msg":"trace[1202636477] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"626.407364ms","start":"2026-10-15T07:20:49.381885Z","end":"2026-10-15T07:20:50.008292Z","steps":["trace[1202636477] 'process raft request' (duration: 626.22585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:20:50.008471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.371839Z","time spent":"636.57586ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:20:50.008432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.312022Z","time spent":"696.393867ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:20:50.008492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.549858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:20:50.008558Z","caller":"traceutil/trace.go:171","msg":"trace[1150781313] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25834; }","duration":"560.65439ms","start":"2026-10-15T07:20:49.447896Z","end":"2026-10-15T07:20:50.008551Z","steps":["trace[1150781313] 'agreement among raft nodes before linearized reading' (duration: 560.569648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:20:50.008574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.447878Z","time spent":"560.691681ms","remote":"127.0.0.1:42674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:20:50.008675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.381861Z","time spent":"626.64125ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:20:50.008759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.306242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18984"} {"level":"info","ts":"2026-10-15T07:20:50.008842Z","caller":"traceutil/trace.go:171","msg":"trace[1386605896] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25834; }","duration":"306.430446ms","start":"2026-10-15T07:20:49.702396Z","end":"2026-10-15T07:20:50.008827Z","steps":["trace[1386605896] 'agreement among raft nodes before linearized reading' (duration: 306.257482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:20:50.008949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.702373Z","time spent":"306.563089ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19008,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-15T07:20:50.008996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.556985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-15T07:20:50.009447Z","caller":"traceutil/trace.go:171","msg":"trace[329830399] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25834; }","duration":"420.72066ms","start":"2026-10-15T07:20:49.588309Z","end":"2026-10-15T07:20:50.00903Z","steps":["trace[329830399] 'agreement among raft nodes before linearized reading' (duration: 420.215797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:20:50.009548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:20:49.588292Z","time spent":"421.240611ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":233,"response size":33,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-10-15T07:21:17.202447Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006958.wal"} {"level":"warn","ts":"2026-10-15T07:24:06.938617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.163176ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-15T07:24:06.995718Z","caller":"traceutil/trace.go:171","msg":"trace[2117167313] transaction","detail":"{read_only:false; response_revision:27777; number_of_response:1; }","duration":"226.751802ms","start":"2026-10-15T07:24:06.768941Z","end":"2026-10-15T07:24:06.995693Z","steps":["trace[2117167313] 'process raft request' (duration: 226.505736ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:24:06.995949Z","caller":"traceutil/trace.go:171","msg":"trace[168953316] transaction","detail":"{read_only:false; response_revision:27778; number_of_response:1; }","duration":"226.866526ms","start":"2026-10-15T07:24:06.769048Z","end":"2026-10-15T07:24:06.995914Z","steps":["trace[168953316] 'process raft request' (duration: 226.582059ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:24:07.023105Z","caller":"traceutil/trace.go:171","msg":"trace[597504090] transaction","detail":"{read_only:false; response_revision:27779; number_of_response:1; }","duration":"201.922549ms","start":"2026-10-15T07:24:06.821136Z","end":"2026-10-15T07:24:07.023058Z","steps":["trace[597504090] 'process raft request' (duration: 201.720875ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:24:07.023192Z","caller":"traceutil/trace.go:171","msg":"trace[588648361] transaction","detail":"{read_only:false; response_revision:27781; number_of_response:1; }","duration":"154.782305ms","start":"2026-10-15T07:24:06.868378Z","end":"2026-10-15T07:24:07.02316Z","steps":["trace[588648361] 'process raft request' (duration: 154.642102ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:24:07.02314Z","caller":"traceutil/trace.go:171","msg":"trace[1988622599] transaction","detail":"{read_only:false; response_revision:27780; number_of_response:1; }","duration":"187.922458ms","start":"2026-10-15T07:24:06.835184Z","end":"2026-10-15T07:24:07.023106Z","steps":["trace[1988622599] 'process raft request' (duration: 187.786595ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:25:07.856534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25482} {"level":"info","ts":"2026-10-15T07:25:08.14569Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25482,"took":"284.006211ms","hash":3972949722,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":36073472,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T07:25:08.145744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3972949722,"revision":25482,"compact-revision":22048} {"level":"info","ts":"2026-10-15T07:26:41.581645Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd81c1a075562f5","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:26:41.590172Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T07:26:41.590333Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T07:27:29.424247Z","caller":"traceutil/trace.go:171","msg":"trace[2031472352] transaction","detail":"{read_only:false; response_revision:29418; number_of_response:1; }","duration":"119.732378ms","start":"2026-10-15T07:27:29.30449Z","end":"2026-10-15T07:27:29.424223Z","steps":["trace[2031472352] 'process raft request' (duration: 119.553904ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:30:07.864012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28371} {"level":"info","ts":"2026-10-15T07:30:08.011325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28371,"took":"143.093806ms","hash":4024030160,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T07:30:08.011378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4024030160,"revision":28371,"compact-revision":25482} {"level":"info","ts":"2026-10-15T07:35:07.871789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30595} {"level":"info","ts":"2026-10-15T07:35:07.999376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30595,"took":"122.869011ms","hash":3831675157,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T07:35:07.999481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3831675157,"revision":30595,"compact-revision":28371} {"level":"info","ts":"2026-10-15T07:39:59.519187Z","caller":"traceutil/trace.go:171","msg":"trace[380954969] transaction","detail":"{read_only:false; response_revision:35061; number_of_response:1; }","duration":"117.432056ms","start":"2026-10-15T07:39:59.401736Z","end":"2026-10-15T07:39:59.519168Z","steps":["trace[380954969] 'process raft request' (duration: 117.310453ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:59.64418Z","caller":"traceutil/trace.go:171","msg":"trace[83320250] transaction","detail":"{read_only:false; response_revision:35063; number_of_response:1; }","duration":"118.736846ms","start":"2026-10-15T07:39:59.525418Z","end":"2026-10-15T07:39:59.644155Z","steps":["trace[83320250] 'process raft request' (duration: 84.782584ms)","trace[83320250] 'compare' (duration: 33.642535ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:40:07.877439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32855} {"level":"info","ts":"2026-10-15T07:40:08.019028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32855,"took":"138.174943ms","hash":1016885870,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":30773248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T07:40:08.019112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1016885870,"revision":32855,"compact-revision":30595} {"level":"info","ts":"2026-10-15T07:45:07.886531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35152} {"level":"info","ts":"2026-10-15T07:45:08.028129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35152,"took":"137.60689ms","hash":2435710549,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":32935936,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:45:08.028177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2435710549,"revision":35152,"compact-revision":32855} {"level":"info","ts":"2026-10-15T07:48:12.471Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd81c1a075562f5","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:48:12.4785Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T07:48:12.478615Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T07:50:07.894466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37382} {"level":"info","ts":"2026-10-15T07:50:08.035594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37382,"took":"136.7652ms","hash":1387001457,"current-db-size-bytes":61313024,"current-db-size":"61 MB","current-db-size-in-use-bytes":33148928,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:50:08.03583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1387001457,"revision":37382,"compact-revision":35152}