{"level":"warn","ts":"2026-08-21T02:31:42.227027Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-21T02:31:42.227142Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-21T02:31:42.227219Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-21T02:31:42.227229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-21T02:31:42.227254Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-21T02:31:42.227912Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-08-21T02:31:42.227993Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-21T02:31:42.231122Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.868398ms"} {"level":"info","ts":"2026-08-21T02:31:42.238596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-08-21T02:31:42.238688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-08-21T02:31:42.238714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-08-21T02:31:42.238723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-21T02:31:42.238733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-08-21T02:31:42.238778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-08-21T02:31:42.243453Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-21T02:31:42.245912Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-21T02:31:42.247699Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-21T02:31:42.25007Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-21T02:31:42.250401Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-21T02:31:42.250439Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-21T02:31:42.250574Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-21T02:31:42.250592Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-21T02:31:42.252017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-08-21T02:31:42.252191Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-21T02:31:42.252976Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-21T02:31:42.25316Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-21T02:31:42.253203Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-21T02:31:42.253261Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-21T02:31:42.253345Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-21T02:31:42.539666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-08-21T02:31:42.539763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-21T02:31:42.539837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-08-21T02:31:42.53988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-08-21T02:31:42.539906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-21T02:31:42.539946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-08-21T02:31:42.539975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-21T02:31:42.541336Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-08-21T02:31:42.541976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:31:42.542588Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:31:42.542992Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:31:42.543991Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-21T02:31:42.544025Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-21T02:31:42.545175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-08-21T02:31:42.545332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:31:42.545413Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:31:42.54544Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:31:42.547677Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-21T02:32:01.635327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.463128ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-21T02:32:01.635516Z","caller":"traceutil/trace.go:171","msg":"trace[842588347] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"139.920513ms","start":"2026-08-21T02:32:01.495569Z","end":"2026-08-21T02:32:01.63549Z","steps":["trace[842588347] 'read index received' (duration: 62.492µs)","trace[842588347] 'applied index is now lower than readState.Index' (duration: 139.85598ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:32:01.635655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.083545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T02:32:01.63571Z","caller":"traceutil/trace.go:171","msg":"trace[1308572050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"140.151347ms","start":"2026-08-21T02:32:01.495546Z","end":"2026-08-21T02:32:01.635697Z","steps":["trace[1308572050] 'agreement among raft nodes before linearized reading' (duration: 140.028844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:01.957514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.485718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:01.957647Z","caller":"traceutil/trace.go:171","msg":"trace[488901959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"161.692292ms","start":"2026-08-21T02:32:01.795926Z","end":"2026-08-21T02:32:01.957619Z","steps":["trace[488901959] 'range keys from in-memory index tree' (duration: 161.301334ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:30.065956Z","caller":"traceutil/trace.go:171","msg":"trace[1717893429] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"145.655503ms","start":"2026-08-21T02:32:29.920265Z","end":"2026-08-21T02:32:30.065921Z","steps":["trace[1717893429] 'read index received' (duration: 144.768995ms)","trace[1717893429] 'applied index is now lower than readState.Index' (duration: 884.528µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:30.066009Z","caller":"traceutil/trace.go:171","msg":"trace[1950975195] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"224.604613ms","start":"2026-08-21T02:32:29.841367Z","end":"2026-08-21T02:32:30.065971Z","steps":["trace[1950975195] 'process raft request' (duration: 223.96861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:30.06619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.912529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T02:32:30.066253Z","caller":"traceutil/trace.go:171","msg":"trace[242668720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"146.058911ms","start":"2026-08-21T02:32:29.920181Z","end":"2026-08-21T02:32:30.06624Z","steps":["trace[242668720] 'agreement among raft nodes before linearized reading' (duration: 145.874358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:30.513056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.494774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:32:30.513223Z","caller":"traceutil/trace.go:171","msg":"trace[1480243514] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"373.763576ms","start":"2026-08-21T02:32:30.139446Z","end":"2026-08-21T02:32:30.51321Z","steps":["trace[1480243514] 'process raft request' (duration: 373.711445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:30.513277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:30.139426Z","time spent":"373.827597ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-21T02:32:30.513345Z","caller":"traceutil/trace.go:171","msg":"trace[1736475295] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"409.537142ms","start":"2026-08-21T02:32:30.103775Z","end":"2026-08-21T02:32:30.513312Z","steps":["trace[1736475295] 'read index received' (duration: 60.592µs)","trace[1736475295] 'applied index is now lower than readState.Index' (duration: 409.47286ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:30.513432Z","caller":"traceutil/trace.go:171","msg":"trace[721457114] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"440.52757ms","start":"2026-08-21T02:32:30.072875Z","end":"2026-08-21T02:32:30.513402Z","steps":["trace[721457114] 'process raft request' (duration: 19.24209ms)","trace[721457114] 'compare' (duration: 420.348991ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:32:30.513563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:30.072846Z","time spent":"440.650712ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:32:30.513606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.824208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-21T02:32:30.513623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.494523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xmjnf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-21T02:32:30.513658Z","caller":"traceutil/trace.go:171","msg":"trace[1527137898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"409.93761ms","start":"2026-08-21T02:32:30.103704Z","end":"2026-08-21T02:32:30.513642Z","steps":["trace[1527137898] 'agreement among raft nodes before linearized reading' (duration: 409.750786ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:30.513671Z","caller":"traceutil/trace.go:171","msg":"trace[1903823341] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xmjnf; range_end:; response_count:1; response_revision:640; }","duration":"196.601085ms","start":"2026-08-21T02:32:30.317055Z","end":"2026-08-21T02:32:30.513657Z","steps":["trace[1903823341] 'agreement among raft nodes before linearized reading' (duration: 196.430502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:30.513684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:30.103677Z","time spent":"410.001751ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-21T02:32:31.145492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.881633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:32:31.145557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.20466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-21T02:32:31.145624Z","caller":"traceutil/trace.go:171","msg":"trace[509993223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"356.315112ms","start":"2026-08-21T02:32:30.78929Z","end":"2026-08-21T02:32:31.145605Z","steps":["trace[509993223] 'range keys from in-memory index tree' (duration: 355.944945ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:31.145624Z","caller":"traceutil/trace.go:171","msg":"trace[1500719713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"350.070496ms","start":"2026-08-21T02:32:30.795525Z","end":"2026-08-21T02:32:31.145595Z","steps":["trace[1500719713] 'range keys from in-memory index tree' (duration: 349.72556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:31.145691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:30.795505Z","time spent":"350.171699ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:32:31.145691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:30.789265Z","time spent":"356.411705ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-21T02:32:31.146252Z","caller":"traceutil/trace.go:171","msg":"trace[651475304] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"194.081695ms","start":"2026-08-21T02:32:30.952133Z","end":"2026-08-21T02:32:31.146215Z","steps":["trace[651475304] 'process raft request' (duration: 191.961501ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:32.5604Z","caller":"traceutil/trace.go:171","msg":"trace[386521276] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"107.058949ms","start":"2026-08-21T02:32:32.45331Z","end":"2026-08-21T02:32:32.560369Z","steps":["trace[386521276] 'process raft request' (duration: 94.024165ms)","trace[386521276] 'compare' (duration: 12.677537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:13.719881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.791498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-21T02:33:13.719994Z","caller":"traceutil/trace.go:171","msg":"trace[1533384472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"116.153975ms","start":"2026-08-21T02:33:13.603823Z","end":"2026-08-21T02:33:13.719977Z","steps":["trace[1533384472] 'range keys from in-memory index tree' (duration: 115.541192ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:36.932721Z","caller":"traceutil/trace.go:171","msg":"trace[270524139] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1508; }","duration":"138.11324ms","start":"2026-08-21T02:33:36.794579Z","end":"2026-08-21T02:33:36.932692Z","steps":["trace[270524139] 'read index received' (duration: 61.001707ms)","trace[270524139] 'applied index is now lower than readState.Index' (duration: 77.110433ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:36.932823Z","caller":"traceutil/trace.go:171","msg":"trace[597654819] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"146.689249ms","start":"2026-08-21T02:33:36.786106Z","end":"2026-08-21T02:33:36.932795Z","steps":["trace[597654819] 'process raft request' (duration: 146.397943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:36.932967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.378246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:33:36.933053Z","caller":"traceutil/trace.go:171","msg":"trace[482051801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1464; }","duration":"138.513359ms","start":"2026-08-21T02:33:36.794517Z","end":"2026-08-21T02:33:36.93303Z","steps":["trace[482051801] 'agreement among raft nodes before linearized reading' (duration: 138.373426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:36.933133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.839504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-21T02:33:36.933217Z","caller":"traceutil/trace.go:171","msg":"trace[652989862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1464; }","duration":"136.004717ms","start":"2026-08-21T02:33:36.797197Z","end":"2026-08-21T02:33:36.933202Z","steps":["trace[652989862] 'agreement among raft nodes before linearized reading' (duration: 135.700921ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:01.196581Z","caller":"traceutil/trace.go:171","msg":"trace[750108327] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"334.084623ms","start":"2026-08-21T02:34:00.862471Z","end":"2026-08-21T02:34:01.196556Z","steps":["trace[750108327] 'process raft request' (duration: 333.782917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:01.196793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:34:00.862456Z","time spent":"334.227995ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:34:01.2097Z","caller":"traceutil/trace.go:171","msg":"trace[1403509093] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1935; }","duration":"150.410127ms","start":"2026-08-21T02:34:01.059261Z","end":"2026-08-21T02:34:01.209671Z","steps":["trace[1403509093] 'read index received' (duration: 137.181501ms)","trace[1403509093] 'applied index is now lower than readState.Index' (duration: 13.227455ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:34:01.209741Z","caller":"traceutil/trace.go:171","msg":"trace[1395195836] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"174.403364ms","start":"2026-08-21T02:34:01.035301Z","end":"2026-08-21T02:34:01.209704Z","steps":["trace[1395195836] 'process raft request' (duration: 174.226801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:01.209992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.513258ms","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-08-21T02:34:01.210632Z","caller":"traceutil/trace.go:171","msg":"trace[2004308001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1864; }","duration":"151.196342ms","start":"2026-08-21T02:34:01.059422Z","end":"2026-08-21T02:34:01.210618Z","steps":["trace[2004308001] 'agreement among raft nodes before linearized reading' (duration: 150.410796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:01.210006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.719083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-21T02:34:01.210745Z","caller":"traceutil/trace.go:171","msg":"trace[1773978350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1864; }","duration":"151.505788ms","start":"2026-08-21T02:34:01.059225Z","end":"2026-08-21T02:34:01.210731Z","steps":["trace[1773978350] 'agreement among raft nodes before linearized reading' (duration: 150.574039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:01.210131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.87048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-21T02:34:01.21088Z","caller":"traceutil/trace.go:171","msg":"trace[1942768070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1864; }","duration":"118.627095ms","start":"2026-08-21T02:34:01.092223Z","end":"2026-08-21T02:34:01.21085Z","steps":["trace[1942768070] 'agreement among raft nodes before linearized reading' (duration: 117.844629ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:01.321589Z","caller":"traceutil/trace.go:171","msg":"trace[1168354151] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"106.985654ms","start":"2026-08-21T02:34:01.214552Z","end":"2026-08-21T02:34:01.321538Z","steps":["trace[1168354151] 'process raft request' (duration: 82.740869ms)","trace[1168354151] 'compare' (duration: 23.983159ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:34:01.322009Z","caller":"traceutil/trace.go:171","msg":"trace[370062808] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"107.248579ms","start":"2026-08-21T02:34:01.214695Z","end":"2026-08-21T02:34:01.321943Z","steps":["trace[370062808] 'process raft request' (duration: 106.742689ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:01.322187Z","caller":"traceutil/trace.go:171","msg":"trace[1602700316] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"106.563654ms","start":"2026-08-21T02:34:01.215601Z","end":"2026-08-21T02:34:01.322165Z","steps":["trace[1602700316] 'process raft request' (duration: 106.260808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:01.623756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.383509ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-21T02:34:01.62424Z","caller":"traceutil/trace.go:171","msg":"trace[2118061012] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"153.00826ms","start":"2026-08-21T02:34:01.471209Z","end":"2026-08-21T02:34:01.624218Z","steps":["trace[2118061012] 'process raft request' (duration: 152.841656ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:14.23891Z","caller":"traceutil/trace.go:171","msg":"trace[1977038266] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"146.171207ms","start":"2026-08-21T02:34:14.092701Z","end":"2026-08-21T02:34:14.238872Z","steps":["trace[1977038266] 'process raft request' (duration: 145.984903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:45.985141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.26053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:34:45.985255Z","caller":"traceutil/trace.go:171","msg":"trace[1629753503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2165; }","duration":"190.438014ms","start":"2026-08-21T02:34:45.794799Z","end":"2026-08-21T02:34:45.985237Z","steps":["trace[1629753503] 'range keys from in-memory index tree' (duration: 190.174409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:46.359205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.996147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-21T02:34:46.359314Z","caller":"traceutil/trace.go:171","msg":"trace[1508950916] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:2166; }","duration":"241.157421ms","start":"2026-08-21T02:34:46.118137Z","end":"2026-08-21T02:34:46.359294Z","steps":["trace[1508950916] 'range keys from in-memory index tree' (duration: 240.800493ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:51.629925Z","caller":"traceutil/trace.go:171","msg":"trace[550390386] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2276; }","duration":"104.298712ms","start":"2026-08-21T02:34:51.525597Z","end":"2026-08-21T02:34:51.629896Z","steps":["trace[550390386] 'read index received' (duration: 23.391887ms)","trace[550390386] 'applied index is now lower than readState.Index' (duration: 80.905145ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:34:51.630199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.566788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T02:34:51.630285Z","caller":"traceutil/trace.go:171","msg":"trace[119609189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2192; }","duration":"104.712201ms","start":"2026-08-21T02:34:51.525557Z","end":"2026-08-21T02:34:51.630269Z","steps":["trace[119609189] 'agreement among raft nodes before linearized reading' (duration: 104.442275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:36:15.600649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.370499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:36:15.601659Z","caller":"traceutil/trace.go:171","msg":"trace[992086724] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2842; }","duration":"112.388221ms","start":"2026-08-21T02:36:15.48922Z","end":"2026-08-21T02:36:15.601608Z","steps":["trace[992086724] 'count revisions from in-memory index tree' (duration: 111.104624ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:01.351997Z","caller":"traceutil/trace.go:171","msg":"trace[728192651] transaction","detail":"{read_only:false; response_revision:3915; number_of_response:1; }","duration":"166.457992ms","start":"2026-08-21T02:39:01.185509Z","end":"2026-08-21T02:39:01.351967Z","steps":["trace[728192651] 'process raft request' (duration: 166.292248ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:01.364269Z","caller":"traceutil/trace.go:171","msg":"trace[1400577798] transaction","detail":"{read_only:false; response_revision:3916; number_of_response:1; }","duration":"178.037711ms","start":"2026-08-21T02:39:01.186193Z","end":"2026-08-21T02:39:01.36423Z","steps":["trace[1400577798] 'process raft request' (duration: 177.637833ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:01.364634Z","caller":"traceutil/trace.go:171","msg":"trace[1735129018] transaction","detail":"{read_only:false; response_revision:3917; number_of_response:1; }","duration":"178.218243ms","start":"2026-08-21T02:39:01.186402Z","end":"2026-08-21T02:39:01.36462Z","steps":["trace[1735129018] 'process raft request' (duration: 178.076531ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:01.367432Z","caller":"traceutil/trace.go:171","msg":"trace[1094966143] linearizableReadLoop","detail":"{readStateIndex:4067; appliedIndex:4064; }","duration":"117.56746ms","start":"2026-08-21T02:39:01.24982Z","end":"2026-08-21T02:39:01.367388Z","steps":["trace[1094966143] 'read index received' (duration: 102.037241ms)","trace[1094966143] 'applied index is now lower than readState.Index' (duration: 15.528519ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:39:01.367704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.863636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45nbx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T02:39:01.367782Z","caller":"traceutil/trace.go:171","msg":"trace[1153135041] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45nbx; range_end:; response_count:1; response_revision:3917; }","duration":"118.004179ms","start":"2026-08-21T02:39:01.249757Z","end":"2026-08-21T02:39:01.367761Z","steps":["trace[1153135041] 'agreement among raft nodes before linearized reading' (duration: 117.753494ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:32.057537Z","caller":"traceutil/trace.go:171","msg":"trace[1912445802] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5121; }","duration":"112.086046ms","start":"2026-08-21T02:40:31.945419Z","end":"2026-08-21T02:40:32.057505Z","steps":["trace[1912445802] 'read index received' (duration: 80.891494ms)","trace[1912445802] 'applied index is now lower than readState.Index' (duration: 31.193552ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:40:32.057611Z","caller":"traceutil/trace.go:171","msg":"trace[677211160] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"124.48064ms","start":"2026-08-21T02:40:31.93311Z","end":"2026-08-21T02:40:32.057591Z","steps":["trace[677211160] 'process raft request' (duration: 93.146326ms)","trace[677211160] 'compare' (duration: 31.10393ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:40:32.057799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.356851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db13af1a-4dd2-4bbf-b301-ebd6699cd572\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-08-21T02:40:32.057863Z","caller":"traceutil/trace.go:171","msg":"trace[1194860583] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db13af1a-4dd2-4bbf-b301-ebd6699cd572; range_end:; response_count:1; response_revision:4929; }","duration":"112.490774ms","start":"2026-08-21T02:40:31.945357Z","end":"2026-08-21T02:40:32.057848Z","steps":["trace[1194860583] 'agreement among raft nodes before linearized reading' (duration: 112.30295ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:47.738264Z","caller":"traceutil/trace.go:171","msg":"trace[1035165311] linearizableReadLoop","detail":"{readStateIndex:5562; appliedIndex:5561; }","duration":"181.558555ms","start":"2026-08-21T02:40:47.556675Z","end":"2026-08-21T02:40:47.738233Z","steps":["trace[1035165311] 'read index received' (duration: 181.173568ms)","trace[1035165311] 'applied index is now lower than readState.Index' (duration: 383.887µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:40:47.738305Z","caller":"traceutil/trace.go:171","msg":"trace[1085771141] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"210.631783ms","start":"2026-08-21T02:40:47.52763Z","end":"2026-08-21T02:40:47.738262Z","steps":["trace[1085771141] 'process raft request' (duration: 210.338857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:47.738569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.873312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-21T02:40:47.738627Z","caller":"traceutil/trace.go:171","msg":"trace[696367125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5357; }","duration":"181.988004ms","start":"2026-08-21T02:40:47.556626Z","end":"2026-08-21T02:40:47.738614Z","steps":["trace[696367125] 'agreement among raft nodes before linearized reading' (duration: 181.759839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:48.077384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.567821ms","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-08-21T02:40:48.077523Z","caller":"traceutil/trace.go:171","msg":"trace[1937383556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5361; }","duration":"120.748694ms","start":"2026-08-21T02:40:47.956745Z","end":"2026-08-21T02:40:48.077493Z","steps":["trace[1937383556] 'range keys from in-memory index tree' (duration: 120.393257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:48.077625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.450728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-21T02:40:48.077733Z","caller":"traceutil/trace.go:171","msg":"trace[1532472068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5361; }","duration":"119.596581ms","start":"2026-08-21T02:40:47.958111Z","end":"2026-08-21T02:40:48.077708Z","steps":["trace[1532472068] 'range keys from in-memory index tree' (duration: 119.281155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:48.077926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.821387ms","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-08-21T02:40:48.077988Z","caller":"traceutil/trace.go:171","msg":"trace[1646203017] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5361; }","duration":"123.909719ms","start":"2026-08-21T02:40:47.954058Z","end":"2026-08-21T02:40:48.077968Z","steps":["trace[1646203017] 'range keys from in-memory index tree' (duration: 123.692385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:48.078279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.995859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45nbx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T02:40:48.078403Z","caller":"traceutil/trace.go:171","msg":"trace[956838244] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45nbx; range_end:; response_count:1; response_revision:5361; }","duration":"120.138182ms","start":"2026-08-21T02:40:47.958242Z","end":"2026-08-21T02:40:48.07838Z","steps":["trace[956838244] 'range keys from in-memory index tree' (duration: 119.529339ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:48.183961Z","caller":"traceutil/trace.go:171","msg":"trace[458794009] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"100.916026ms","start":"2026-08-21T02:40:48.083004Z","end":"2026-08-21T02:40:48.18392Z","steps":["trace[458794009] 'process raft request' (duration: 100.771093ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:48.23813Z","caller":"traceutil/trace.go:171","msg":"trace[506389730] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"154.779174ms","start":"2026-08-21T02:40:48.083331Z","end":"2026-08-21T02:40:48.238111Z","steps":["trace[506389730] 'process raft request' (duration: 121.07067ms)","trace[506389730] 'compare' (duration: 33.52037ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:40:48.238128Z","caller":"traceutil/trace.go:171","msg":"trace[93979957] linearizableReadLoop","detail":"{readStateIndex:5569; appliedIndex:5567; }","duration":"154.211793ms","start":"2026-08-21T02:40:48.083895Z","end":"2026-08-21T02:40:48.238107Z","steps":["trace[93979957] 'read index received' (duration: 100.348065ms)","trace[93979957] 'applied index is now lower than readState.Index' (duration: 53.862638ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:40:48.238188Z","caller":"traceutil/trace.go:171","msg":"trace[1258980434] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"154.621841ms","start":"2026-08-21T02:40:48.083543Z","end":"2026-08-21T02:40:48.238165Z","steps":["trace[1258980434] 'process raft request' (duration: 154.512699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:48.238429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.663263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-fgqv2\" ","response":"range_response_count:1 size:7818"} {"level":"warn","ts":"2026-08-21T02:40:48.238449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.557109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70674"} {"level":"info","ts":"2026-08-21T02:40:48.238475Z","caller":"traceutil/trace.go:171","msg":"trace[1746092577] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-fgqv2; range_end:; response_count:1; response_revision:5364; }","duration":"105.721884ms","start":"2026-08-21T02:40:48.132741Z","end":"2026-08-21T02:40:48.238463Z","steps":["trace[1746092577] 'agreement among raft nodes before linearized reading' (duration: 105.584001ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:48.238482Z","caller":"traceutil/trace.go:171","msg":"trace[1585250502] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5364; }","duration":"154.617711ms","start":"2026-08-21T02:40:48.083854Z","end":"2026-08-21T02:40:48.238472Z","steps":["trace[1585250502] 'agreement among raft nodes before linearized reading' (duration: 154.379726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:48.825213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.259536ms","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-08-21T02:40:48.82532Z","caller":"traceutil/trace.go:171","msg":"trace[749601623] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5373; }","duration":"175.43953ms","start":"2026-08-21T02:40:48.649859Z","end":"2026-08-21T02:40:48.825298Z","steps":["trace[749601623] 'range keys from in-memory index tree' (duration: 175.154954ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:52.360725Z","caller":"traceutil/trace.go:171","msg":"trace[868219714] linearizableReadLoop","detail":"{readStateIndex:5629; appliedIndex:5628; }","duration":"157.01772ms","start":"2026-08-21T02:40:52.203675Z","end":"2026-08-21T02:40:52.360693Z","steps":["trace[868219714] 'read index received' (duration: 156.778765ms)","trace[868219714] 'applied index is now lower than readState.Index' (duration: 237.355µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:40:52.360829Z","caller":"traceutil/trace.go:171","msg":"trace[61536093] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"157.714945ms","start":"2026-08-21T02:40:52.203065Z","end":"2026-08-21T02:40:52.36078Z","steps":["trace[61536093] 'process raft request' (duration: 157.416279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:52.361078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.371478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-21T02:40:52.361147Z","caller":"traceutil/trace.go:171","msg":"trace[1134264010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5423; }","duration":"157.517721ms","start":"2026-08-21T02:40:52.203613Z","end":"2026-08-21T02:40:52.361131Z","steps":["trace[1134264010] 'agreement among raft nodes before linearized reading' (duration: 157.238665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:52.361218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.177752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45nbx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T02:40:52.361297Z","caller":"traceutil/trace.go:171","msg":"trace[723173232] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45nbx; range_end:; response_count:1; response_revision:5423; }","duration":"102.300164ms","start":"2026-08-21T02:40:52.258977Z","end":"2026-08-21T02:40:52.361277Z","steps":["trace[723173232] 'agreement among raft nodes before linearized reading' (duration: 102.080269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:52.36122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.047891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-08-21T02:40:52.361368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.703797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-08-21T02:40:52.361439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.026222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T02:40:52.361561Z","caller":"traceutil/trace.go:171","msg":"trace[57063005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5423; }","duration":"108.186075ms","start":"2026-08-21T02:40:52.253352Z","end":"2026-08-21T02:40:52.361538Z","steps":["trace[57063005] 'agreement among raft nodes before linearized reading' (duration: 107.822168ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:52.361481Z","caller":"traceutil/trace.go:171","msg":"trace[704041427] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5423; }","duration":"109.88272ms","start":"2026-08-21T02:40:52.251573Z","end":"2026-08-21T02:40:52.361456Z","steps":["trace[704041427] 'agreement among raft nodes before linearized reading' (duration: 109.632695ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:52.361411Z","caller":"traceutil/trace.go:171","msg":"trace[1644347561] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5423; }","duration":"156.269195ms","start":"2026-08-21T02:40:52.205125Z","end":"2026-08-21T02:40:52.361394Z","steps":["trace[1644347561] 'agreement among raft nodes before linearized reading' (duration: 155.892998ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:57.185814Z","caller":"traceutil/trace.go:171","msg":"trace[1157487676] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"264.705985ms","start":"2026-08-21T02:40:56.921064Z","end":"2026-08-21T02:40:57.18577Z","steps":["trace[1157487676] 'process raft request' (duration: 264.500681ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:57.23639Z","caller":"traceutil/trace.go:171","msg":"trace[1258898432] linearizableReadLoop","detail":"{readStateIndex:5767; appliedIndex:5765; }","duration":"210.367677ms","start":"2026-08-21T02:40:57.025993Z","end":"2026-08-21T02:40:57.236361Z","steps":["trace[1258898432] 'read index received' (duration: 159.558142ms)","trace[1258898432] 'applied index is now lower than readState.Index' (duration: 50.808055ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:40:57.236522Z","caller":"traceutil/trace.go:171","msg":"trace[1007375282] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"257.542757ms","start":"2026-08-21T02:40:56.978965Z","end":"2026-08-21T02:40:57.236508Z","steps":["trace[1007375282] 'process raft request' (duration: 242.647671ms)","trace[1007375282] 'compare' (duration: 14.543889ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:40:57.237127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.854229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d830a1ce-5c65-4d48-981c-f0b26e3513ad\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-21T02:40:57.237259Z","caller":"traceutil/trace.go:171","msg":"trace[1627353817] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d830a1ce-5c65-4d48-981c-f0b26e3513ad; range_end:; response_count:1; response_revision:5560; }","duration":"211.234166ms","start":"2026-08-21T02:40:57.025975Z","end":"2026-08-21T02:40:57.237209Z","steps":["trace[1627353817] 'agreement among raft nodes before linearized reading' (duration: 210.594082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:58.5157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.103272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-21T02:40:58.515815Z","caller":"traceutil/trace.go:171","msg":"trace[1701445558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5573; }","duration":"105.281875ms","start":"2026-08-21T02:40:58.410506Z","end":"2026-08-21T02:40:58.515788Z","steps":["trace[1701445558] 'range keys from in-memory index tree' (duration: 104.785815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:40:58.516016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.822325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45nbx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T02:40:58.5161Z","caller":"traceutil/trace.go:171","msg":"trace[1142250594] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45nbx; range_end:; response_count:1; response_revision:5573; }","duration":"119.954618ms","start":"2026-08-21T02:40:58.396123Z","end":"2026-08-21T02:40:58.516078Z","steps":["trace[1142250594] 'range keys from in-memory index tree' (duration: 119.491059ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:41:07.753055Z","caller":"traceutil/trace.go:171","msg":"trace[355222705] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"264.921508ms","start":"2026-08-21T02:41:07.488109Z","end":"2026-08-21T02:41:07.753031Z","steps":["trace[355222705] 'process raft request' (duration: 258.18836ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:41:42.81306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-08-21T02:41:42.876787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"61.650167ms","hash":1186680804,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":33746944,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-21T02:41:42.876989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1186680804,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-08-21T02:46:42.821239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6028} {"level":"info","ts":"2026-08-21T02:46:42.919141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6028,"took":"96.293448ms","hash":3593217794,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":21872640,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-21T02:46:42.919189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3593217794,"revision":6028,"compact-revision":2995} {"level":"info","ts":"2026-08-21T02:46:51.956879Z","caller":"traceutil/trace.go:171","msg":"trace[1023557078] linearizableReadLoop","detail":"{readStateIndex:8504; appliedIndex:8503; }","duration":"159.121812ms","start":"2026-08-21T02:46:51.797713Z","end":"2026-08-21T02:46:51.956834Z","steps":["trace[1023557078] 'read index received' (duration: 158.620771ms)","trace[1023557078] 'applied index is now lower than readState.Index' (duration: 499.471µs)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:46:51.957296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.548061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:46:51.957363Z","caller":"traceutil/trace.go:171","msg":"trace[924315815] transaction","detail":"{read_only:false; response_revision:8209; number_of_response:1; }","duration":"163.662445ms","start":"2026-08-21T02:46:51.793679Z","end":"2026-08-21T02:46:51.957342Z","steps":["trace[924315815] 'process raft request' (duration: 162.862428ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:51.957404Z","caller":"traceutil/trace.go:171","msg":"trace[1107836683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8209; }","duration":"159.719134ms","start":"2026-08-21T02:46:51.797658Z","end":"2026-08-21T02:46:51.957377Z","steps":["trace[1107836683] 'agreement among raft nodes before linearized reading' (duration: 159.51314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:51.957328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.395566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:46:51.957743Z","caller":"traceutil/trace.go:171","msg":"trace[1148105999] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8209; }","duration":"110.885946ms","start":"2026-08-21T02:46:51.846847Z","end":"2026-08-21T02:46:51.957733Z","steps":["trace[1148105999] 'agreement among raft nodes before linearized reading' (duration: 110.399567ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:52.444232Z","caller":"traceutil/trace.go:171","msg":"trace[1415832357] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"202.117331ms","start":"2026-08-21T02:46:52.242073Z","end":"2026-08-21T02:46:52.444191Z","steps":["trace[1415832357] 'process raft request' (duration: 201.792424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:52.684454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.687279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-21T02:46:52.684569Z","caller":"traceutil/trace.go:171","msg":"trace[1455007838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8211; }","duration":"149.908093ms","start":"2026-08-21T02:46:52.534636Z","end":"2026-08-21T02:46:52.684544Z","steps":["trace[1455007838] 'range keys from in-memory index tree' (duration: 149.315361ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:52.974568Z","caller":"traceutil/trace.go:171","msg":"trace[352404599] transaction","detail":"{read_only:false; response_revision:8213; number_of_response:1; }","duration":"308.511055ms","start":"2026-08-21T02:46:52.666015Z","end":"2026-08-21T02:46:52.974526Z","steps":["trace[352404599] 'process raft request' (duration: 257.903961ms)","trace[352404599] 'compare' (duration: 50.214316ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:46:52.974861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:52.665991Z","time spent":"308.684149ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:46:52.975065Z","caller":"traceutil/trace.go:171","msg":"trace[1574729126] transaction","detail":"{read_only:false; response_revision:8214; number_of_response:1; }","duration":"285.516755ms","start":"2026-08-21T02:46:52.689515Z","end":"2026-08-21T02:46:52.975031Z","steps":["trace[1574729126] 'process raft request' (duration: 284.902993ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:52.975493Z","caller":"traceutil/trace.go:171","msg":"trace[1342486077] transaction","detail":"{read_only:false; response_revision:8215; number_of_response:1; }","duration":"285.320352ms","start":"2026-08-21T02:46:52.689939Z","end":"2026-08-21T02:46:52.975259Z","steps":["trace[1342486077] 'process raft request' (duration: 284.816972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:52.975514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.211135ms","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-08-21T02:46:52.975564Z","caller":"traceutil/trace.go:171","msg":"trace[584589298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8217; }","duration":"301.330128ms","start":"2026-08-21T02:46:52.674221Z","end":"2026-08-21T02:46:52.975552Z","steps":["trace[584589298] 'agreement among raft nodes before linearized reading' (duration: 301.171704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:52.975597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:52.674204Z","time spent":"301.383979ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-21T02:46:52.975612Z","caller":"traceutil/trace.go:171","msg":"trace[1418636371] transaction","detail":"{read_only:false; response_revision:8216; number_of_response:1; }","duration":"285.25132ms","start":"2026-08-21T02:46:52.690034Z","end":"2026-08-21T02:46:52.975285Z","steps":["trace[1418636371] 'process raft request' (duration: 285.104107ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:52.975413Z","caller":"traceutil/trace.go:171","msg":"trace[126351257] linearizableReadLoop","detail":"{readStateIndex:8508; appliedIndex:8506; }","duration":"300.96419ms","start":"2026-08-21T02:46:52.674271Z","end":"2026-08-21T02:46:52.975236Z","steps":["trace[126351257] 'read index received' (duration: 46.218694ms)","trace[126351257] 'applied index is now lower than readState.Index' (duration: 254.744366ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:46:52.97569Z","caller":"traceutil/trace.go:171","msg":"trace[630644157] transaction","detail":"{read_only:false; response_revision:8217; number_of_response:1; }","duration":"284.960354ms","start":"2026-08-21T02:46:52.690355Z","end":"2026-08-21T02:46:52.975315Z","steps":["trace[630644157] 'process raft request' (duration: 284.830451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:52.97603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.448512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45nbx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T02:46:52.976093Z","caller":"traceutil/trace.go:171","msg":"trace[1306523603] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45nbx; range_end:; response_count:1; response_revision:8217; }","duration":"169.566435ms","start":"2026-08-21T02:46:52.806511Z","end":"2026-08-21T02:46:52.976077Z","steps":["trace[1306523603] 'agreement among raft nodes before linearized reading' (duration: 169.31569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:54.086968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.358222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-21T02:46:54.087062Z","caller":"traceutil/trace.go:171","msg":"trace[1287617412] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8219; }","duration":"125.510515ms","start":"2026-08-21T02:46:53.961531Z","end":"2026-08-21T02:46:54.087041Z","steps":["trace[1287617412] 'range keys from in-memory index tree' (duration: 125.124288ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:54.948191Z","caller":"traceutil/trace.go:171","msg":"trace[1515606153] linearizableReadLoop","detail":"{readStateIndex:8517; appliedIndex:8516; }","duration":"155.356855ms","start":"2026-08-21T02:46:54.792797Z","end":"2026-08-21T02:46:54.948154Z","steps":["trace[1515606153] 'read index received' (duration: 155.133321ms)","trace[1515606153] 'applied index is now lower than readState.Index' (duration: 222.364µs)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:46:54.948341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.522339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:46:54.94833Z","caller":"traceutil/trace.go:171","msg":"trace[529136741] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"220.74505ms","start":"2026-08-21T02:46:54.727509Z","end":"2026-08-21T02:46:54.948254Z","steps":["trace[529136741] 'process raft request' (duration: 220.405893ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:54.948389Z","caller":"traceutil/trace.go:171","msg":"trace[636766953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8222; }","duration":"155.61136ms","start":"2026-08-21T02:46:54.792752Z","end":"2026-08-21T02:46:54.948363Z","steps":["trace[636766953] 'agreement among raft nodes before linearized reading' (duration: 155.515428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:06.36565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.007089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-21T02:47:06.365828Z","caller":"traceutil/trace.go:171","msg":"trace[290272681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8414; }","duration":"113.236064ms","start":"2026-08-21T02:47:06.252575Z","end":"2026-08-21T02:47:06.365811Z","steps":["trace[290272681] 'range keys from in-memory index tree' (duration: 112.858826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:06.365676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.858333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:47:06.365944Z","caller":"traceutil/trace.go:171","msg":"trace[1494339046] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8414; }","duration":"129.20005ms","start":"2026-08-21T02:47:06.236716Z","end":"2026-08-21T02:47:06.365916Z","steps":["trace[1494339046] 'count revisions from in-memory index tree' (duration: 128.751191ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:14.331123Z","caller":"traceutil/trace.go:171","msg":"trace[571363096] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"139.219665ms","start":"2026-08-21T02:47:14.191871Z","end":"2026-08-21T02:47:14.331091Z","steps":["trace[571363096] 'process raft request' (duration: 139.013121ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:15.167508Z","caller":"traceutil/trace.go:171","msg":"trace[1837603592] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"144.568735ms","start":"2026-08-21T02:47:15.022907Z","end":"2026-08-21T02:47:15.167476Z","steps":["trace[1837603592] 'process raft request' (duration: 144.375971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:17.406764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.190968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-21T02:47:17.406825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.903402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-21T02:47:17.406866Z","caller":"traceutil/trace.go:171","msg":"trace[1338949586] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8469; }","duration":"146.956003ms","start":"2026-08-21T02:47:17.259897Z","end":"2026-08-21T02:47:17.406853Z","steps":["trace[1338949586] 'range keys from in-memory index tree' (duration: 146.83813ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:17.406884Z","caller":"traceutil/trace.go:171","msg":"trace[1006362625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8469; }","duration":"147.32994ms","start":"2026-08-21T02:47:17.259517Z","end":"2026-08-21T02:47:17.406847Z","steps":["trace[1006362625] 'range keys from in-memory index tree' (duration: 147.061415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:17.406854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.714998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-21T02:47:17.406966Z","caller":"traceutil/trace.go:171","msg":"trace[844822468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8469; }","duration":"146.851711ms","start":"2026-08-21T02:47:17.260086Z","end":"2026-08-21T02:47:17.406938Z","steps":["trace[844822468] 'range keys from in-memory index tree' (duration: 146.577245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:17.407009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.992886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:47:17.406987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.3509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45nbx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T02:47:17.40706Z","caller":"traceutil/trace.go:171","msg":"trace[1133943336] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8469; }","duration":"130.098748ms","start":"2026-08-21T02:47:17.276947Z","end":"2026-08-21T02:47:17.407045Z","steps":["trace[1133943336] 'range keys from in-memory index tree' (duration: 129.878744ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:17.40709Z","caller":"traceutil/trace.go:171","msg":"trace[845324348] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45nbx; range_end:; response_count:1; response_revision:8469; }","duration":"143.550154ms","start":"2026-08-21T02:47:17.263521Z","end":"2026-08-21T02:47:17.407071Z","steps":["trace[845324348] 'range keys from in-memory index tree' (duration: 143.245978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:17.40711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.156607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-21T02:47:17.407269Z","caller":"traceutil/trace.go:171","msg":"trace[291215976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8469; }","duration":"148.404923ms","start":"2026-08-21T02:47:17.25882Z","end":"2026-08-21T02:47:17.407225Z","steps":["trace[291215976] 'range keys from in-memory index tree' (duration: 147.796189ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:44.59811Z","caller":"traceutil/trace.go:171","msg":"trace[1406028504] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"148.073284ms","start":"2026-08-21T02:47:44.45001Z","end":"2026-08-21T02:47:44.598083Z","steps":["trace[1406028504] 'process raft request' (duration: 147.900851ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:50.780256Z","caller":"traceutil/trace.go:171","msg":"trace[1958857443] linearizableReadLoop","detail":"{readStateIndex:9013; appliedIndex:9012; }","duration":"113.155742ms","start":"2026-08-21T02:47:50.667038Z","end":"2026-08-21T02:47:50.780194Z","steps":["trace[1958857443] 'read index received' (duration: 112.843375ms)","trace[1958857443] 'applied index is now lower than readState.Index' (duration: 310.947µs)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:47:50.780597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.5297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-21T02:47:50.780647Z","caller":"traceutil/trace.go:171","msg":"trace[317818840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8704; }","duration":"113.639292ms","start":"2026-08-21T02:47:50.666994Z","end":"2026-08-21T02:47:50.780633Z","steps":["trace[317818840] 'agreement among raft nodes before linearized reading' (duration: 113.406847ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:50.781025Z","caller":"traceutil/trace.go:171","msg":"trace[1486712160] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"158.870065ms","start":"2026-08-21T02:47:50.622133Z","end":"2026-08-21T02:47:50.781003Z","steps":["trace[1486712160] 'process raft request' (duration: 157.846515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:57.650028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.35316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-g7xlh\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-08-21T02:47:57.650215Z","caller":"traceutil/trace.go:171","msg":"trace[1683062755] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-g7xlh; range_end:; response_count:1; response_revision:8779; }","duration":"145.541693ms","start":"2026-08-21T02:47:57.50459Z","end":"2026-08-21T02:47:57.650132Z","steps":["trace[1683062755] 'range keys from in-memory index tree' (duration: 145.113084ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:57.652831Z","caller":"traceutil/trace.go:171","msg":"trace[1714325646] linearizableReadLoop","detail":"{readStateIndex:9094; appliedIndex:9093; }","duration":"128.877352ms","start":"2026-08-21T02:47:57.52393Z","end":"2026-08-21T02:47:57.652807Z","steps":["trace[1714325646] 'read index received' (duration: 128.698979ms)","trace[1714325646] 'applied index is now lower than readState.Index' (duration: 177.033µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:47:57.652901Z","caller":"traceutil/trace.go:171","msg":"trace[1482314938] transaction","detail":"{read_only:false; response_revision:8780; number_of_response:1; }","duration":"148.058085ms","start":"2026-08-21T02:47:57.504821Z","end":"2026-08-21T02:47:57.652879Z","steps":["trace[1482314938] 'process raft request' (duration: 147.83946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:57.653092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.133567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-21T02:47:57.653149Z","caller":"traceutil/trace.go:171","msg":"trace[836766807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8780; }","duration":"129.23404ms","start":"2026-08-21T02:47:57.523902Z","end":"2026-08-21T02:47:57.653136Z","steps":["trace[836766807] 'agreement among raft nodes before linearized reading' (duration: 129.033156ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:58.602289Z","caller":"traceutil/trace.go:171","msg":"trace[2039291593] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"124.669277ms","start":"2026-08-21T02:47:58.477592Z","end":"2026-08-21T02:47:58.602261Z","steps":["trace[2039291593] 'process raft request' (duration: 124.466953ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:48:12.96219Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002415.wal"} {"level":"info","ts":"2026-08-21T02:49:19.256487Z","caller":"traceutil/trace.go:171","msg":"trace[775218138] transaction","detail":"{read_only:false; response_revision:9560; number_of_response:1; }","duration":"104.871414ms","start":"2026-08-21T02:49:19.151566Z","end":"2026-08-21T02:49:19.256438Z","steps":["trace[775218138] 'process raft request' (duration: 95.903531ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:49:32.09142Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-21T02:49:32.096248Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-21T02:49:32.0964Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-21T02:51:42.830454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8153} {"level":"info","ts":"2026-08-21T02:51:42.894867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8153,"took":"62.082861ms","hash":4060914315,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":29102080,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-21T02:51:42.894929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4060914315,"revision":8153,"compact-revision":6028} {"level":"info","ts":"2026-08-21T02:52:58.777188Z","caller":"traceutil/trace.go:171","msg":"trace[1349327195] transaction","detail":"{read_only:false; response_revision:11377; number_of_response:1; }","duration":"144.402758ms","start":"2026-08-21T02:52:58.632748Z","end":"2026-08-21T02:52:58.777151Z","steps":["trace[1349327195] 'process raft request' (duration: 144.192784ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:52:58.968001Z","caller":"traceutil/trace.go:171","msg":"trace[867823767] transaction","detail":"{read_only:false; response_revision:11378; number_of_response:1; }","duration":"319.447534ms","start":"2026-08-21T02:52:58.64851Z","end":"2026-08-21T02:52:58.967958Z","steps":["trace[867823767] 'process raft request' (duration: 290.519581ms)","trace[867823767] 'compare' (duration: 28.525515ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:52:58.968044Z","caller":"traceutil/trace.go:171","msg":"trace[979190135] linearizableReadLoop","detail":"{readStateIndex:11770; appliedIndex:11768; }","duration":"172.35525ms","start":"2026-08-21T02:52:58.795645Z","end":"2026-08-21T02:52:58.968Z","steps":["trace[979190135] 'read index received' (duration: 143.314126ms)","trace[979190135] 'applied index is now lower than readState.Index' (duration: 29.040164ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:52:58.968088Z","caller":"traceutil/trace.go:171","msg":"trace[851604778] transaction","detail":"{read_only:false; response_revision:11379; number_of_response:1; }","duration":"301.749691ms","start":"2026-08-21T02:52:58.666297Z","end":"2026-08-21T02:52:58.968047Z","steps":["trace[851604778] 'process raft request' (duration: 301.572608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:52:58.968187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:52:58.648482Z","time spent":"319.606306ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:52:58.968319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:52:58.666283Z","time spent":"301.898464ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:52:58.968257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.577926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:52:58.968428Z","caller":"traceutil/trace.go:171","msg":"trace[1327843424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11379; }","duration":"172.79502ms","start":"2026-08-21T02:52:58.795591Z","end":"2026-08-21T02:52:58.968386Z","steps":["trace[1327843424] 'agreement among raft nodes before linearized reading' (duration: 172.538645ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:56:42.836893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10642} {"level":"info","ts":"2026-08-21T02:56:42.926888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10642,"took":"86.73832ms","hash":2165144222,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":27242496,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-21T02:56:42.926958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2165144222,"revision":10642,"compact-revision":8153} {"level":"info","ts":"2026-08-21T02:57:38.221764Z","caller":"traceutil/trace.go:171","msg":"trace[1943819140] transaction","detail":"{read_only:false; response_revision:13272; number_of_response:1; }","duration":"107.610629ms","start":"2026-08-21T02:57:38.114093Z","end":"2026-08-21T02:57:38.221704Z","steps":["trace[1943819140] 'process raft request' (duration: 78.44182ms)","trace[1943819140] 'compare' (duration: 28.791662ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:59:21.91623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.111925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-21T02:59:21.916394Z","caller":"traceutil/trace.go:171","msg":"trace[657168801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13937; }","duration":"165.350271ms","start":"2026-08-21T02:59:21.751018Z","end":"2026-08-21T02:59:21.916368Z","steps":["trace[657168801] 'range keys from in-memory index tree' (duration: 164.961483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:21.916518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.838207ms","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-08-21T02:59:21.9166Z","caller":"traceutil/trace.go:171","msg":"trace[1188151431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13937; }","duration":"155.942779ms","start":"2026-08-21T02:59:21.760636Z","end":"2026-08-21T02:59:21.916579Z","steps":["trace[1188151431] 'range keys from in-memory index tree' (duration: 155.733255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:21.91663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.395364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-21T02:59:21.916677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.408893ms","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-08-21T02:59:21.91674Z","caller":"traceutil/trace.go:171","msg":"trace[1759762706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13937; }","duration":"147.633849ms","start":"2026-08-21T02:59:21.769081Z","end":"2026-08-21T02:59:21.916714Z","steps":["trace[1759762706] 'range keys from in-memory index tree' (duration: 147.068298ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:59:21.916794Z","caller":"traceutil/trace.go:171","msg":"trace[75515975] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13937; }","duration":"145.556736ms","start":"2026-08-21T02:59:21.771209Z","end":"2026-08-21T02:59:21.916766Z","steps":["trace[75515975] 'range keys from in-memory index tree' (duration: 145.178059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:21.916395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.267905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-21T02:59:21.91722Z","caller":"traceutil/trace.go:171","msg":"trace[247740563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13937; }","duration":"155.025851ms","start":"2026-08-21T02:59:21.76204Z","end":"2026-08-21T02:59:21.917066Z","steps":["trace[247740563] 'range keys from in-memory index tree' (duration: 154.061061ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:59:40.095381Z","caller":"traceutil/trace.go:171","msg":"trace[1381539591] transaction","detail":"{read_only:false; response_revision:14051; number_of_response:1; }","duration":"255.145007ms","start":"2026-08-21T02:59:39.8402Z","end":"2026-08-21T02:59:40.095345Z","steps":["trace[1381539591] 'process raft request' (duration: 254.754029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:40.473031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.080459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:59:40.473194Z","caller":"traceutil/trace.go:171","msg":"trace[666640768] transaction","detail":"{read_only:false; response_revision:14052; number_of_response:1; }","duration":"372.700641ms","start":"2026-08-21T02:59:40.100465Z","end":"2026-08-21T02:59:40.473165Z","steps":["trace[666640768] 'process raft request' (duration: 268.363187ms)","trace[666640768] 'compare' (duration: 103.666229ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:59:40.473295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:59:40.100426Z","time spent":"372.811353ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:59:40.628181Z","caller":"traceutil/trace.go:171","msg":"trace[611428248] transaction","detail":"{read_only:false; response_revision:14054; number_of_response:1; }","duration":"526.428975ms","start":"2026-08-21T02:59:40.101717Z","end":"2026-08-21T02:59:40.628146Z","steps":["trace[611428248] 'process raft request' (duration: 526.352313ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:59:40.628225Z","caller":"traceutil/trace.go:171","msg":"trace[612043623] transaction","detail":"{read_only:false; response_revision:14053; number_of_response:1; }","duration":"526.806832ms","start":"2026-08-21T02:59:40.101328Z","end":"2026-08-21T02:59:40.628135Z","steps":["trace[612043623] 'process raft request' (duration: 526.332153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:40.628424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:59:40.101696Z","time spent":"526.576388ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:59:40.628437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:59:40.101311Z","time spent":"527.011226ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:59:40.62866Z","caller":"traceutil/trace.go:171","msg":"trace[289298766] linearizableReadLoop","detail":"{readStateIndex:14542; appliedIndex:14537; }","duration":"487.973608ms","start":"2026-08-21T02:59:40.140659Z","end":"2026-08-21T02:59:40.628632Z","steps":["trace[289298766] 'read index received' (duration: 228.271669ms)","trace[289298766] 'applied index is now lower than readState.Index' (duration: 259.700729ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:59:40.62869Z","caller":"traceutil/trace.go:171","msg":"trace[1490071415] transaction","detail":"{read_only:false; response_revision:14055; number_of_response:1; }","duration":"526.66773ms","start":"2026-08-21T02:59:40.101981Z","end":"2026-08-21T02:59:40.628648Z","steps":["trace[1490071415] 'process raft request' (duration: 526.133569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:40.628883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:59:40.101971Z","time spent":"526.811322ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:59:40.629025Z","caller":"traceutil/trace.go:171","msg":"trace[1923117961] transaction","detail":"{read_only:false; response_revision:14056; number_of_response:1; }","duration":"526.17754ms","start":"2026-08-21T02:59:40.102676Z","end":"2026-08-21T02:59:40.628853Z","steps":["trace[1923117961] 'process raft request' (duration: 525.791382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:40.629164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.183186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:59:40.629198Z","caller":"traceutil/trace.go:171","msg":"trace[483521948] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:14056; }","duration":"453.270868ms","start":"2026-08-21T02:59:40.175918Z","end":"2026-08-21T02:59:40.629189Z","steps":["trace[483521948] 'agreement among raft nodes before linearized reading' (duration: 453.128975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:40.629229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:59:40.175896Z","time spent":"453.327709ms","remote":"127.0.0.1:42424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":104,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:59:40.629285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:59:40.102668Z","time spent":"526.488326ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:59:40.629508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.748587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-21T02:59:40.629624Z","caller":"traceutil/trace.go:171","msg":"trace[197042085] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:14056; }","duration":"383.948051ms","start":"2026-08-21T02:59:40.245642Z","end":"2026-08-21T02:59:40.62959Z","steps":["trace[197042085] 'agreement among raft nodes before linearized reading' (duration: 383.633133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:40.629684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:59:40.245609Z","time spent":"384.061014ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-21T02:59:40.629758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.082701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:930194"} {"level":"info","ts":"2026-08-21T02:59:40.629811Z","caller":"traceutil/trace.go:171","msg":"trace[1054735999] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:14056; }","duration":"489.215034ms","start":"2026-08-21T02:59:40.140584Z","end":"2026-08-21T02:59:40.629799Z","steps":["trace[1054735999] 'agreement among raft nodes before linearized reading' (duration: 488.215243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:59:40.629875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:59:40.140554Z","time spent":"489.308826ms","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":86,"response size":930216,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-08-21T03:01:25.152312Z","caller":"traceutil/trace.go:171","msg":"trace[547813507] transaction","detail":"{read_only:false; response_revision:15273; number_of_response:1; }","duration":"106.424506ms","start":"2026-08-21T03:01:25.045831Z","end":"2026-08-21T03:01:25.152255Z","steps":["trace[547813507] 'process raft request' (duration: 106.250373ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:30.263131Z","caller":"traceutil/trace.go:171","msg":"trace[890680966] transaction","detail":"{read_only:false; response_revision:15303; number_of_response:1; }","duration":"105.17187ms","start":"2026-08-21T03:01:30.157918Z","end":"2026-08-21T03:01:30.26309Z","steps":["trace[890680966] 'process raft request' (duration: 104.386674ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:31.342144Z","caller":"traceutil/trace.go:171","msg":"trace[685932956] linearizableReadLoop","detail":"{readStateIndex:15834; appliedIndex:15833; }","duration":"155.048759ms","start":"2026-08-21T03:01:31.187071Z","end":"2026-08-21T03:01:31.34212Z","steps":["trace[685932956] 'read index received' (duration: 66.210894ms)","trace[685932956] 'applied index is now lower than readState.Index' (duration: 88.836925ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:01:31.342684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.584562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-21T03:01:31.342753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.736512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45nbx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T03:01:31.342788Z","caller":"traceutil/trace.go:171","msg":"trace[1279099258] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45nbx; range_end:; response_count:1; response_revision:15305; }","duration":"126.908495ms","start":"2026-08-21T03:01:31.21587Z","end":"2026-08-21T03:01:31.342778Z","steps":["trace[1279099258] 'agreement among raft nodes before linearized reading' (duration: 126.725682ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:31.342789Z","caller":"traceutil/trace.go:171","msg":"trace[173105924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15305; }","duration":"155.747634ms","start":"2026-08-21T03:01:31.187015Z","end":"2026-08-21T03:01:31.342762Z","steps":["trace[173105924] 'agreement among raft nodes before linearized reading' (duration: 155.449218ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:31.343552Z","caller":"traceutil/trace.go:171","msg":"trace[1896476716] transaction","detail":"{read_only:false; response_revision:15305; number_of_response:1; }","duration":"159.201785ms","start":"2026-08-21T03:01:31.184335Z","end":"2026-08-21T03:01:31.343537Z","steps":["trace[1896476716] 'process raft request' (duration: 154.796084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:01:31.343789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.683686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-bwtwj\" ","response":"range_response_count:1 size:20653"} {"level":"info","ts":"2026-08-21T03:01:31.343814Z","caller":"traceutil/trace.go:171","msg":"trace[1837274983] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-bwtwj; range_end:; response_count:1; response_revision:15305; }","duration":"116.721547ms","start":"2026-08-21T03:01:31.227085Z","end":"2026-08-21T03:01:31.343807Z","steps":["trace[1837274983] 'agreement among raft nodes before linearized reading' (duration: 116.595665ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:42.844112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12811} {"level":"info","ts":"2026-08-21T03:01:42.926054Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12811,"took":"78.655999ms","hash":319817728,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":30748672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-21T03:01:42.926147Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":319817728,"revision":12811,"compact-revision":10642} {"level":"info","ts":"2026-08-21T03:02:35.966599Z","caller":"traceutil/trace.go:171","msg":"trace[11482678] linearizableReadLoop","detail":"{readStateIndex:16277; appliedIndex:16276; }","duration":"334.679698ms","start":"2026-08-21T03:02:35.631883Z","end":"2026-08-21T03:02:35.966562Z","steps":["trace[11482678] 'read index received' (duration: 328.701216ms)","trace[11482678] 'applied index is now lower than readState.Index' (duration: 5.976612ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:02:35.968718Z","caller":"traceutil/trace.go:171","msg":"trace[1301423313] transaction","detail":"{read_only:false; response_revision:15733; number_of_response:1; }","duration":"365.658691ms","start":"2026-08-21T03:02:35.603007Z","end":"2026-08-21T03:02:35.968666Z","steps":["trace[1301423313] 'process raft request' (duration: 357.649097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.968926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:35.602963Z","time spent":"365.846875ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:02:35.968934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.481814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-21T03:02:35.968937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.498908ms","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-08-21T03:02:35.968969Z","caller":"traceutil/trace.go:171","msg":"trace[1663113123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15733; }","duration":"241.572716ms","start":"2026-08-21T03:02:35.727386Z","end":"2026-08-21T03:02:35.968959Z","steps":["trace[1663113123] 'agreement among raft nodes before linearized reading' (duration: 241.421083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.969066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.645262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qd5rg\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-21T03:02:35.969095Z","caller":"traceutil/trace.go:171","msg":"trace[264082716] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qd5rg; range_end:; response_count:1; response_revision:15733; }","duration":"115.722805ms","start":"2026-08-21T03:02:35.853365Z","end":"2026-08-21T03:02:35.969088Z","steps":["trace[264082716] 'agreement among raft nodes before linearized reading' (duration: 115.639123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.969116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.826095ms","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-08-21T03:02:35.969142Z","caller":"traceutil/trace.go:171","msg":"trace[1351566174] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15733; }","duration":"227.873886ms","start":"2026-08-21T03:02:35.741259Z","end":"2026-08-21T03:02:35.969133Z","steps":["trace[1351566174] 'agreement among raft nodes before linearized reading' (duration: 227.813544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.969229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.38842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-21T03:02:35.969255Z","caller":"traceutil/trace.go:171","msg":"trace[899455555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15733; }","duration":"236.426051ms","start":"2026-08-21T03:02:35.732822Z","end":"2026-08-21T03:02:35.969248Z","steps":["trace[899455555] 'agreement among raft nodes before linearized reading' (duration: 236.36628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.969205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.905487ms","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-08-21T03:02:35.969391Z","caller":"traceutil/trace.go:171","msg":"trace[400303494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15733; }","duration":"227.201352ms","start":"2026-08-21T03:02:35.742159Z","end":"2026-08-21T03:02:35.96936Z","steps":["trace[400303494] 'agreement among raft nodes before linearized reading' (duration: 226.651921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.96941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.321891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T03:02:35.969461Z","caller":"traceutil/trace.go:171","msg":"trace[213988988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15733; }","duration":"173.371552ms","start":"2026-08-21T03:02:35.796081Z","end":"2026-08-21T03:02:35.969452Z","steps":["trace[213988988] 'agreement among raft nodes before linearized reading' (duration: 173.24599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.969407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.582163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qd5rg\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-21T03:02:35.968978Z","caller":"traceutil/trace.go:171","msg":"trace[1851394104] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15733; }","duration":"134.61859ms","start":"2026-08-21T03:02:35.834345Z","end":"2026-08-21T03:02:35.968964Z","steps":["trace[1851394104] 'agreement among raft nodes before linearized reading' (duration: 134.460477ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:02:35.969542Z","caller":"traceutil/trace.go:171","msg":"trace[95689390] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qd5rg; range_end:; response_count:1; response_revision:15733; }","duration":"166.737767ms","start":"2026-08-21T03:02:35.802795Z","end":"2026-08-21T03:02:35.969533Z","steps":["trace[95689390] 'agreement among raft nodes before linearized reading' (duration: 166.38392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.969746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.660316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-21T03:02:35.969853Z","caller":"traceutil/trace.go:171","msg":"trace[1765335721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15733; }","duration":"238.829351ms","start":"2026-08-21T03:02:35.730999Z","end":"2026-08-21T03:02:35.969828Z","steps":["trace[1765335721] 'agreement among raft nodes before linearized reading' (duration: 238.217917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.970328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.439595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T03:02:35.971009Z","caller":"traceutil/trace.go:171","msg":"trace[76260799] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:15733; }","duration":"339.17297ms","start":"2026-08-21T03:02:35.63182Z","end":"2026-08-21T03:02:35.970993Z","steps":["trace[76260799] 'agreement among raft nodes before linearized reading' (duration: 336.885373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:35.97106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:35.631797Z","time spent":"339.248972ms","remote":"127.0.0.1:42414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":125,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:02:56.127056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972656826893599,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-21T03:02:56.333718Z","caller":"traceutil/trace.go:171","msg":"trace[373184834] transaction","detail":"{read_only:false; response_revision:15868; number_of_response:1; }","duration":"740.00951ms","start":"2026-08-21T03:02:55.593674Z","end":"2026-08-21T03:02:56.333683Z","steps":["trace[373184834] 'process raft request' (duration: 739.821636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.33392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:55.593636Z","time spent":"740.176043ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:02:56.33447Z","caller":"traceutil/trace.go:171","msg":"trace[632620361] linearizableReadLoop","detail":"{readStateIndex:16417; appliedIndex:16415; }","duration":"707.997037ms","start":"2026-08-21T03:02:55.626437Z","end":"2026-08-21T03:02:56.334434Z","steps":["trace[632620361] 'read index received' (duration: 707.16203ms)","trace[632620361] 'applied index is now lower than readState.Index' (duration: 833.577µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:02:56.334544Z","caller":"traceutil/trace.go:171","msg":"trace[1268378099] transaction","detail":"{read_only:false; response_revision:15869; number_of_response:1; }","duration":"286.646027ms","start":"2026-08-21T03:02:56.047887Z","end":"2026-08-21T03:02:56.334533Z","steps":["trace[1268378099] 'process raft request' (duration: 286.369461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.334806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.82725ms","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-08-21T03:02:56.334844Z","caller":"traceutil/trace.go:171","msg":"trace[326596669] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15869; }","duration":"307.912722ms","start":"2026-08-21T03:02:56.026921Z","end":"2026-08-21T03:02:56.334834Z","steps":["trace[326596669] 'agreement among raft nodes before linearized reading' (duration: 307.791789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.334871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.026894Z","time spent":"307.972943ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-21T03:02:56.334909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.664354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-21T03:02:56.334961Z","caller":"traceutil/trace.go:171","msg":"trace[570399792] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15869; }","duration":"233.727685ms","start":"2026-08-21T03:02:56.101214Z","end":"2026-08-21T03:02:56.334942Z","steps":["trace[570399792] 'agreement among raft nodes before linearized reading' (duration: 233.629173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.33502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.563904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-21T03:02:56.335059Z","caller":"traceutil/trace.go:171","msg":"trace[1277857019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15869; }","duration":"237.612115ms","start":"2026-08-21T03:02:56.09744Z","end":"2026-08-21T03:02:56.335052Z","steps":["trace[1277857019] 'agreement among raft nodes before linearized reading' (duration: 237.530994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.33515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.107046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T03:02:56.335174Z","caller":"traceutil/trace.go:171","msg":"trace[2138920173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15869; }","duration":"241.157278ms","start":"2026-08-21T03:02:56.094012Z","end":"2026-08-21T03:02:56.335169Z","steps":["trace[2138920173] 'agreement among raft nodes before linearized reading' (duration: 241.096906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.335292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.462064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-21T03:02:56.335318Z","caller":"traceutil/trace.go:171","msg":"trace[1852747390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15869; }","duration":"242.525064ms","start":"2026-08-21T03:02:56.092785Z","end":"2026-08-21T03:02:56.33531Z","steps":["trace[1852747390] 'agreement among raft nodes before linearized reading' (duration: 242.436413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.335405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.24426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-21T03:02:56.335421Z","caller":"traceutil/trace.go:171","msg":"trace[376481646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15869; }","duration":"244.319972ms","start":"2026-08-21T03:02:56.091096Z","end":"2026-08-21T03:02:56.335416Z","steps":["trace[376481646] 'agreement among raft nodes before linearized reading' (duration: 244.269771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.334803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.347103ms","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-08-21T03:02:56.335514Z","caller":"traceutil/trace.go:171","msg":"trace[1431627037] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15869; }","duration":"709.138489ms","start":"2026-08-21T03:02:55.626369Z","end":"2026-08-21T03:02:56.335507Z","steps":["trace[1431627037] 'agreement among raft nodes before linearized reading' (duration: 708.230671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.335536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:55.62633Z","time spent":"709.199521ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-21T03:02:56.335844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.426752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-08-21T03:02:56.335864Z","caller":"traceutil/trace.go:171","msg":"trace[1314406558] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:15869; }","duration":"121.491563ms","start":"2026-08-21T03:02:56.214367Z","end":"2026-08-21T03:02:56.335858Z","steps":["trace[1314406558] 'agreement among raft nodes before linearized reading' (duration: 121.429372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.816515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-21T03:02:56.33603Z","caller":"traceutil/trace.go:171","msg":"trace[1566478650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15869; }","duration":"203.846516ms","start":"2026-08-21T03:02:56.132178Z","end":"2026-08-21T03:02:56.336024Z","steps":["trace[1566478650] 'agreement among raft nodes before linearized reading' (duration: 203.781415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.878709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.662959ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T03:02:56.878831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.340957Z","time spent":"537.86694ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-21T03:02:56.878929Z","caller":"traceutil/trace.go:171","msg":"trace[165484906] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15869; }","duration":"407.892724ms","start":"2026-08-21T03:02:56.471002Z","end":"2026-08-21T03:02:56.878879Z","steps":["trace[165484906] 'range keys from in-memory index tree' (duration: 407.637639ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:02:56.881155Z","caller":"traceutil/trace.go:171","msg":"trace[832314884] transaction","detail":"{read_only:false; response_revision:15872; number_of_response:1; }","duration":"539.774308ms","start":"2026-08-21T03:02:56.34135Z","end":"2026-08-21T03:02:56.881125Z","steps":["trace[832314884] 'process raft request' (duration: 539.716437ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:02:56.881199Z","caller":"traceutil/trace.go:171","msg":"trace[622417070] transaction","detail":"{read_only:false; response_revision:15871; number_of_response:1; }","duration":"539.801039ms","start":"2026-08-21T03:02:56.341364Z","end":"2026-08-21T03:02:56.881165Z","steps":["trace[622417070] 'process raft request' (duration: 539.667987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.881309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.341319Z","time spent":"539.908682ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:02:56.881335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.341351Z","time spent":"539.898281ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:02:56.881456Z","caller":"traceutil/trace.go:171","msg":"trace[22014691] transaction","detail":"{read_only:false; response_revision:15873; number_of_response:1; }","duration":"540.094535ms","start":"2026-08-21T03:02:56.341349Z","end":"2026-08-21T03:02:56.881444Z","steps":["trace[22014691] 'process raft request' (duration: 539.744968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.881515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.341311Z","time spent":"540.171157ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:02:56.881562Z","caller":"traceutil/trace.go:171","msg":"trace[1635795186] linearizableReadLoop","detail":"{readStateIndex:16424; appliedIndex:16417; }","duration":"504.182393ms","start":"2026-08-21T03:02:56.377347Z","end":"2026-08-21T03:02:56.881529Z","steps":["trace[1635795186] 'read index received' (duration: 499.447647ms)","trace[1635795186] 'applied index is now lower than readState.Index' (duration: 4.733536ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:02:56.881642Z","caller":"traceutil/trace.go:171","msg":"trace[546469025] transaction","detail":"{read_only:false; response_revision:15874; number_of_response:1; }","duration":"540.135146ms","start":"2026-08-21T03:02:56.341501Z","end":"2026-08-21T03:02:56.881636Z","steps":["trace[546469025] 'process raft request' (duration: 539.782829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.881679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.341493Z","time spent":"540.166496ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:02:56.881689Z","caller":"traceutil/trace.go:171","msg":"trace[512211553] transaction","detail":"{read_only:false; response_revision:15876; number_of_response:1; }","duration":"263.27786ms","start":"2026-08-21T03:02:56.618401Z","end":"2026-08-21T03:02:56.881679Z","steps":["trace[512211553] 'process raft request' (duration: 263.049485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.881859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.524939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45nbx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T03:02:56.881615Z","caller":"traceutil/trace.go:171","msg":"trace[541777362] transaction","detail":"{read_only:false; response_revision:15875; number_of_response:1; }","duration":"538.990553ms","start":"2026-08-21T03:02:56.342615Z","end":"2026-08-21T03:02:56.881606Z","steps":["trace[541777362] 'process raft request' (duration: 538.770619ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:02:56.881882Z","caller":"traceutil/trace.go:171","msg":"trace[2087067732] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45nbx; range_end:; response_count:1; response_revision:15877; }","duration":"504.602281ms","start":"2026-08-21T03:02:56.377274Z","end":"2026-08-21T03:02:56.881876Z","steps":["trace[2087067732] 'agreement among raft nodes before linearized reading' (duration: 504.461618ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:02:56.881873Z","caller":"traceutil/trace.go:171","msg":"trace[1341362651] transaction","detail":"{read_only:false; response_revision:15877; number_of_response:1; }","duration":"124.189657ms","start":"2026-08-21T03:02:56.757646Z","end":"2026-08-21T03:02:56.881836Z","steps":["trace[1341362651] 'process raft request' (duration: 123.84055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.881904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.377233Z","time spent":"504.667032ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-45nbx\" "} {"level":"warn","ts":"2026-08-21T03:02:56.881926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.342605Z","time spent":"539.285809ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:02:56.882092Z","caller":"traceutil/trace.go:171","msg":"trace[338494239] transaction","detail":"{read_only:false; response_revision:15870; number_of_response:1; }","duration":"540.782111ms","start":"2026-08-21T03:02:56.341258Z","end":"2026-08-21T03:02:56.88204Z","steps":["trace[338494239] 'process raft request' (duration: 539.608066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.882186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.34123Z","time spent":"540.896144ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:02:56.884697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.320848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-08-21T03:02:56.884781Z","caller":"traceutil/trace.go:171","msg":"trace[22755207] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15878; }","duration":"303.433271ms","start":"2026-08-21T03:02:56.581329Z","end":"2026-08-21T03:02:56.884762Z","steps":["trace[22755207] 'agreement among raft nodes before linearized reading' (duration: 303.178825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.88482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.581325Z","time spent":"303.488173ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":704,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-08-21T03:02:56.885014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.487563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-bwtwj\" ","response":"range_response_count:1 size:20653"} {"level":"info","ts":"2026-08-21T03:02:56.885283Z","caller":"traceutil/trace.go:171","msg":"trace[1666384561] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-bwtwj; range_end:; response_count:1; response_revision:15878; }","duration":"395.593445ms","start":"2026-08-21T03:02:56.489471Z","end":"2026-08-21T03:02:56.885064Z","steps":["trace[1666384561] 'agreement among raft nodes before linearized reading' (duration: 395.449782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.885297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.156144ms","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-08-21T03:02:56.885332Z","caller":"traceutil/trace.go:171","msg":"trace[564440960] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15878; }","duration":"301.211776ms","start":"2026-08-21T03:02:56.584109Z","end":"2026-08-21T03:02:56.885321Z","steps":["trace[564440960] 'agreement among raft nodes before linearized reading' (duration: 301.117284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.885358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.584042Z","time spent":"301.311138ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-21T03:02:56.885357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.489448Z","time spent":"395.89649ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20675,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-bwtwj\" "} {"level":"warn","ts":"2026-08-21T03:02:56.884986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.375994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:83 size:809288"} {"level":"info","ts":"2026-08-21T03:02:56.885466Z","caller":"traceutil/trace.go:171","msg":"trace[2094359097] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:83; response_revision:15878; }","duration":"405.917375ms","start":"2026-08-21T03:02:56.479525Z","end":"2026-08-21T03:02:56.885442Z","steps":["trace[2094359097] 'agreement among raft nodes before linearized reading' (duration: 404.488756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.885528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.479499Z","time spent":"406.002086ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":83,"response size":809310,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-21T03:02:56.88554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.838323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:83 size:809288"} {"level":"info","ts":"2026-08-21T03:02:56.885572Z","caller":"traceutil/trace.go:171","msg":"trace[84497124] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:83; response_revision:15878; }","duration":"405.924965ms","start":"2026-08-21T03:02:56.479639Z","end":"2026-08-21T03:02:56.885564Z","steps":["trace[84497124] 'agreement among raft nodes before linearized reading' (duration: 405.411794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.885568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.213587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T03:02:56.885604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.479603Z","time spent":"405.992806ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":83,"response size":809310,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-21T03:02:56.885662Z","caller":"traceutil/trace.go:171","msg":"trace[1851847948] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15878; }","duration":"304.34504ms","start":"2026-08-21T03:02:56.581265Z","end":"2026-08-21T03:02:56.88561Z","steps":["trace[1851847948] 'agreement among raft nodes before linearized reading' (duration: 303.960502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.885976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:02:56.581243Z","time spent":"304.458331ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":27,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-08-21T03:06:42.851189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15366} {"level":"info","ts":"2026-08-21T03:06:42.95884Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15366,"took":"103.657021ms","hash":1463851282,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-21T03:06:42.958927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1463851282,"revision":15366,"compact-revision":12811} {"level":"info","ts":"2026-08-21T03:07:01.751242Z","caller":"traceutil/trace.go:171","msg":"trace[795526499] transaction","detail":"{read_only:false; response_revision:17774; number_of_response:1; }","duration":"115.158671ms","start":"2026-08-21T03:07:01.636059Z","end":"2026-08-21T03:07:01.751217Z","steps":["trace[795526499] 'process raft request' (duration: 114.732153ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:06.087689Z","caller":"traceutil/trace.go:171","msg":"trace[2088941415] transaction","detail":"{read_only:false; response_revision:17853; number_of_response:1; }","duration":"185.547549ms","start":"2026-08-21T03:07:05.902105Z","end":"2026-08-21T03:07:06.087653Z","steps":["trace[2088941415] 'process raft request' (duration: 185.343995ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:07.524668Z","caller":"traceutil/trace.go:171","msg":"trace[2023521058] transaction","detail":"{read_only:false; response_revision:17870; number_of_response:1; }","duration":"181.006226ms","start":"2026-08-21T03:07:07.343633Z","end":"2026-08-21T03:07:07.524639Z","steps":["trace[2023521058] 'process raft request' (duration: 180.827603ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:07.549894Z","caller":"traceutil/trace.go:171","msg":"trace[1831097440] transaction","detail":"{read_only:false; response_revision:17871; number_of_response:1; }","duration":"205.907945ms","start":"2026-08-21T03:07:07.343957Z","end":"2026-08-21T03:07:07.549865Z","steps":["trace[1831097440] 'process raft request' (duration: 205.776372ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:47.857884Z","caller":"traceutil/trace.go:171","msg":"trace[1791717034] linearizableReadLoop","detail":"{readStateIndex:18982; appliedIndex:18981; }","duration":"200.681289ms","start":"2026-08-21T03:07:47.657176Z","end":"2026-08-21T03:07:47.857857Z","steps":["trace[1791717034] 'read index received' (duration: 200.482215ms)","trace[1791717034] 'applied index is now lower than readState.Index' (duration: 197.864µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:07:47.857989Z","caller":"traceutil/trace.go:171","msg":"trace[183377395] transaction","detail":"{read_only:false; response_revision:18354; number_of_response:1; }","duration":"389.521725ms","start":"2026-08-21T03:07:47.468424Z","end":"2026-08-21T03:07:47.857946Z","steps":["trace[183377395] 'process raft request' (duration: 389.243459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:47.858062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.861432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:47.8581Z","caller":"traceutil/trace.go:171","msg":"trace[524522751] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18354; }","duration":"200.968095ms","start":"2026-08-21T03:07:47.657121Z","end":"2026-08-21T03:07:47.85809Z","steps":["trace[524522751] 'agreement among raft nodes before linearized reading' (duration: 200.879883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:47.858224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:47.468254Z","time spent":"389.842111ms","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:07:47.858253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.086257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:47.858404Z","caller":"traceutil/trace.go:171","msg":"trace[1293512686] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18354; }","duration":"201.2379ms","start":"2026-08-21T03:07:47.657153Z","end":"2026-08-21T03:07:47.858391Z","steps":["trace[1293512686] 'agreement among raft nodes before linearized reading' (duration: 201.054396ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:47.861309Z","caller":"traceutil/trace.go:171","msg":"trace[1500174421] transaction","detail":"{read_only:false; response_revision:18355; number_of_response:1; }","duration":"185.511039ms","start":"2026-08-21T03:07:47.67576Z","end":"2026-08-21T03:07:47.861271Z","steps":["trace[1500174421] 'process raft request' (duration: 185.212333ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:47.861521Z","caller":"traceutil/trace.go:171","msg":"trace[1631543834] transaction","detail":"{read_only:false; response_revision:18356; number_of_response:1; }","duration":"146.091853ms","start":"2026-08-21T03:07:47.7154Z","end":"2026-08-21T03:07:47.861492Z","steps":["trace[1631543834] 'process raft request' (duration: 145.800917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:47.861628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.622203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:47.861685Z","caller":"traceutil/trace.go:171","msg":"trace[1185941409] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:18356; }","duration":"189.721985ms","start":"2026-08-21T03:07:47.671944Z","end":"2026-08-21T03:07:47.861666Z","steps":["trace[1185941409] 'agreement among raft nodes before linearized reading' (duration: 189.603803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:47.861687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.116748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:47.861732Z","caller":"traceutil/trace.go:171","msg":"trace[1295762742] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:18356; }","duration":"202.186059ms","start":"2026-08-21T03:07:47.659533Z","end":"2026-08-21T03:07:47.861719Z","steps":["trace[1295762742] 'agreement among raft nodes before linearized reading' (duration: 202.117317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:47.861802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.150178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:47.861817Z","caller":"traceutil/trace.go:171","msg":"trace[841387613] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18356; }","duration":"202.207139ms","start":"2026-08-21T03:07:47.659604Z","end":"2026-08-21T03:07:47.861811Z","steps":["trace[841387613] 'agreement among raft nodes before linearized reading' (duration: 202.178109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:47.862069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.377773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:47.862156Z","caller":"traceutil/trace.go:171","msg":"trace[1768877787] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18356; }","duration":"202.71688ms","start":"2026-08-21T03:07:47.659418Z","end":"2026-08-21T03:07:47.862135Z","steps":["trace[1768877787] 'agreement among raft nodes before linearized reading' (duration: 202.405794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:47.86238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.379462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:47.862438Z","caller":"traceutil/trace.go:171","msg":"trace[1969582051] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18356; }","duration":"203.456965ms","start":"2026-08-21T03:07:47.658966Z","end":"2026-08-21T03:07:47.862423Z","steps":["trace[1969582051] 'agreement among raft nodes before linearized reading' (duration: 202.501135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:48.462806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972656826902054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:07:48.963815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972656826902054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:07:49.294033Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.339765411s","expected-duration":"1s"} {"level":"info","ts":"2026-08-21T03:07:49.295002Z","caller":"traceutil/trace.go:171","msg":"trace[507951019] transaction","detail":"{read_only:false; response_revision:18360; number_of_response:1; }","duration":"1.340951926s","start":"2026-08-21T03:07:47.954005Z","end":"2026-08-21T03:07:49.294957Z","steps":["trace[507951019] 'process raft request' (duration: 1.34067668s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.29521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:47.953961Z","time spent":"1.34114005s","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65932,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:07:49.300025Z","caller":"traceutil/trace.go:171","msg":"trace[1074466546] linearizableReadLoop","detail":"{readStateIndex:18989; appliedIndex:18988; }","duration":"1.337322662s","start":"2026-08-21T03:07:47.962678Z","end":"2026-08-21T03:07:49.300001Z","steps":["trace[1074466546] 'read index received' (duration: 1.332375401s)","trace[1074466546] 'applied index is now lower than readState.Index' (duration: 4.945871ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:07:49.300324Z","caller":"traceutil/trace.go:171","msg":"trace[1620719646] transaction","detail":"{read_only:false; response_revision:18361; number_of_response:1; }","duration":"1.031291851s","start":"2026-08-21T03:07:48.268993Z","end":"2026-08-21T03:07:49.300285Z","steps":["trace[1620719646] 'process raft request' (duration: 1.030875282s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.268974Z","time spent":"1.031382353s","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:07:49.300514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.132507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-21T03:07:49.300523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337569047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:49.300575Z","caller":"traceutil/trace.go:171","msg":"trace[474310305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18361; }","duration":"720.205188ms","start":"2026-08-21T03:07:48.580355Z","end":"2026-08-21T03:07:49.30056Z","steps":["trace[474310305] 'agreement among raft nodes before linearized reading' (duration: 720.006234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.951262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:07:49.300609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.580349Z","time spent":"720.252289ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-21T03:07:49.300506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33774778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:07:49.300691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.802721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qd5rg\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-08-21T03:07:49.300696Z","caller":"traceutil/trace.go:171","msg":"trace[1848322003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18361; }","duration":"505.114285ms","start":"2026-08-21T03:07:48.795555Z","end":"2026-08-21T03:07:49.300669Z","steps":["trace[1848322003] 'agreement among raft nodes before linearized reading' (duration: 504.956912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.72037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-21T03:07:49.300676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.315537ms","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-08-21T03:07:49.300729Z","caller":"traceutil/trace.go:171","msg":"trace[1308718597] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qd5rg; range_end:; response_count:1; response_revision:18361; }","duration":"317.875032ms","start":"2026-08-21T03:07:48.982844Z","end":"2026-08-21T03:07:49.300719Z","steps":["trace[1308718597] 'agreement among raft nodes before linearized reading' (duration: 317.682959ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:49.300749Z","caller":"traceutil/trace.go:171","msg":"trace[666471727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18361; }","duration":"700.772922ms","start":"2026-08-21T03:07:48.599967Z","end":"2026-08-21T03:07:49.30074Z","steps":["trace[666471727] 'agreement among raft nodes before linearized reading' (duration: 700.68336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.795531Z","time spent":"505.207377ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T03:07:49.300767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.982824Z","time spent":"317.935273ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22148,"request content":"key:\"/registry/pods/openstack/nova-compute-default-qd5rg\" "} {"level":"warn","ts":"2026-08-21T03:07:49.300778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.599959Z","time spent":"700.813902ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-21T03:07:49.300778Z","caller":"traceutil/trace.go:171","msg":"trace[989419600] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18361; }","duration":"640.525671ms","start":"2026-08-21T03:07:48.660227Z","end":"2026-08-21T03:07:49.300752Z","steps":["trace[989419600] 'agreement among raft nodes before linearized reading' (duration: 640.199084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.707826ms","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-08-21T03:07:49.30072Z","caller":"traceutil/trace.go:171","msg":"trace[1809650177] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18361; }","duration":"1.338027196s","start":"2026-08-21T03:07:47.962674Z","end":"2026-08-21T03:07:49.300701Z","steps":["trace[1809650177] 'agreement among raft nodes before linearized reading' (duration: 1.337648588s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.660202Z","time spent":"640.621723ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-21T03:07:49.300844Z","caller":"traceutil/trace.go:171","msg":"trace[262133901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18361; }","duration":"713.751307ms","start":"2026-08-21T03:07:48.587085Z","end":"2026-08-21T03:07:49.300836Z","steps":["trace[262133901] 'agreement among raft nodes before linearized reading' (duration: 713.645355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.86419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-21T03:07:49.300868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.587076Z","time spent":"713.785947ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T03:07:49.300863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:47.962644Z","time spent":"1.338204959s","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:07:49.300887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337544177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:07:49.300846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106791723s","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":"warn","ts":"2026-08-21T03:07:49.300904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.659628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-21T03:07:49.30092Z","caller":"traceutil/trace.go:171","msg":"trace[1378015408] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18361; }","duration":"1.337616857s","start":"2026-08-21T03:07:47.963293Z","end":"2026-08-21T03:07:49.30091Z","steps":["trace[1378015408] 'agreement among raft nodes before linearized reading' (duration: 1.337567876s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:49.300923Z","caller":"traceutil/trace.go:171","msg":"trace[2059072460] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:18361; }","duration":"1.106917625s","start":"2026-08-21T03:07:48.193995Z","end":"2026-08-21T03:07:49.300912Z","steps":["trace[2059072460] 'agreement among raft nodes before linearized reading' (duration: 1.106762282s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:47.963263Z","time spent":"1.337673259s","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"info","ts":"2026-08-21T03:07:49.300942Z","caller":"traceutil/trace.go:171","msg":"trace[225061292] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:18361; }","duration":"721.70901ms","start":"2026-08-21T03:07:48.579218Z","end":"2026-08-21T03:07:49.300927Z","steps":["trace[225061292] 'agreement among raft nodes before linearized reading' (duration: 721.649768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.300977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.579212Z","time spent":"721.75507ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:07:49.300981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337902383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:49.300891Z","caller":"traceutil/trace.go:171","msg":"trace[1285236740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18361; }","duration":"692.958072ms","start":"2026-08-21T03:07:48.607919Z","end":"2026-08-21T03:07:49.300877Z","steps":["trace[1285236740] 'agreement among raft nodes before linearized reading' (duration: 692.834049ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:49.301025Z","caller":"traceutil/trace.go:171","msg":"trace[981160230] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18361; }","duration":"1.337942544s","start":"2026-08-21T03:07:47.963063Z","end":"2026-08-21T03:07:49.301006Z","steps":["trace[981160230] 'agreement among raft nodes before linearized reading' (duration: 1.337888253s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.301035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.607894Z","time spent":"693.132955ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-21T03:07:49.301045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:47.963059Z","time spent":"1.337981965s","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"warn","ts":"2026-08-21T03:07:49.301119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.193964Z","time spent":"1.1071448s","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-08-21T03:07:49.301169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337676439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:49.301183Z","caller":"traceutil/trace.go:171","msg":"trace[1337844850] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18361; }","duration":"1.33772834s","start":"2026-08-21T03:07:47.963449Z","end":"2026-08-21T03:07:49.301178Z","steps":["trace[1337844850] 'agreement among raft nodes before linearized reading' (duration: 1.337702259s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.301182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047202396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:49.301227Z","caller":"traceutil/trace.go:171","msg":"trace[1366421052] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:18361; }","duration":"1.047287398s","start":"2026-08-21T03:07:48.253926Z","end":"2026-08-21T03:07:49.301214Z","steps":["trace[1366421052] 'agreement among raft nodes before linearized reading' (duration: 1.047211167s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.302728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.25391Z","time spent":"1.048794629s","remote":"127.0.0.1:57298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:07:49.301196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:47.963445Z","time spent":"1.3377474s","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-08-21T03:07:49.301285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.634912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-21T03:07:49.30313Z","caller":"traceutil/trace.go:171","msg":"trace[53231898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18361; }","duration":"733.5287ms","start":"2026-08-21T03:07:48.569583Z","end":"2026-08-21T03:07:49.303112Z","steps":["trace[53231898] 'agreement among raft nodes before linearized reading' (duration: 731.653612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.303214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.569546Z","time spent":"733.640453ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-21T03:07:49.300614Z","caller":"traceutil/trace.go:171","msg":"trace[685378942] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18361; }","duration":"1.337688179s","start":"2026-08-21T03:07:47.962902Z","end":"2026-08-21T03:07:49.30059Z","steps":["trace[685378942] 'agreement among raft nodes before linearized reading' (duration: 1.337451744s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.303261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:47.962896Z","time spent":"1.340351793s","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-08-21T03:07:49.301344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.212669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fwshn\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-08-21T03:07:49.303365Z","caller":"traceutil/trace.go:171","msg":"trace[2019822132] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fwshn; range_end:; response_count:1; response_revision:18361; }","duration":"390.26328ms","start":"2026-08-21T03:07:48.913089Z","end":"2026-08-21T03:07:49.303353Z","steps":["trace[2019822132] 'agreement among raft nodes before linearized reading' (duration: 387.367931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.303393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:48.913069Z","time spent":"390.318182ms","remote":"127.0.0.1:42264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9822,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-fwshn\" "} {"level":"warn","ts":"2026-08-21T03:07:49.300981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33774314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:49.303515Z","caller":"traceutil/trace.go:171","msg":"trace[1102994074] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:18361; }","duration":"1.340289812s","start":"2026-08-21T03:07:47.963218Z","end":"2026-08-21T03:07:49.303508Z","steps":["trace[1102994074] 'agreement among raft nodes before linearized reading' (duration: 1.33772826s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:49.303538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:47.963211Z","time spent":"1.340319113s","remote":"127.0.0.1:42328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"info","ts":"2026-08-21T03:10:06.242765Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-21T03:10:06.248034Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-21T03:10:06.248361Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-21T03:11:42.857331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17642} {"level":"info","ts":"2026-08-21T03:11:42.966452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17642,"took":"103.793228ms","hash":1681230014,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":34394112,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-21T03:11:42.966553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1681230014,"revision":17642,"compact-revision":15366} {"level":"info","ts":"2026-08-21T03:12:35.092504Z","caller":"traceutil/trace.go:171","msg":"trace[363553524] linearizableReadLoop","detail":"{readStateIndex:21075; appliedIndex:21074; }","duration":"138.898276ms","start":"2026-08-21T03:12:34.953579Z","end":"2026-08-21T03:12:35.092477Z","steps":["trace[363553524] 'read index received' (duration: 138.748892ms)","trace[363553524] 'applied index is now lower than readState.Index' (duration: 147.944µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:12:35.092948Z","caller":"traceutil/trace.go:171","msg":"trace[94048207] transaction","detail":"{read_only:false; response_revision:20379; number_of_response:1; }","duration":"227.923743ms","start":"2026-08-21T03:12:34.865011Z","end":"2026-08-21T03:12:35.092935Z","steps":["trace[94048207] 'process raft request' (duration: 227.269659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:12:35.093181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.59046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-21T03:12:35.0933Z","caller":"traceutil/trace.go:171","msg":"trace[252345575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20379; }","duration":"139.788713ms","start":"2026-08-21T03:12:34.95349Z","end":"2026-08-21T03:12:35.093279Z","steps":["trace[252345575] 'agreement among raft nodes before linearized reading' (duration: 139.432316ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:12:35.248113Z","caller":"traceutil/trace.go:171","msg":"trace[667765635] transaction","detail":"{read_only:false; response_revision:20380; number_of_response:1; }","duration":"175.131704ms","start":"2026-08-21T03:12:35.072953Z","end":"2026-08-21T03:12:35.248085Z","steps":["trace[667765635] 'process raft request' (duration: 90.447386ms)","trace[667765635] 'compare' (duration: 84.515995ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:12:35.248486Z","caller":"traceutil/trace.go:171","msg":"trace[190060897] transaction","detail":"{read_only:false; response_revision:20381; number_of_response:1; }","duration":"162.944116ms","start":"2026-08-21T03:12:35.085506Z","end":"2026-08-21T03:12:35.24845Z","steps":["trace[190060897] 'process raft request' (duration: 162.533207ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:12:35.248573Z","caller":"traceutil/trace.go:171","msg":"trace[1508520894] linearizableReadLoop","detail":"{readStateIndex:21077; appliedIndex:21075; }","duration":"155.963593ms","start":"2026-08-21T03:12:35.09259Z","end":"2026-08-21T03:12:35.248554Z","steps":["trace[1508520894] 'read index received' (duration: 70.831195ms)","trace[1508520894] 'applied index is now lower than readState.Index' (duration: 85.131148ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:12:35.24857Z","caller":"traceutil/trace.go:171","msg":"trace[959114116] transaction","detail":"{read_only:false; response_revision:20382; number_of_response:1; }","duration":"149.549462ms","start":"2026-08-21T03:12:35.098976Z","end":"2026-08-21T03:12:35.248526Z","steps":["trace[959114116] 'process raft request' (duration: 149.353918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:12:35.248791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.509885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-21T03:12:35.248839Z","caller":"traceutil/trace.go:171","msg":"trace[1196042235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20382; }","duration":"279.588597ms","start":"2026-08-21T03:12:34.969239Z","end":"2026-08-21T03:12:35.248827Z","steps":["trace[1196042235] 'agreement among raft nodes before linearized reading' (duration: 279.410434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:12:35.248938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.38814ms","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-08-21T03:12:35.249066Z","caller":"traceutil/trace.go:171","msg":"trace[478283751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20382; }","duration":"294.568114ms","start":"2026-08-21T03:12:34.95447Z","end":"2026-08-21T03:12:35.249038Z","steps":["trace[478283751] 'agreement among raft nodes before linearized reading' (duration: 294.239137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:12:35.249084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.295462ms","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-08-21T03:12:35.249163Z","caller":"traceutil/trace.go:171","msg":"trace[1053334373] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20382; }","duration":"280.405525ms","start":"2026-08-21T03:12:34.968737Z","end":"2026-08-21T03:12:35.249142Z","steps":["trace[1053334373] 'agreement among raft nodes before linearized reading' (duration: 280.16306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:12:35.249051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.17855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-21T03:12:35.249271Z","caller":"traceutil/trace.go:171","msg":"trace[1265609274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20382; }","duration":"280.475127ms","start":"2026-08-21T03:12:34.968782Z","end":"2026-08-21T03:12:35.249257Z","steps":["trace[1265609274] 'agreement among raft nodes before linearized reading' (duration: 280.011717ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:16:23.177853Z","caller":"traceutil/trace.go:171","msg":"trace[1494362449] transaction","detail":"{read_only:false; response_revision:22790; number_of_response:1; }","duration":"233.672626ms","start":"2026-08-21T03:16:22.944141Z","end":"2026-08-21T03:16:23.177813Z","steps":["trace[1494362449] 'process raft request' (duration: 233.431381ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:16:23.258167Z","caller":"traceutil/trace.go:171","msg":"trace[1264497872] transaction","detail":"{read_only:false; response_revision:22791; number_of_response:1; }","duration":"181.566494ms","start":"2026-08-21T03:16:23.076556Z","end":"2026-08-21T03:16:23.258122Z","steps":["trace[1264497872] 'process raft request' (duration: 113.636098ms)","trace[1264497872] 'compare' (duration: 67.764091ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:16:42.866096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20120} {"level":"info","ts":"2026-08-21T03:16:42.981826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20120,"took":"110.903181ms","hash":2539800617,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":42213376,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-21T03:16:42.981916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2539800617,"revision":20120,"compact-revision":17642} {"level":"info","ts":"2026-08-21T03:21:42.875383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22972} {"level":"info","ts":"2026-08-21T03:21:43.009654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22972,"took":"129.452083ms","hash":1819099482,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":32858112,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-21T03:21:43.009733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1819099482,"revision":22972,"compact-revision":20120} {"level":"info","ts":"2026-08-21T03:26:42.882352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25482} {"level":"info","ts":"2026-08-21T03:26:43.012581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25482,"took":"125.483551ms","hash":2085561079,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":29298688,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-21T03:26:43.012658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2085561079,"revision":25482,"compact-revision":22972}