{"level":"warn","ts":"2026-10-03T09:14:42.631472Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T09:14:42.631623Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.179:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.179:2380","--initial-cluster=instance=https://162.253.55.179:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.179:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.179:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-03T09:14:42.631735Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T09:14:42.631758Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.179:2380"]} {"level":"info","ts":"2026-10-03T09:14:42.631788Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-03T09:14:42.632992Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.179:2379"]} {"level":"info","ts":"2026-10-03T09:14:42.633125Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.179:2380"],"listen-peer-urls":["https://162.253.55.179:2380"],"advertise-client-urls":["https://162.253.55.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.179:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-03T09:14:42.636051Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.536002ms"} {"level":"info","ts":"2026-10-03T09:14:42.642706Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"68b29b9453c9d66d","cluster-id":"f39bc50358d1a417"} {"level":"info","ts":"2026-10-03T09:14:42.642807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T09:14:42.642839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became follower at term 0"} {"level":"info","ts":"2026-10-03T09:14:42.642867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 68b29b9453c9d66d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T09:14:42.64288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became follower at term 1"} {"level":"info","ts":"2026-10-03T09:14:42.642965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d switched to configuration voters=(7544263387162203757)"} {"level":"warn","ts":"2026-10-03T09:14:42.648306Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T09:14:42.650404Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T09:14:42.652066Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-03T09:14:42.657122Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"68b29b9453c9d66d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T09:14:42.657535Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"68b29b9453c9d66d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T09:14:42.657734Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T09:14:42.657784Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T09:14:42.657798Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-03T09:14:42.659556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d switched to configuration voters=(7544263387162203757)"} {"level":"info","ts":"2026-10-03T09:14:42.659784Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f39bc50358d1a417","local-member-id":"68b29b9453c9d66d","added-peer-id":"68b29b9453c9d66d","added-peer-peer-urls":["https://162.253.55.179:2380"]} {"level":"info","ts":"2026-10-03T09:14:42.6681Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-03T09:14:42.668264Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.179:2380"} {"level":"info","ts":"2026-10-03T09:14:42.6683Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.179:2380"} {"level":"info","ts":"2026-10-03T09:14:42.668404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"68b29b9453c9d66d","initial-advertise-peer-urls":["https://162.253.55.179:2380"],"listen-peer-urls":["https://162.253.55.179:2380"],"advertise-client-urls":["https://162.253.55.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T09:14:42.668438Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T09:14:43.043569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T09:14:43.043637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T09:14:43.043673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d received MsgPreVoteResp from 68b29b9453c9d66d at term 1"} {"level":"info","ts":"2026-10-03T09:14:43.043688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became candidate at term 2"} {"level":"info","ts":"2026-10-03T09:14:43.043698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d received MsgVoteResp from 68b29b9453c9d66d at term 2"} {"level":"info","ts":"2026-10-03T09:14:43.04371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became leader at term 2"} {"level":"info","ts":"2026-10-03T09:14:43.043722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 68b29b9453c9d66d elected leader 68b29b9453c9d66d at term 2"} {"level":"info","ts":"2026-10-03T09:14:43.045741Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"68b29b9453c9d66d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.179:2379]}","request-path":"/0/members/68b29b9453c9d66d/attributes","cluster-id":"f39bc50358d1a417","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T09:14:43.045777Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:14:43.046038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T09:14:43.046148Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T09:14:43.046208Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T09:14:43.046414Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T09:14:43.049222Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T09:14:43.049768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.179:2379"} {"level":"info","ts":"2026-10-03T09:14:43.052669Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f39bc50358d1a417","local-member-id":"68b29b9453c9d66d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:14:43.052851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:14:43.052904Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:15:26.739551Z","caller":"traceutil/trace.go:171","msg":"trace[1180890347] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"342.284849ms","start":"2026-10-03T09:15:26.39724Z","end":"2026-10-03T09:15:26.739524Z","steps":["trace[1180890347] 'process raft request' (duration: 340.028295ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:26.739551Z","caller":"traceutil/trace.go:171","msg":"trace[1892544793] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"205.378127ms","start":"2026-10-03T09:15:26.53409Z","end":"2026-10-03T09:15:26.739469Z","steps":["trace[1892544793] 'read index received' (duration: 198.632496ms)","trace[1892544793] 'applied index is now lower than readState.Index' (duration: 6.744761ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:15:26.739791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.666024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.179\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-03T09:15:26.740071Z","caller":"traceutil/trace.go:171","msg":"trace[292017890] range","detail":"{range_begin:/registry/masterleases/162.253.55.179; range_end:; response_count:1; response_revision:637; }","duration":"205.992123ms","start":"2026-10-03T09:15:26.534061Z","end":"2026-10-03T09:15:26.740053Z","steps":["trace[292017890] 'agreement among raft nodes before linearized reading' (duration: 205.584612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:15:26.740484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:15:26.397188Z","time spent":"342.462645ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036273,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T09:15:27.08675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.239047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:15:27.086926Z","caller":"traceutil/trace.go:171","msg":"trace[1635797890] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"332.263936ms","start":"2026-10-03T09:15:26.754608Z","end":"2026-10-03T09:15:27.086872Z","steps":["trace[1635797890] 'process raft request' (duration: 11.571546ms)","trace[1635797890] 'compare' (duration: 319.868417ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:15:27.087068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:15:26.754584Z","time spent":"332.38894ms","remote":"127.0.0.1:44410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:15:27.087629Z","caller":"traceutil/trace.go:171","msg":"trace[2109928669] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"272.934602ms","start":"2026-10-03T09:15:26.814667Z","end":"2026-10-03T09:15:27.087602Z","steps":["trace[2109928669] 'process raft request' (duration: 272.780908ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:41.737874Z","caller":"traceutil/trace.go:171","msg":"trace[986022118] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"127.154467ms","start":"2026-10-03T09:15:41.610695Z","end":"2026-10-03T09:15:41.73785Z","steps":["trace[986022118] 'process raft request' (duration: 127.025734ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:58.949299Z","caller":"traceutil/trace.go:171","msg":"trace[779826279] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"173.028043ms","start":"2026-10-03T09:16:58.776218Z","end":"2026-10-03T09:16:58.949246Z","steps":["trace[779826279] 'read index received' (duration: 172.948361ms)","trace[779826279] 'applied index is now lower than readState.Index' (duration: 78.722µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:16:58.949511Z","caller":"traceutil/trace.go:171","msg":"trace[644943268] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"254.726033ms","start":"2026-10-03T09:16:58.694649Z","end":"2026-10-03T09:16:58.949375Z","steps":["trace[644943268] 'process raft request' (duration: 254.450876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:58.949537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.2967ms","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-10-03T09:16:58.949622Z","caller":"traceutil/trace.go:171","msg":"trace[1130763208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1868; }","duration":"173.421254ms","start":"2026-10-03T09:16:58.776189Z","end":"2026-10-03T09:16:58.949611Z","steps":["trace[1130763208] 'agreement among raft nodes before linearized reading' (duration: 173.206618ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:58.962123Z","caller":"traceutil/trace.go:171","msg":"trace[1386169669] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"147.494214ms","start":"2026-10-03T09:16:58.81461Z","end":"2026-10-03T09:16:58.962104Z","steps":["trace[1386169669] 'process raft request' (duration: 147.377171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:58.962233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.370564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-10-03T09:16:58.962322Z","caller":"traceutil/trace.go:171","msg":"trace[945915818] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1869; }","duration":"156.510758ms","start":"2026-10-03T09:16:58.805797Z","end":"2026-10-03T09:16:58.962308Z","steps":["trace[945915818] 'agreement among raft nodes before linearized reading' (duration: 156.283911ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:59.069726Z","caller":"traceutil/trace.go:171","msg":"trace[1206519075] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"115.099812ms","start":"2026-10-03T09:16:58.95459Z","end":"2026-10-03T09:16:59.06969Z","steps":["trace[1206519075] 'process raft request' (duration: 114.848935ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:59.377879Z","caller":"traceutil/trace.go:171","msg":"trace[2083749393] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"402.833324ms","start":"2026-10-03T09:16:58.974886Z","end":"2026-10-03T09:16:59.377719Z","steps":["trace[2083749393] 'process raft request' (duration: 397.607357ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:59.379594Z","caller":"traceutil/trace.go:171","msg":"trace[1408364604] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1942; }","duration":"337.561466ms","start":"2026-10-03T09:16:59.041943Z","end":"2026-10-03T09:16:59.379504Z","steps":["trace[1408364604] 'read index received' (duration: 27.485654ms)","trace[1408364604] 'applied index is now lower than readState.Index' (duration: 310.073222ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:16:59.381126Z","caller":"traceutil/trace.go:171","msg":"trace[981763947] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"405.789767ms","start":"2026-10-03T09:16:58.975299Z","end":"2026-10-03T09:16:59.381089Z","steps":["trace[981763947] 'process raft request' (duration: 402.193215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:59.381252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.289925ms","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-10-03T09:16:59.381351Z","caller":"traceutil/trace.go:171","msg":"trace[1064876027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1872; }","duration":"339.405147ms","start":"2026-10-03T09:16:59.041913Z","end":"2026-10-03T09:16:59.381318Z","steps":["trace[1064876027] 'agreement among raft nodes before linearized reading' (duration: 339.211642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:59.381306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:16:58.975258Z","time spent":"405.92245ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T09:16:59.381399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:16:59.041897Z","time spent":"339.49123ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-03T09:16:59.38138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.920508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:16:59.381764Z","caller":"traceutil/trace.go:171","msg":"trace[775443817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1872; }","duration":"147.297089ms","start":"2026-10-03T09:16:59.234394Z","end":"2026-10-03T09:16:59.381691Z","steps":["trace[775443817] 'agreement among raft nodes before linearized reading' (duration: 146.861567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:59.383032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:16:58.974863Z","time spent":"403.178894ms","remote":"127.0.0.1:44548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:17:12.482227Z","caller":"traceutil/trace.go:171","msg":"trace[1754586414] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"227.201738ms","start":"2026-10-03T09:17:12.255Z","end":"2026-10-03T09:17:12.482202Z","steps":["trace[1754586414] 'process raft request' (duration: 227.024723ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:17:15.25897Z","caller":"traceutil/trace.go:171","msg":"trace[1890322551] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2080; }","duration":"168.618448ms","start":"2026-10-03T09:17:15.090331Z","end":"2026-10-03T09:17:15.25895Z","steps":["trace[1890322551] 'read index received' (duration: 168.399872ms)","trace[1890322551] 'applied index is now lower than readState.Index' (duration: 217.696µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:17:15.259113Z","caller":"traceutil/trace.go:171","msg":"trace[1935009524] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"235.270776ms","start":"2026-10-03T09:17:15.023814Z","end":"2026-10-03T09:17:15.259085Z","steps":["trace[1935009524] 'process raft request' (duration: 234.958877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:17:15.259188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.838194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-03T09:17:15.259225Z","caller":"traceutil/trace.go:171","msg":"trace[651147375] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2005; }","duration":"168.910257ms","start":"2026-10-03T09:17:15.090302Z","end":"2026-10-03T09:17:15.259212Z","steps":["trace[651147375] 'agreement among raft nodes before linearized reading' (duration: 168.766973ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:19:02.623265Z","caller":"traceutil/trace.go:171","msg":"trace[2140861688] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"138.767222ms","start":"2026-10-03T09:19:02.484468Z","end":"2026-10-03T09:19:02.623235Z","steps":["trace[2140861688] 'process raft request' (duration: 138.570517ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:19:03.079756Z","caller":"traceutil/trace.go:171","msg":"trace[615235460] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"100.956258ms","start":"2026-10-03T09:19:02.978774Z","end":"2026-10-03T09:19:03.07973Z","steps":["trace[615235460] 'process raft request' (duration: 100.758943ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:38.232343Z","caller":"traceutil/trace.go:171","msg":"trace[1206507329] linearizableReadLoop","detail":"{readStateIndex:5252; appliedIndex:5251; }","duration":"102.584344ms","start":"2026-10-03T09:23:38.12974Z","end":"2026-10-03T09:23:38.232324Z","steps":["trace[1206507329] 'read index received' (duration: 102.43596ms)","trace[1206507329] 'applied index is now lower than readState.Index' (duration: 147.814µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:23:38.232533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.748659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-10-03T09:23:38.232563Z","caller":"traceutil/trace.go:171","msg":"trace[176616698] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5065; }","duration":"102.852992ms","start":"2026-10-03T09:23:38.129701Z","end":"2026-10-03T09:23:38.232554Z","steps":["trace[176616698] 'agreement among raft nodes before linearized reading' (duration: 102.699997ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:38.233121Z","caller":"traceutil/trace.go:171","msg":"trace[86024716] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"116.395303ms","start":"2026-10-03T09:23:38.116691Z","end":"2026-10-03T09:23:38.233086Z","steps":["trace[86024716] 'process raft request' (duration: 115.533438ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:41.68734Z","caller":"traceutil/trace.go:171","msg":"trace[1012042447] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5384; }","duration":"107.339918ms","start":"2026-10-03T09:23:41.579977Z","end":"2026-10-03T09:23:41.687317Z","steps":["trace[1012042447] 'read index received' (duration: 107.227494ms)","trace[1012042447] 'applied index is now lower than readState.Index' (duration: 111.634µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:23:41.687395Z","caller":"traceutil/trace.go:171","msg":"trace[456627807] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"140.246513ms","start":"2026-10-03T09:23:41.547114Z","end":"2026-10-03T09:23:41.68736Z","steps":["trace[456627807] 'process raft request' (duration: 140.094458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:41.68755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.563354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-03T09:23:41.687574Z","caller":"traceutil/trace.go:171","msg":"trace[175137372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5195; }","duration":"107.643217ms","start":"2026-10-03T09:23:41.579924Z","end":"2026-10-03T09:23:41.687567Z","steps":["trace[175137372] 'agreement among raft nodes before linearized reading' (duration: 107.473042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:41.868215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.088527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:23:41.868384Z","caller":"traceutil/trace.go:171","msg":"trace[138715842] linearizableReadLoop","detail":"{readStateIndex:5387; appliedIndex:5385; }","duration":"169.797784ms","start":"2026-10-03T09:23:41.698568Z","end":"2026-10-03T09:23:41.868366Z","steps":["trace[138715842] 'read index received' (duration: 55.456549ms)","trace[138715842] 'applied index is now lower than readState.Index' (duration: 114.340245ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:23:41.868481Z","caller":"traceutil/trace.go:171","msg":"trace[1962465232] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"173.758585ms","start":"2026-10-03T09:23:41.694678Z","end":"2026-10-03T09:23:41.868437Z","steps":["trace[1962465232] 'process raft request' (duration: 59.369879ms)","trace[1962465232] 'compare' (duration: 113.960694ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:23:41.868529Z","caller":"traceutil/trace.go:171","msg":"trace[1839706666] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"171.503792ms","start":"2026-10-03T09:23:41.696952Z","end":"2026-10-03T09:23:41.868456Z","steps":["trace[1839706666] 'process raft request' (duration: 171.363858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:41.868559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.995279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-03T09:23:41.868593Z","caller":"traceutil/trace.go:171","msg":"trace[884967855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5197; }","duration":"170.070581ms","start":"2026-10-03T09:23:41.698513Z","end":"2026-10-03T09:23:41.868584Z","steps":["trace[884967855] 'agreement among raft nodes before linearized reading' (duration: 169.926287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:41.896089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.924673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-03T09:23:41.896194Z","caller":"traceutil/trace.go:171","msg":"trace[1226237094] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5197; }","duration":"194.060386ms","start":"2026-10-03T09:23:41.702108Z","end":"2026-10-03T09:23:41.896168Z","steps":["trace[1226237094] 'agreement among raft nodes before linearized reading' (duration: 193.815779ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:56.701453Z","caller":"traceutil/trace.go:171","msg":"trace[170981513] linearizableReadLoop","detail":"{readStateIndex:5673; appliedIndex:5672; }","duration":"106.324679ms","start":"2026-10-03T09:23:56.595102Z","end":"2026-10-03T09:23:56.701427Z","steps":["trace[170981513] 'read index received' (duration: 41.599719ms)","trace[170981513] 'applied index is now lower than readState.Index' (duration: 64.72378ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:23:56.701528Z","caller":"traceutil/trace.go:171","msg":"trace[695235014] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"124.49033ms","start":"2026-10-03T09:23:56.576997Z","end":"2026-10-03T09:23:56.701488Z","steps":["trace[695235014] 'process raft request' (duration: 59.827052ms)","trace[695235014] 'compare' (duration: 64.480813ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:23:56.701685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.554785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-03T09:23:56.701777Z","caller":"traceutil/trace.go:171","msg":"trace[315055210] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5473; }","duration":"106.688329ms","start":"2026-10-03T09:23:56.59507Z","end":"2026-10-03T09:23:56.701758Z","steps":["trace[315055210] 'agreement among raft nodes before linearized reading' (duration: 106.485654ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:57.261955Z","caller":"traceutil/trace.go:171","msg":"trace[1223448445] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"211.876187ms","start":"2026-10-03T09:23:57.050063Z","end":"2026-10-03T09:23:57.261939Z","steps":["trace[1223448445] 'process raft request' (duration: 211.742083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:57.55916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.955421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:23:57.55942Z","caller":"traceutil/trace.go:171","msg":"trace[643913445] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"254.881966ms","start":"2026-10-03T09:23:57.304517Z","end":"2026-10-03T09:23:57.559399Z","steps":["trace[643913445] 'process raft request' (duration: 254.830045ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:57.559639Z","caller":"traceutil/trace.go:171","msg":"trace[1954055100] linearizableReadLoop","detail":"{readStateIndex:5677; appliedIndex:5675; }","duration":"327.148108ms","start":"2026-10-03T09:23:57.23248Z","end":"2026-10-03T09:23:57.559628Z","steps":["trace[1954055100] 'read index received' (duration: 29.308204ms)","trace[1954055100] 'applied index is now lower than readState.Index' (duration: 297.838904ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:23:57.559576Z","caller":"traceutil/trace.go:171","msg":"trace[1315417896] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"301.798105ms","start":"2026-10-03T09:23:57.257743Z","end":"2026-10-03T09:23:57.559541Z","steps":["trace[1315417896] 'process raft request' (duration: 301.505507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:57.559763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:23:57.257727Z","time spent":"301.97001ms","remote":"127.0.0.1:44438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T09:23:57.559897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.692144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-03T09:23:57.559929Z","caller":"traceutil/trace.go:171","msg":"trace[1958029783] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5479; }","duration":"279.772436ms","start":"2026-10-03T09:23:57.280147Z","end":"2026-10-03T09:23:57.559919Z","steps":["trace[1958029783] 'agreement among raft nodes before linearized reading' (duration: 279.645842ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:23:57.559961Z","caller":"traceutil/trace.go:171","msg":"trace[735797096] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"501.249683ms","start":"2026-10-03T09:23:57.058673Z","end":"2026-10-03T09:23:57.559923Z","steps":["trace[735797096] 'process raft request' (duration: 374.453778ms)","trace[735797096] 'compare' (duration: 125.651643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:23:57.560005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.831959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-03T09:23:57.56005Z","caller":"traceutil/trace.go:171","msg":"trace[1415373626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5479; }","duration":"225.915572ms","start":"2026-10-03T09:23:57.334122Z","end":"2026-10-03T09:23:57.560037Z","steps":["trace[1415373626] 'agreement among raft nodes before linearized reading' (duration: 225.786809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:57.560088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.831611ms","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-10-03T09:23:57.560118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:23:57.058666Z","time spent":"501.372326ms","remote":"127.0.0.1:44548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:23:57.560165Z","caller":"traceutil/trace.go:171","msg":"trace[262862183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5479; }","duration":"240.958914ms","start":"2026-10-03T09:23:57.319186Z","end":"2026-10-03T09:23:57.560145Z","steps":["trace[262862183] 'agreement among raft nodes before linearized reading' (duration: 240.734018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:57.560189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.660172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:23:57.56026Z","caller":"traceutil/trace.go:171","msg":"trace[556716235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5479; }","duration":"327.814277ms","start":"2026-10-03T09:23:57.232424Z","end":"2026-10-03T09:23:57.560239Z","steps":["trace[556716235] 'agreement among raft nodes before linearized reading' (duration: 327.55607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:23:57.560306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:23:57.232404Z","time spent":"327.890948ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T09:24:04.895882Z","caller":"traceutil/trace.go:171","msg":"trace[1890379020] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"147.61068ms","start":"2026-10-03T09:24:04.748219Z","end":"2026-10-03T09:24:04.895829Z","steps":["trace[1890379020] 'process raft request' (duration: 78.279961ms)","trace[1890379020] 'compare' (duration: 69.046051ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:24:05.164804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.728231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T09:24:05.164899Z","caller":"traceutil/trace.go:171","msg":"trace[346208445] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5663; }","duration":"105.894296ms","start":"2026-10-03T09:24:05.058982Z","end":"2026-10-03T09:24:05.164876Z","steps":["trace[346208445] 'count revisions from in-memory index tree' (duration: 105.623889ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:24:43.094361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3011} {"level":"info","ts":"2026-10-03T09:24:43.184731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3011,"took":"88.871729ms","hash":2883898588,"current-db-size-bytes":56295424,"current-db-size":"56 MB","current-db-size-in-use-bytes":30654464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T09:24:43.1848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2883898588,"revision":3011,"compact-revision":-1} {"level":"info","ts":"2026-10-03T09:24:58.287566Z","caller":"traceutil/trace.go:171","msg":"trace[340606081] transaction","detail":"{read_only:false; response_revision:6152; number_of_response:1; }","duration":"106.186235ms","start":"2026-10-03T09:24:58.181338Z","end":"2026-10-03T09:24:58.287524Z","steps":["trace[340606081] 'process raft request' (duration: 76.251044ms)","trace[340606081] 'compare' (duration: 29.723085ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:29:43.102253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5963} {"level":"info","ts":"2026-10-03T09:29:43.19401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5963,"took":"89.976512ms","hash":2451381401,"current-db-size-bytes":56295424,"current-db-size":"56 MB","current-db-size-in-use-bytes":26419200,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-03T09:29:43.194083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2451381401,"revision":5963,"compact-revision":3011} {"level":"warn","ts":"2026-10-03T09:30:15.257534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.702077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:30:15.257643Z","caller":"traceutil/trace.go:171","msg":"trace[1214210852] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8781; }","duration":"306.334692ms","start":"2026-10-03T09:30:14.951292Z","end":"2026-10-03T09:30:15.257627Z","steps":["trace[1214210852] 'read index received' (duration: 180.48905ms)","trace[1214210852] 'applied index is now lower than readState.Index' (duration: 125.844522ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:30:15.257731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.439524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:30:15.257758Z","caller":"traceutil/trace.go:171","msg":"trace[641482684] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8485; }","duration":"306.513976ms","start":"2026-10-03T09:30:14.951233Z","end":"2026-10-03T09:30:15.257747Z","steps":["trace[641482684] 'agreement among raft nodes before linearized reading' (duration: 306.434594ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:30:15.257748Z","caller":"traceutil/trace.go:171","msg":"trace[126131757] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"387.879196ms","start":"2026-10-03T09:30:14.869802Z","end":"2026-10-03T09:30:15.257681Z","steps":["trace[126131757] 'process raft request' (duration: 261.946192ms)","trace[126131757] 'compare' (duration: 125.579084ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:30:15.257791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:30:14.95121Z","time spent":"306.572438ms","remote":"127.0.0.1:44440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-10-03T09:30:15.257938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:30:14.869761Z","time spent":"388.073431ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:30:15.264914Z","caller":"traceutil/trace.go:171","msg":"trace[488953959] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"276.048359ms","start":"2026-10-03T09:30:14.988836Z","end":"2026-10-03T09:30:15.264884Z","steps":["trace[488953959] 'process raft request' (duration: 275.900705ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:30:27.727771Z","caller":"traceutil/trace.go:171","msg":"trace[477614915] linearizableReadLoop","detail":"{readStateIndex:8851; appliedIndex:8850; }","duration":"141.138172ms","start":"2026-10-03T09:30:27.58659Z","end":"2026-10-03T09:30:27.727728Z","steps":["trace[477614915] 'read index received' (duration: 128.459725ms)","trace[477614915] 'applied index is now lower than readState.Index' (duration: 12.676497ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:30:27.72786Z","caller":"traceutil/trace.go:171","msg":"trace[33532785] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"172.578326ms","start":"2026-10-03T09:30:27.555192Z","end":"2026-10-03T09:30:27.72777Z","steps":["trace[33532785] 'process raft request' (duration: 159.93555ms)","trace[33532785] 'compare' (duration: 12.308687ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:30:27.728061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.460221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-03T09:30:27.728119Z","caller":"traceutil/trace.go:171","msg":"trace[668348667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8550; }","duration":"141.593945ms","start":"2026-10-03T09:30:27.586511Z","end":"2026-10-03T09:30:27.728105Z","steps":["trace[668348667] 'agreement among raft nodes before linearized reading' (duration: 141.356838ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:30:57.875652Z","caller":"traceutil/trace.go:171","msg":"trace[321196653] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"429.96045ms","start":"2026-10-03T09:30:57.445643Z","end":"2026-10-03T09:30:57.875604Z","steps":["trace[321196653] 'process raft request' (duration: 429.735763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:30:57.875944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:30:57.445628Z","time spent":"430.168326ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:31:00.397691Z","caller":"traceutil/trace.go:171","msg":"trace[1804658281] transaction","detail":"{read_only:false; response_revision:8782; number_of_response:1; }","duration":"112.639519ms","start":"2026-10-03T09:31:00.284471Z","end":"2026-10-03T09:31:00.397111Z","steps":["trace[1804658281] 'process raft request' (duration: 98.562073ms)","trace[1804658281] 'compare' (duration: 13.806148ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:31:08.12487Z","caller":"traceutil/trace.go:171","msg":"trace[1589524532] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"104.641114ms","start":"2026-10-03T09:31:08.020196Z","end":"2026-10-03T09:31:08.124837Z","steps":["trace[1589524532] 'process raft request' (duration: 104.464749ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:31:35.996099Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024bc.wal"} {"level":"info","ts":"2026-10-03T09:32:30.780879Z","caller":"traceutil/trace.go:171","msg":"trace[2038893124] linearizableReadLoop","detail":"{readStateIndex:9979; appliedIndex:9978; }","duration":"238.684984ms","start":"2026-10-03T09:32:30.542144Z","end":"2026-10-03T09:32:30.780829Z","steps":["trace[2038893124] 'read index received' (duration: 228.130847ms)","trace[2038893124] 'applied index is now lower than readState.Index' (duration: 10.551667ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:32:30.781114Z","caller":"traceutil/trace.go:171","msg":"trace[103103393] transaction","detail":"{read_only:false; response_revision:9645; number_of_response:1; }","duration":"249.046066ms","start":"2026-10-03T09:32:30.532047Z","end":"2026-10-03T09:32:30.781094Z","steps":["trace[103103393] 'process raft request' (duration: 238.286793ms)","trace[103103393] 'compare' (duration: 10.106335ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:32:30.781292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.131447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-03T09:32:30.781384Z","caller":"traceutil/trace.go:171","msg":"trace[393347290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9645; }","duration":"239.25707ms","start":"2026-10-03T09:32:30.542107Z","end":"2026-10-03T09:32:30.781364Z","steps":["trace[393347290] 'agreement among raft nodes before linearized reading' (duration: 239.034024ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:32:33.591914Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68b29b9453c9d66d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T09:32:33.59918Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-03T09:32:33.599288Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-03T09:34:43.112093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8140} {"level":"info","ts":"2026-10-03T09:34:43.18084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8140,"took":"66.554995ms","hash":356321625,"current-db-size-bytes":56295424,"current-db-size":"56 MB","current-db-size-in-use-bytes":29200384,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-03T09:34:43.180903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":356321625,"revision":8140,"compact-revision":5963} {"level":"info","ts":"2026-10-03T09:39:43.119626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10579} {"level":"info","ts":"2026-10-03T09:39:43.209783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10579,"took":"87.522264ms","hash":1403321938,"current-db-size-bytes":56295424,"current-db-size":"56 MB","current-db-size-in-use-bytes":27004928,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-03T09:39:43.209852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1403321938,"revision":10579,"compact-revision":8140} {"level":"info","ts":"2026-10-03T09:42:06.416217Z","caller":"traceutil/trace.go:171","msg":"trace[1915709475] transaction","detail":"{read_only:false; response_revision:13892; number_of_response:1; }","duration":"149.58506ms","start":"2026-10-03T09:42:06.266605Z","end":"2026-10-03T09:42:06.41619Z","steps":["trace[1915709475] 'process raft request' (duration: 149.411155ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:42:44.891446Z","caller":"traceutil/trace.go:171","msg":"trace[730633984] transaction","detail":"{read_only:false; response_revision:14110; number_of_response:1; }","duration":"268.339907ms","start":"2026-10-03T09:42:44.623082Z","end":"2026-10-03T09:42:44.891422Z","steps":["trace[730633984] 'process raft request' (duration: 268.192413ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:42:45.032845Z","caller":"traceutil/trace.go:171","msg":"trace[1132478694] transaction","detail":"{read_only:false; response_revision:14111; number_of_response:1; }","duration":"265.270961ms","start":"2026-10-03T09:42:44.767549Z","end":"2026-10-03T09:42:45.03282Z","steps":["trace[1132478694] 'process raft request' (duration: 237.543931ms)","trace[1132478694] 'compare' (duration: 27.37833ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:42:45.033115Z","caller":"traceutil/trace.go:171","msg":"trace[877505311] linearizableReadLoop","detail":"{readStateIndex:14593; appliedIndex:14590; }","duration":"222.93373ms","start":"2026-10-03T09:42:44.810131Z","end":"2026-10-03T09:42:45.033065Z","steps":["trace[877505311] 'read index received' (duration: 81.229194ms)","trace[877505311] 'applied index is now lower than readState.Index' (duration: 141.702876ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:42:45.033307Z","caller":"traceutil/trace.go:171","msg":"trace[657575503] transaction","detail":"{read_only:false; response_revision:14112; number_of_response:1; }","duration":"264.424927ms","start":"2026-10-03T09:42:44.768842Z","end":"2026-10-03T09:42:45.033267Z","steps":["trace[657575503] 'process raft request' (duration: 263.927473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:42:45.033445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.30242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-03T09:42:45.033532Z","caller":"traceutil/trace.go:171","msg":"trace[1209896398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14112; }","duration":"223.459194ms","start":"2026-10-03T09:42:44.810053Z","end":"2026-10-03T09:42:45.033512Z","steps":["trace[1209896398] 'agreement among raft nodes before linearized reading' (duration: 223.158246ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:42:55.235078Z","caller":"traceutil/trace.go:171","msg":"trace[1627401431] linearizableReadLoop","detail":"{readStateIndex:14767; appliedIndex:14766; }","duration":"127.220828ms","start":"2026-10-03T09:42:55.107835Z","end":"2026-10-03T09:42:55.235056Z","steps":["trace[1627401431] 'read index received' (duration: 127.099175ms)","trace[1627401431] 'applied index is now lower than readState.Index' (duration: 116.813µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:42:55.235249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.384593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T09:42:55.235345Z","caller":"traceutil/trace.go:171","msg":"trace[2136166123] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:14283; }","duration":"127.551918ms","start":"2026-10-03T09:42:55.107781Z","end":"2026-10-03T09:42:55.235333Z","steps":["trace[2136166123] 'agreement among raft nodes before linearized reading' (duration: 127.382653ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:42:55.235595Z","caller":"traceutil/trace.go:171","msg":"trace[121858207] transaction","detail":"{read_only:false; response_revision:14283; number_of_response:1; }","duration":"280.132559ms","start":"2026-10-03T09:42:54.954971Z","end":"2026-10-03T09:42:55.235104Z","steps":["trace[121858207] 'process raft request' (duration: 279.886252ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:42:55.236894Z","caller":"traceutil/trace.go:171","msg":"trace[1213058284] transaction","detail":"{read_only:false; response_revision:14284; number_of_response:1; }","duration":"117.153335ms","start":"2026-10-03T09:42:55.119721Z","end":"2026-10-03T09:42:55.236875Z","steps":["trace[1213058284] 'process raft request' (duration: 116.877437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:42:55.236947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.331216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-03T09:42:55.237041Z","caller":"traceutil/trace.go:171","msg":"trace[1206051322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14285; }","duration":"103.437949ms","start":"2026-10-03T09:42:55.133579Z","end":"2026-10-03T09:42:55.237017Z","steps":["trace[1206051322] 'agreement among raft nodes before linearized reading' (duration: 103.200402ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:42:55.237069Z","caller":"traceutil/trace.go:171","msg":"trace[1365946984] transaction","detail":"{read_only:false; response_revision:14285; number_of_response:1; }","duration":"113.385559ms","start":"2026-10-03T09:42:55.123548Z","end":"2026-10-03T09:42:55.236934Z","steps":["trace[1365946984] 'process raft request' (duration: 113.188213ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:44:25.683189Z","caller":"traceutil/trace.go:171","msg":"trace[268522146] transaction","detail":"{read_only:false; response_revision:15322; number_of_response:1; }","duration":"103.877651ms","start":"2026-10-03T09:44:25.579286Z","end":"2026-10-03T09:44:25.683164Z","steps":["trace[268522146] 'process raft request' (duration: 103.731867ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:44:25.723033Z","caller":"traceutil/trace.go:171","msg":"trace[1479939164] transaction","detail":"{read_only:false; response_revision:15323; number_of_response:1; }","duration":"133.992537ms","start":"2026-10-03T09:44:25.589009Z","end":"2026-10-03T09:44:25.723002Z","steps":["trace[1479939164] 'process raft request' (duration: 133.784541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:25.958013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.060413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-03T09:44:25.958101Z","caller":"traceutil/trace.go:171","msg":"trace[2069898684] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15323; }","duration":"181.190956ms","start":"2026-10-03T09:44:25.776889Z","end":"2026-10-03T09:44:25.95808Z","steps":["trace[2069898684] 'range keys from in-memory index tree' (duration: 180.782885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:25.958192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.230537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:44:25.958289Z","caller":"traceutil/trace.go:171","msg":"trace[1944060893] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15323; }","duration":"181.401902ms","start":"2026-10-03T09:44:25.77686Z","end":"2026-10-03T09:44:25.958262Z","steps":["trace[1944060893] 'range keys from in-memory index tree' (duration: 181.045241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:25.958351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.527928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-2v527\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-03T09:44:25.958512Z","caller":"traceutil/trace.go:171","msg":"trace[1002797717] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-2v527; range_end:; response_count:1; response_revision:15323; }","duration":"185.685352ms","start":"2026-10-03T09:44:25.772764Z","end":"2026-10-03T09:44:25.958449Z","steps":["trace[1002797717] 'range keys from in-memory index tree' (duration: 185.321283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:25.958515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.226304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-03T09:44:25.95853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.282057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:44:25.958651Z","caller":"traceutil/trace.go:171","msg":"trace[165166745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15323; }","duration":"209.473231ms","start":"2026-10-03T09:44:25.749142Z","end":"2026-10-03T09:44:25.958615Z","steps":["trace[165166745] 'range keys from in-memory index tree' (duration: 208.792821ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:44:25.958778Z","caller":"traceutil/trace.go:171","msg":"trace[377112267] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15323; }","duration":"187.609776ms","start":"2026-10-03T09:44:25.771095Z","end":"2026-10-03T09:44:25.958704Z","steps":["trace[377112267] 'range keys from in-memory index tree' (duration: 186.877266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:25.959008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.484007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-03T09:44:25.959086Z","caller":"traceutil/trace.go:171","msg":"trace[344041948] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15323; }","duration":"206.632211ms","start":"2026-10-03T09:44:25.75243Z","end":"2026-10-03T09:44:25.959063Z","steps":["trace[344041948] 'range keys from in-memory index tree' (duration: 205.430357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:25.959106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.998613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:44:25.959194Z","caller":"traceutil/trace.go:171","msg":"trace[174228362] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15323; }","duration":"180.473456ms","start":"2026-10-03T09:44:25.778704Z","end":"2026-10-03T09:44:25.959178Z","steps":["trace[174228362] 'range keys from in-memory index tree' (duration: 179.788996ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:44:26.091731Z","caller":"traceutil/trace.go:171","msg":"trace[1417927592] linearizableReadLoop","detail":"{readStateIndex:15841; appliedIndex:15839; }","duration":"125.248693ms","start":"2026-10-03T09:44:25.966423Z","end":"2026-10-03T09:44:26.091671Z","steps":["trace[1417927592] 'read index received' (duration: 82.577293ms)","trace[1417927592] 'applied index is now lower than readState.Index' (duration: 42.6697ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:44:26.091778Z","caller":"traceutil/trace.go:171","msg":"trace[88314383] transaction","detail":"{read_only:false; response_revision:15325; number_of_response:1; }","duration":"127.375902ms","start":"2026-10-03T09:44:25.964368Z","end":"2026-10-03T09:44:26.091744Z","steps":["trace[88314383] 'process raft request' (duration: 102.902534ms)","trace[88314383] 'compare' (duration: 24.260812ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:44:26.092607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.170329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769799"} {"level":"info","ts":"2026-10-03T09:44:26.092688Z","caller":"traceutil/trace.go:171","msg":"trace[888902419] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15325; }","duration":"126.262631ms","start":"2026-10-03T09:44:25.966405Z","end":"2026-10-03T09:44:26.092667Z","steps":["trace[888902419] 'agreement among raft nodes before linearized reading' (duration: 125.405527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.051382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.79442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:44:27.051474Z","caller":"traceutil/trace.go:171","msg":"trace[420577261] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15331; }","duration":"177.989686ms","start":"2026-10-03T09:44:26.873462Z","end":"2026-10-03T09:44:27.051452Z","steps":["trace[420577261] 'range keys from in-memory index tree' (duration: 177.592824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.051612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.487571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-03T09:44:27.05168Z","caller":"traceutil/trace.go:171","msg":"trace[1730707927] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15331; }","duration":"172.587784ms","start":"2026-10-03T09:44:26.879075Z","end":"2026-10-03T09:44:27.051663Z","steps":["trace[1730707927] 'range keys from in-memory index tree' (duration: 172.296876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.051852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.089227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:44:27.051907Z","caller":"traceutil/trace.go:171","msg":"trace[1509860862] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15331; }","duration":"179.175769ms","start":"2026-10-03T09:44:26.872715Z","end":"2026-10-03T09:44:27.051891Z","steps":["trace[1509860862] 'range keys from in-memory index tree' (duration: 178.995714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.051923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.286865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-03T09:44:27.051983Z","caller":"traceutil/trace.go:171","msg":"trace[1802612004] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15331; }","duration":"204.392429ms","start":"2026-10-03T09:44:26.847574Z","end":"2026-10-03T09:44:27.051967Z","steps":["trace[1802612004] 'range keys from in-memory index tree' (duration: 203.940306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.052024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.629937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-03T09:44:27.052061Z","caller":"traceutil/trace.go:171","msg":"trace[1578544339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15331; }","duration":"144.693299ms","start":"2026-10-03T09:44:26.907354Z","end":"2026-10-03T09:44:27.052047Z","steps":["trace[1578544339] 'range keys from in-memory index tree' (duration: 144.461543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.052204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.526424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-03T09:44:27.052255Z","caller":"traceutil/trace.go:171","msg":"trace[570018275] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15331; }","duration":"203.577665ms","start":"2026-10-03T09:44:26.848662Z","end":"2026-10-03T09:44:27.05224Z","steps":["trace[570018275] 'range keys from in-memory index tree' (duration: 203.405961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.052278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.610746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9xjw6\" ","response":"range_response_count:1 size:22124"} {"level":"info","ts":"2026-10-03T09:44:27.052322Z","caller":"traceutil/trace.go:171","msg":"trace[1460173410] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9xjw6; range_end:; response_count:1; response_revision:15331; }","duration":"230.689458ms","start":"2026-10-03T09:44:26.821619Z","end":"2026-10-03T09:44:27.052308Z","steps":["trace[1460173410] 'range keys from in-memory index tree' (duration: 230.338888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.052259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.259859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-03T09:44:27.052581Z","caller":"traceutil/trace.go:171","msg":"trace[2133143265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15331; }","duration":"138.587887ms","start":"2026-10-03T09:44:26.91397Z","end":"2026-10-03T09:44:27.052558Z","steps":["trace[2133143265] 'range keys from in-memory index tree' (duration: 138.063593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.052755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.383103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9xjw6\" ","response":"range_response_count:1 size:22124"} {"level":"info","ts":"2026-10-03T09:44:27.052799Z","caller":"traceutil/trace.go:171","msg":"trace[1098087834] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9xjw6; range_end:; response_count:1; response_revision:15331; }","duration":"228.469016ms","start":"2026-10-03T09:44:26.824317Z","end":"2026-10-03T09:44:27.052786Z","steps":["trace[1098087834] 'range keys from in-memory index tree' (duration: 228.234259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.053006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.52195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:44:27.053058Z","caller":"traceutil/trace.go:171","msg":"trace[1845640534] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15331; }","duration":"205.608513ms","start":"2026-10-03T09:44:26.847434Z","end":"2026-10-03T09:44:27.053043Z","steps":["trace[1845640534] 'range keys from in-memory index tree' (duration: 205.437108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:44:27.053193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.848728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:44:27.053224Z","caller":"traceutil/trace.go:171","msg":"trace[1062969363] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15331; }","duration":"179.91191ms","start":"2026-10-03T09:44:26.873303Z","end":"2026-10-03T09:44:27.053214Z","steps":["trace[1062969363] 'range keys from in-memory index tree' (duration: 179.796797ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:44:43.126913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12831} {"level":"info","ts":"2026-10-03T09:44:43.215178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12831,"took":"84.61698ms","hash":1397109142,"current-db-size-bytes":56295424,"current-db-size":"56 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T09:44:43.215339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1397109142,"revision":12831,"compact-revision":10579} {"level":"info","ts":"2026-10-03T09:49:43.134504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15414} {"level":"info","ts":"2026-10-03T09:49:43.243056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15414,"took":"103.478948ms","hash":3624379034,"current-db-size-bytes":56295424,"current-db-size":"56 MB","current-db-size-in-use-bytes":32169984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T09:49:43.24313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3624379034,"revision":15414,"compact-revision":12831} {"level":"info","ts":"2026-10-03T09:50:11.188978Z","caller":"traceutil/trace.go:171","msg":"trace[1343822057] transaction","detail":"{read_only:false; response_revision:17912; number_of_response:1; }","duration":"159.501173ms","start":"2026-10-03T09:50:11.029456Z","end":"2026-10-03T09:50:11.188957Z","steps":["trace[1343822057] 'process raft request' (duration: 159.339708ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:50:11.189949Z","caller":"traceutil/trace.go:171","msg":"trace[1538639580] linearizableReadLoop","detail":"{readStateIndex:18513; appliedIndex:18511; }","duration":"136.572058ms","start":"2026-10-03T09:50:11.053328Z","end":"2026-10-03T09:50:11.1899Z","steps":["trace[1538639580] 'read index received' (duration: 135.486718ms)","trace[1538639580] 'applied index is now lower than readState.Index' (duration: 1.08382ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:50:11.19015Z","caller":"traceutil/trace.go:171","msg":"trace[636039734] transaction","detail":"{read_only:false; response_revision:17913; number_of_response:1; }","duration":"160.0948ms","start":"2026-10-03T09:50:11.030022Z","end":"2026-10-03T09:50:11.190117Z","steps":["trace[636039734] 'process raft request' (duration: 159.708859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:50:11.190285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.923708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-03T09:50:11.190349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.401813ms","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-10-03T09:50:11.190353Z","caller":"traceutil/trace.go:171","msg":"trace[110966931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17913; }","duration":"137.042032ms","start":"2026-10-03T09:50:11.05329Z","end":"2026-10-03T09:50:11.190332Z","steps":["trace[110966931] 'agreement among raft nodes before linearized reading' (duration: 136.824745ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:50:11.190411Z","caller":"traceutil/trace.go:171","msg":"trace[251058755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17913; }","duration":"126.484755ms","start":"2026-10-03T09:50:11.063912Z","end":"2026-10-03T09:50:11.190397Z","steps":["trace[251058755] 'agreement among raft nodes before linearized reading' (duration: 126.325651ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:50:18.166625Z","caller":"traceutil/trace.go:171","msg":"trace[252599157] transaction","detail":"{read_only:false; response_revision:17995; number_of_response:1; }","duration":"157.331292ms","start":"2026-10-03T09:50:18.009277Z","end":"2026-10-03T09:50:18.166608Z","steps":["trace[252599157] 'process raft request' (duration: 157.185207ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:50:18.219602Z","caller":"traceutil/trace.go:171","msg":"trace[651890009] transaction","detail":"{read_only:false; response_revision:17996; number_of_response:1; }","duration":"150.50415ms","start":"2026-10-03T09:50:18.069058Z","end":"2026-10-03T09:50:18.219562Z","steps":["trace[651890009] 'process raft request' (duration: 150.256453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:50:18.513628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.502209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T09:50:18.513728Z","caller":"traceutil/trace.go:171","msg":"trace[1191086861] linearizableReadLoop","detail":"{readStateIndex:18600; appliedIndex:18598; }","duration":"280.39182ms","start":"2026-10-03T09:50:18.233321Z","end":"2026-10-03T09:50:18.513713Z","steps":["trace[1191086861] 'read index received' (duration: 8.343774ms)","trace[1191086861] 'applied index is now lower than readState.Index' (duration: 272.046796ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:50:18.513857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.527255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T09:50:18.513901Z","caller":"traceutil/trace.go:171","msg":"trace[1627553275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17998; }","duration":"280.595646ms","start":"2026-10-03T09:50:18.233294Z","end":"2026-10-03T09:50:18.513889Z","steps":["trace[1627553275] 'agreement among raft nodes before linearized reading' (duration: 280.509563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:50:18.513943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.733528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T09:50:18.513951Z","caller":"traceutil/trace.go:171","msg":"trace[1328552536] transaction","detail":"{read_only:false; response_revision:17998; number_of_response:1; }","duration":"288.521069ms","start":"2026-10-03T09:50:18.225397Z","end":"2026-10-03T09:50:18.513918Z","steps":["trace[1328552536] 'process raft request' (duration: 73.63817ms)","trace[1328552536] 'compare' (duration: 214.308743ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:50:18.513993Z","caller":"traceutil/trace.go:171","msg":"trace[1744020641] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:17998; }","duration":"191.851702ms","start":"2026-10-03T09:50:18.322126Z","end":"2026-10-03T09:50:18.513978Z","steps":["trace[1744020641] 'agreement among raft nodes before linearized reading' (duration: 191.747849ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:53:02.83934Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68b29b9453c9d66d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T09:53:02.846341Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-03T09:53:02.846526Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-03T09:54:43.143191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17633} {"level":"info","ts":"2026-10-03T09:54:43.256985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17633,"took":"108.675471ms","hash":2346699854,"current-db-size-bytes":56295424,"current-db-size":"56 MB","current-db-size-in-use-bytes":35139584,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-03T09:54:43.257061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2346699854,"revision":17633,"compact-revision":15414} {"level":"info","ts":"2026-10-03T09:55:34.472971Z","caller":"traceutil/trace.go:171","msg":"trace[600854754] transaction","detail":"{read_only:false; response_revision:20439; number_of_response:1; }","duration":"104.598297ms","start":"2026-10-03T09:55:34.368341Z","end":"2026-10-03T09:55:34.472939Z","steps":["trace[600854754] 'process raft request' (duration: 104.450893ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:55:50.089472Z","caller":"traceutil/trace.go:171","msg":"trace[1021959526] transaction","detail":"{read_only:false; response_revision:20520; number_of_response:1; }","duration":"113.421444ms","start":"2026-10-03T09:55:49.976006Z","end":"2026-10-03T09:55:50.089427Z","steps":["trace[1021959526] 'process raft request' (duration: 113.191718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:55:50.392774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.479987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T09:55:50.392864Z","caller":"traceutil/trace.go:171","msg":"trace[10812004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20520; }","duration":"159.685813ms","start":"2026-10-03T09:55:50.233155Z","end":"2026-10-03T09:55:50.392841Z","steps":["trace[10812004] 'range keys from in-memory index tree' (duration: 159.364384ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:55:50.540946Z","caller":"traceutil/trace.go:171","msg":"trace[255159834] transaction","detail":"{read_only:false; response_revision:20521; number_of_response:1; }","duration":"110.996246ms","start":"2026-10-03T09:55:50.429927Z","end":"2026-10-03T09:55:50.540923Z","steps":["trace[255159834] 'process raft request' (duration: 110.564393ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:55:50.6946Z","caller":"traceutil/trace.go:171","msg":"trace[298256756] transaction","detail":"{read_only:false; response_revision:20522; number_of_response:1; }","duration":"158.535651ms","start":"2026-10-03T09:55:50.536Z","end":"2026-10-03T09:55:50.694536Z","steps":["trace[298256756] 'process raft request' (duration: 132.804069ms)","trace[298256756] 'compare' (duration: 25.525366ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:55:50.694768Z","caller":"traceutil/trace.go:171","msg":"trace[343844313] transaction","detail":"{read_only:false; response_revision:20523; number_of_response:1; }","duration":"155.760723ms","start":"2026-10-03T09:55:50.538973Z","end":"2026-10-03T09:55:50.694734Z","steps":["trace[343844313] 'process raft request' (duration: 155.508955ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:55:50.726022Z","caller":"traceutil/trace.go:171","msg":"trace[1885351101] linearizableReadLoop","detail":"{readStateIndex:21214; appliedIndex:21211; }","duration":"173.190342ms","start":"2026-10-03T09:55:50.552808Z","end":"2026-10-03T09:55:50.725998Z","steps":["trace[1885351101] 'read index received' (duration: 115.816541ms)","trace[1885351101] 'applied index is now lower than readState.Index' (duration: 57.372691ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:55:50.726099Z","caller":"traceutil/trace.go:171","msg":"trace[1493107380] transaction","detail":"{read_only:false; response_revision:20524; number_of_response:1; }","duration":"181.396272ms","start":"2026-10-03T09:55:50.544666Z","end":"2026-10-03T09:55:50.726062Z","steps":["trace[1493107380] 'process raft request' (duration: 181.209297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:55:50.726251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.432768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-03T09:55:50.726308Z","caller":"traceutil/trace.go:171","msg":"trace[225799586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20524; }","duration":"173.557053ms","start":"2026-10-03T09:55:50.552741Z","end":"2026-10-03T09:55:50.726298Z","steps":["trace[225799586] 'agreement among raft nodes before linearized reading' (duration: 173.356957ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:55:51.205451Z","caller":"traceutil/trace.go:171","msg":"trace[1365348174] transaction","detail":"{read_only:false; response_revision:20526; number_of_response:1; }","duration":"103.636809ms","start":"2026-10-03T09:55:51.101788Z","end":"2026-10-03T09:55:51.205425Z","steps":["trace[1365348174] 'process raft request' (duration: 103.472085ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:55:51.328706Z","caller":"traceutil/trace.go:171","msg":"trace[1684761698] linearizableReadLoop","detail":"{readStateIndex:21217; appliedIndex:21216; }","duration":"108.649031ms","start":"2026-10-03T09:55:51.220034Z","end":"2026-10-03T09:55:51.328683Z","steps":["trace[1684761698] 'read index received' (duration: 18.975753ms)","trace[1684761698] 'applied index is now lower than readState.Index' (duration: 89.672157ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:55:51.328786Z","caller":"traceutil/trace.go:171","msg":"trace[1168326037] transaction","detail":"{read_only:false; response_revision:20527; number_of_response:1; }","duration":"114.988738ms","start":"2026-10-03T09:55:51.213765Z","end":"2026-10-03T09:55:51.328754Z","steps":["trace[1168326037] 'process raft request' (duration: 25.314231ms)","trace[1168326037] 'compare' (duration: 89.42409ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:55:51.329112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.037161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T09:55:51.329232Z","caller":"traceutil/trace.go:171","msg":"trace[298882563] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:20527; }","duration":"109.226736ms","start":"2026-10-03T09:55:51.219973Z","end":"2026-10-03T09:55:51.329199Z","steps":["trace[298882563] 'agreement among raft nodes before linearized reading' (duration: 108.826065ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:59:43.15125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20132} {"level":"info","ts":"2026-10-03T09:59:43.276986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20132,"took":"121.631095ms","hash":3776960313,"current-db-size-bytes":56717312,"current-db-size":"57 MB","current-db-size-in-use-bytes":41795584,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-03T09:59:43.277053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3776960313,"revision":20132,"compact-revision":17633} {"level":"warn","ts":"2026-10-03T09:59:44.881506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.03558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T09:59:44.881736Z","caller":"traceutil/trace.go:171","msg":"trace[1936939065] transaction","detail":"{read_only:false; response_revision:22937; number_of_response:1; }","duration":"266.008549ms","start":"2026-10-03T09:59:44.615693Z","end":"2026-10-03T09:59:44.881701Z","steps":["trace[1936939065] 'process raft request' (duration: 77.64953ms)","trace[1936939065] 'compare' (duration: 187.865566ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:50.415409Z","caller":"traceutil/trace.go:171","msg":"trace[1345656129] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"128.073497ms","start":"2026-10-03T10:02:50.287312Z","end":"2026-10-03T10:02:50.415385Z","steps":["trace[1345656129] 'process raft request' (duration: 127.906532ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:50.586914Z","caller":"traceutil/trace.go:171","msg":"trace[580309669] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"247.289504ms","start":"2026-10-03T10:02:50.339584Z","end":"2026-10-03T10:02:50.586873Z","steps":["trace[580309669] 'process raft request' (duration: 226.280054ms)","trace[580309669] 'compare' (duration: 20.782474ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:50.587051Z","caller":"traceutil/trace.go:171","msg":"trace[1568356437] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25769; }","duration":"241.383919ms","start":"2026-10-03T10:02:50.345646Z","end":"2026-10-03T10:02:50.58703Z","steps":["trace[1568356437] 'read index received' (duration: 69.652276ms)","trace[1568356437] 'applied index is now lower than readState.Index' (duration: 171.730533ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:50.587197Z","caller":"traceutil/trace.go:171","msg":"trace[425279340] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"213.715671ms","start":"2026-10-03T10:02:50.373475Z","end":"2026-10-03T10:02:50.587191Z","steps":["trace[425279340] 'process raft request' (duration: 213.371191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:50.587226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.569543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T10:02:50.587257Z","caller":"traceutil/trace.go:171","msg":"trace[1548746717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24960; }","duration":"241.639696ms","start":"2026-10-03T10:02:50.345607Z","end":"2026-10-03T10:02:50.587247Z","steps":["trace[1548746717] 'agreement among raft nodes before linearized reading' (duration: 241.495552ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:50.83646Z","caller":"traceutil/trace.go:171","msg":"trace[142717809] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"327.720063ms","start":"2026-10-03T10:02:50.508715Z","end":"2026-10-03T10:02:50.836435Z","steps":["trace[142717809] 'process raft request' (duration: 327.588519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:50.836579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:50.508692Z","time spent":"327.826725ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:02:50.909152Z","caller":"traceutil/trace.go:171","msg":"trace[797617204] linearizableReadLoop","detail":"{readStateIndex:25774; appliedIndex:25772; }","duration":"212.714833ms","start":"2026-10-03T10:02:50.696405Z","end":"2026-10-03T10:02:50.90912Z","steps":["trace[797617204] 'read index received' (duration: 139.891968ms)","trace[797617204] 'applied index is now lower than readState.Index' (duration: 72.821325ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:02:50.909289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.853687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:02:50.909326Z","caller":"traceutil/trace.go:171","msg":"trace[1226116236] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:24962; }","duration":"212.95105ms","start":"2026-10-03T10:02:50.696362Z","end":"2026-10-03T10:02:50.909313Z","steps":["trace[1226116236] 'agreement among raft nodes before linearized reading' (duration: 212.861527ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:50.909301Z","caller":"traceutil/trace.go:171","msg":"trace[1093589558] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"318.342939ms","start":"2026-10-03T10:02:50.590912Z","end":"2026-10-03T10:02:50.909255Z","steps":["trace[1093589558] 'process raft request' (duration: 318.069901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:50.909493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:50.590876Z","time spent":"318.507754ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:02:51.178363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.167732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-03T10:02:51.178376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.505103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:02:51.178448Z","caller":"traceutil/trace.go:171","msg":"trace[1610221467] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24962; }","duration":"189.306526ms","start":"2026-10-03T10:02:50.989121Z","end":"2026-10-03T10:02:51.178428Z","steps":["trace[1610221467] 'range keys from in-memory index tree' (duration: 188.969706ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:51.178464Z","caller":"traceutil/trace.go:171","msg":"trace[1761558469] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24962; }","duration":"105.642437ms","start":"2026-10-03T10:02:51.072798Z","end":"2026-10-03T10:02:51.178441Z","steps":["trace[1761558469] 'count revisions from in-memory index tree' (duration: 105.39867ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:55.49361Z","caller":"traceutil/trace.go:171","msg":"trace[1443576742] linearizableReadLoop","detail":"{readStateIndex:25805; appliedIndex:25804; }","duration":"261.095451ms","start":"2026-10-03T10:02:55.232495Z","end":"2026-10-03T10:02:55.49359Z","steps":["trace[1443576742] 'read index received' (duration: 33.902192ms)","trace[1443576742] 'applied index is now lower than readState.Index' (duration: 227.192419ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:55.493653Z","caller":"traceutil/trace.go:171","msg":"trace[1887843350] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"254.358113ms","start":"2026-10-03T10:02:55.239274Z","end":"2026-10-03T10:02:55.493632Z","steps":["trace[1887843350] 'process raft request' (duration: 254.232309ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:55.493717Z","caller":"traceutil/trace.go:171","msg":"trace[218951967] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"289.120148ms","start":"2026-10-03T10:02:55.204525Z","end":"2026-10-03T10:02:55.493645Z","steps":["trace[218951967] 'process raft request' (duration: 281.892255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:55.493762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.246246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:02:55.493804Z","caller":"traceutil/trace.go:171","msg":"trace[803878686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24993; }","duration":"261.313648ms","start":"2026-10-03T10:02:55.232479Z","end":"2026-10-03T10:02:55.493793Z","steps":["trace[803878686] 'agreement among raft nodes before linearized reading' (duration: 261.235596ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:55.503792Z","caller":"traceutil/trace.go:171","msg":"trace[767647430] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"138.19659ms","start":"2026-10-03T10:02:55.365573Z","end":"2026-10-03T10:02:55.503769Z","steps":["trace[767647430] 'process raft request' (duration: 137.805499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:55.50414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.653045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:02:55.504184Z","caller":"traceutil/trace.go:171","msg":"trace[968143019] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:24995; }","duration":"101.752558ms","start":"2026-10-03T10:02:55.40242Z","end":"2026-10-03T10:02:55.504172Z","steps":["trace[968143019] 'agreement among raft nodes before linearized reading' (duration: 101.656085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:55.774872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.971487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:02:55.775121Z","caller":"traceutil/trace.go:171","msg":"trace[1928241244] linearizableReadLoop","detail":"{readStateIndex:25810; appliedIndex:25808; }","duration":"188.902355ms","start":"2026-10-03T10:02:55.586198Z","end":"2026-10-03T10:02:55.7751Z","steps":["trace[1928241244] 'read index received' (duration: 36.714591ms)","trace[1928241244] 'applied index is now lower than readState.Index' (duration: 152.186244ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:55.775232Z","caller":"traceutil/trace.go:171","msg":"trace[1570840845] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"240.628517ms","start":"2026-10-03T10:02:55.534591Z","end":"2026-10-03T10:02:55.77522Z","steps":["trace[1570840845] 'process raft request' (duration: 240.429531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:55.775419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.214133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-03T10:02:55.775449Z","caller":"traceutil/trace.go:171","msg":"trace[1925517073] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"269.362604ms","start":"2026-10-03T10:02:55.505999Z","end":"2026-10-03T10:02:55.775362Z","steps":["trace[1925517073] 'process raft request' (duration: 116.80019ms)","trace[1925517073] 'compare' (duration: 151.781072ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:55.775772Z","caller":"traceutil/trace.go:171","msg":"trace[592486541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24997; }","duration":"189.305576ms","start":"2026-10-03T10:02:55.58615Z","end":"2026-10-03T10:02:55.775455Z","steps":["trace[592486541] 'agreement among raft nodes before linearized reading' (duration: 189.154131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:56.077922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.011245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T10:02:56.078016Z","caller":"traceutil/trace.go:171","msg":"trace[62830235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24998; }","duration":"154.114878ms","start":"2026-10-03T10:02:55.923874Z","end":"2026-10-03T10:02:56.077989Z","steps":["trace[62830235] 'range keys from in-memory index tree' (duration: 153.730877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:56.078114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.85457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T10:02:56.078195Z","caller":"traceutil/trace.go:171","msg":"trace[908294126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24998; }","duration":"106.968754ms","start":"2026-10-03T10:02:55.971208Z","end":"2026-10-03T10:02:56.078177Z","steps":["trace[908294126] 'range keys from in-memory index tree' (duration: 106.727197ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:56.386355Z","caller":"traceutil/trace.go:171","msg":"trace[1439160834] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"302.562156ms","start":"2026-10-03T10:02:56.083767Z","end":"2026-10-03T10:02:56.38633Z","steps":["trace[1439160834] 'process raft request' (duration: 301.579218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:56.387062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:56.083741Z","time spent":"302.68602ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:02:56.397837Z","caller":"traceutil/trace.go:171","msg":"trace[869453186] linearizableReadLoop","detail":"{readStateIndex:25814; appliedIndex:25811; }","duration":"164.550601ms","start":"2026-10-03T10:02:56.233272Z","end":"2026-10-03T10:02:56.397822Z","steps":["trace[869453186] 'read index received' (duration: 152.091171ms)","trace[869453186] 'applied index is now lower than readState.Index' (duration: 12.4563ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:56.398021Z","caller":"traceutil/trace.go:171","msg":"trace[683281767] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"313.569395ms","start":"2026-10-03T10:02:56.084439Z","end":"2026-10-03T10:02:56.398009Z","steps":["trace[683281767] 'process raft request' (duration: 313.274357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:56.398089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:56.084426Z","time spent":"313.619966ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:02:56.398216Z","caller":"traceutil/trace.go:171","msg":"trace[1164039545] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"212.995711ms","start":"2026-10-03T10:02:56.185214Z","end":"2026-10-03T10:02:56.398209Z","steps":["trace[1164039545] 'process raft request' (duration: 212.575389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:56.398307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.034644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:02:56.398332Z","caller":"traceutil/trace.go:171","msg":"trace[529823851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25001; }","duration":"165.098856ms","start":"2026-10-03T10:02:56.233228Z","end":"2026-10-03T10:02:56.398327Z","steps":["trace[529823851] 'agreement among raft nodes before linearized reading' (duration: 165.055935ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:56.587485Z","caller":"traceutil/trace.go:171","msg":"trace[60764333] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"150.577788ms","start":"2026-10-03T10:02:56.43688Z","end":"2026-10-03T10:02:56.587458Z","steps":["trace[60764333] 'process raft request' (duration: 148.646364ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:56.829044Z","caller":"traceutil/trace.go:171","msg":"trace[2126639887] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"217.902098ms","start":"2026-10-03T10:02:56.61112Z","end":"2026-10-03T10:02:56.829022Z","steps":["trace[2126639887] 'process raft request' (duration: 144.504047ms)","trace[2126639887] 'compare' (duration: 73.188565ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:57.003845Z","caller":"traceutil/trace.go:171","msg":"trace[603826134] linearizableReadLoop","detail":"{readStateIndex:25817; appliedIndex:25815; }","duration":"310.845899ms","start":"2026-10-03T10:02:56.692982Z","end":"2026-10-03T10:02:57.003828Z","steps":["trace[603826134] 'read index received' (duration: 62.66394ms)","trace[603826134] 'applied index is now lower than readState.Index' (duration: 248.181049ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:57.003859Z","caller":"traceutil/trace.go:171","msg":"trace[1939161848] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"392.675986ms","start":"2026-10-03T10:02:56.611159Z","end":"2026-10-03T10:02:57.003835Z","steps":["trace[1939161848] 'process raft request' (duration: 392.559183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:57.004506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:56.611134Z","time spent":"393.309334ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:02:57.004201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.205309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.179\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-03T10:02:57.004609Z","caller":"traceutil/trace.go:171","msg":"trace[1391194872] range","detail":"{range_begin:/registry/masterleases/162.253.55.179; range_end:; response_count:1; response_revision:25004; }","duration":"311.654671ms","start":"2026-10-03T10:02:56.692933Z","end":"2026-10-03T10:02:57.004587Z","steps":["trace[1391194872] 'agreement among raft nodes before linearized reading' (duration: 310.981022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:57.00471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:56.692909Z","time spent":"311.782905ms","remote":"127.0.0.1:44410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/162.253.55.179\" "} {"level":"info","ts":"2026-10-03T10:02:57.005043Z","caller":"traceutil/trace.go:171","msg":"trace[1080659113] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"145.160276ms","start":"2026-10-03T10:02:56.859862Z","end":"2026-10-03T10:02:57.005022Z","steps":["trace[1080659113] 'process raft request' (duration: 144.988952ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:57.169874Z","caller":"traceutil/trace.go:171","msg":"trace[1188065150] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"161.038532ms","start":"2026-10-03T10:02:57.008808Z","end":"2026-10-03T10:02:57.169846Z","steps":["trace[1188065150] 'process raft request' (duration: 156.831224ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:57.169974Z","caller":"traceutil/trace.go:171","msg":"trace[465623264] linearizableReadLoop","detail":"{readStateIndex:25820; appliedIndex:25818; }","duration":"160.96528ms","start":"2026-10-03T10:02:57.008986Z","end":"2026-10-03T10:02:57.169951Z","steps":["trace[465623264] 'read index received' (duration: 17.367487ms)","trace[465623264] 'applied index is now lower than readState.Index' (duration: 143.595813ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:57.169979Z","caller":"traceutil/trace.go:171","msg":"trace[831340405] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"142.746298ms","start":"2026-10-03T10:02:57.02718Z","end":"2026-10-03T10:02:57.169926Z","steps":["trace[831340405] 'process raft request' (duration: 142.630685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:57.17022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.214647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-10-03T10:02:57.17026Z","caller":"traceutil/trace.go:171","msg":"trace[1407247797] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:25007; }","duration":"161.286619ms","start":"2026-10-03T10:02:57.008963Z","end":"2026-10-03T10:02:57.170249Z","steps":["trace[1407247797] 'agreement among raft nodes before linearized reading' (duration: 161.096034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:57.170275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.503742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-10-03T10:02:57.170364Z","caller":"traceutil/trace.go:171","msg":"trace[1622093878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:25007; }","duration":"147.619545ms","start":"2026-10-03T10:02:57.02272Z","end":"2026-10-03T10:02:57.170339Z","steps":["trace[1622093878] 'agreement among raft nodes before linearized reading' (duration: 147.381878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:57.679718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.977842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-03T10:02:57.679804Z","caller":"traceutil/trace.go:171","msg":"trace[949239788] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25008; }","duration":"181.160327ms","start":"2026-10-03T10:02:57.498623Z","end":"2026-10-03T10:02:57.679783Z","steps":["trace[949239788] 'range keys from in-memory index tree' (duration: 180.804587ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:57.680409Z","caller":"traceutil/trace.go:171","msg":"trace[1862732313] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"169.51494ms","start":"2026-10-03T10:02:57.510863Z","end":"2026-10-03T10:02:57.680378Z","steps":["trace[1862732313] 'process raft request' (duration: 156.361741ms)","trace[1862732313] 'compare' (duration: 11.75857ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:58.008044Z","caller":"traceutil/trace.go:171","msg":"trace[106550634] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"225.0423ms","start":"2026-10-03T10:02:57.782978Z","end":"2026-10-03T10:02:58.008021Z","steps":["trace[106550634] 'process raft request' (duration: 225.000478ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:58.008339Z","caller":"traceutil/trace.go:171","msg":"trace[1336029532] linearizableReadLoop","detail":"{readStateIndex:25824; appliedIndex:25822; }","duration":"480.000599ms","start":"2026-10-03T10:02:57.528309Z","end":"2026-10-03T10:02:58.008309Z","steps":["trace[1336029532] 'read index received' (duration: 138.925751ms)","trace[1336029532] 'applied index is now lower than readState.Index' (duration: 341.061427ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:58.008392Z","caller":"traceutil/trace.go:171","msg":"trace[216673047] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"495.288418ms","start":"2026-10-03T10:02:57.513052Z","end":"2026-10-03T10:02:58.008341Z","steps":["trace[216673047] 'process raft request' (duration: 455.747418ms)","trace[216673047] 'compare' (duration: 38.985745ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:58.008432Z","caller":"traceutil/trace.go:171","msg":"trace[759624146] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"321.730825ms","start":"2026-10-03T10:02:57.686657Z","end":"2026-10-03T10:02:58.008388Z","steps":["trace[759624146] 'process raft request' (duration: 321.278902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:58.008505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.172653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:02:58.008546Z","caller":"traceutil/trace.go:171","msg":"trace[2060657812] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25012; }","duration":"480.261456ms","start":"2026-10-03T10:02:57.528271Z","end":"2026-10-03T10:02:58.008532Z","steps":["trace[2060657812] 'agreement among raft nodes before linearized reading' (duration: 480.165373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:58.008579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:57.52824Z","time spent":"480.330078ms","remote":"127.0.0.1:49818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-10-03T10:02:58.008637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:57.686636Z","time spent":"321.882679ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:02:58.008649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:57.513032Z","time spent":"495.462233ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:02:58.008716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.505438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:02:58.008765Z","caller":"traceutil/trace.go:171","msg":"trace[62334073] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25012; }","duration":"415.646322ms","start":"2026-10-03T10:02:57.593105Z","end":"2026-10-03T10:02:58.008751Z","steps":["trace[62334073] 'agreement among raft nodes before linearized reading' (duration: 415.514678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:58.008794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:57.59307Z","time spent":"415.715344ms","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:02:58.00909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.932185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-03T10:02:58.009136Z","caller":"traceutil/trace.go:171","msg":"trace[130904799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25012; }","duration":"142.023468ms","start":"2026-10-03T10:02:57.867099Z","end":"2026-10-03T10:02:58.009122Z","steps":["trace[130904799] 'agreement among raft nodes before linearized reading' (duration: 141.811912ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:58.525652Z","caller":"traceutil/trace.go:171","msg":"trace[802169274] linearizableReadLoop","detail":"{readStateIndex:25828; appliedIndex:25827; }","duration":"118.771045ms","start":"2026-10-03T10:02:58.406856Z","end":"2026-10-03T10:02:58.525627Z","steps":["trace[802169274] 'read index received' (duration: 118.469467ms)","trace[802169274] 'applied index is now lower than readState.Index' (duration: 300.478µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:58.525755Z","caller":"traceutil/trace.go:171","msg":"trace[1519577481] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"130.729711ms","start":"2026-10-03T10:02:58.39498Z","end":"2026-10-03T10:02:58.52571Z","steps":["trace[1519577481] 'process raft request' (duration: 130.467834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:58.525876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.008492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T10:02:58.525917Z","caller":"traceutil/trace.go:171","msg":"trace[865004212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25014; }","duration":"119.110215ms","start":"2026-10-03T10:02:58.406796Z","end":"2026-10-03T10:02:58.525907Z","steps":["trace[865004212] 'agreement among raft nodes before linearized reading' (duration: 118.94822ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:58.78538Z","caller":"traceutil/trace.go:171","msg":"trace[1650422042] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"254.293361ms","start":"2026-10-03T10:02:58.531041Z","end":"2026-10-03T10:02:58.785334Z","steps":["trace[1650422042] 'process raft request' (duration: 254.106136ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:58.824661Z","caller":"traceutil/trace.go:171","msg":"trace[404038884] linearizableReadLoop","detail":"{readStateIndex:25830; appliedIndex:25828; }","duration":"210.185081ms","start":"2026-10-03T10:02:58.614442Z","end":"2026-10-03T10:02:58.824627Z","steps":["trace[404038884] 'read index received' (duration: 170.548229ms)","trace[404038884] 'applied index is now lower than readState.Index' (duration: 39.635382ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:58.824823Z","caller":"traceutil/trace.go:171","msg":"trace[1801282572] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"229.974257ms","start":"2026-10-03T10:02:58.59481Z","end":"2026-10-03T10:02:58.824784Z","steps":["trace[1801282572] 'process raft request' (duration: 229.617887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:58.824926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.432488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:02:58.824985Z","caller":"traceutil/trace.go:171","msg":"trace[649537927] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25016; }","duration":"210.559032ms","start":"2026-10-03T10:02:58.614408Z","end":"2026-10-03T10:02:58.824967Z","steps":["trace[649537927] 'agreement among raft nodes before linearized reading' (duration: 210.420548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:58.825112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.124496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:02:58.825165Z","caller":"traceutil/trace.go:171","msg":"trace[1859618096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25016; }","duration":"123.233119ms","start":"2026-10-03T10:02:58.701919Z","end":"2026-10-03T10:02:58.825152Z","steps":["trace[1859618096] 'agreement among raft nodes before linearized reading' (duration: 123.123576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:58.82544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.116776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:02:58.825496Z","caller":"traceutil/trace.go:171","msg":"trace[238342490] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25016; }","duration":"123.224219ms","start":"2026-10-03T10:02:58.702256Z","end":"2026-10-03T10:02:58.82548Z","steps":["trace[238342490] 'agreement among raft nodes before linearized reading' (duration: 123.124786ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:59.049714Z","caller":"traceutil/trace.go:171","msg":"trace[1948569086] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"214.0542ms","start":"2026-10-03T10:02:58.835601Z","end":"2026-10-03T10:02:59.049655Z","steps":["trace[1948569086] 'process raft request' (duration: 135.35673ms)","trace[1948569086] 'compare' (duration: 78.540126ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:59.135608Z","caller":"traceutil/trace.go:171","msg":"trace[1897942602] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"125.822883ms","start":"2026-10-03T10:02:59.009752Z","end":"2026-10-03T10:02:59.135575Z","steps":["trace[1897942602] 'process raft request' (duration: 125.558396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:59.305422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.628197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:02:59.305534Z","caller":"traceutil/trace.go:171","msg":"trace[813456802] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25832; }","duration":"129.212388ms","start":"2026-10-03T10:02:59.176303Z","end":"2026-10-03T10:02:59.305515Z","steps":["trace[813456802] 'read index received' (duration: 59.201µs)","trace[813456802] 'applied index is now lower than readState.Index' (duration: 129.151807ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:59.305632Z","caller":"traceutil/trace.go:171","msg":"trace[1258186226] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"293.713328ms","start":"2026-10-03T10:02:59.011906Z","end":"2026-10-03T10:02:59.30562Z","steps":["trace[1258186226] 'process raft request' (duration: 156.792903ms)","trace[1258186226] 'compare' (duration: 136.470492ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:02:59.30613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.814405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T10:02:59.306223Z","caller":"traceutil/trace.go:171","msg":"trace[651324004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25019; }","duration":"129.968159ms","start":"2026-10-03T10:02:59.176237Z","end":"2026-10-03T10:02:59.306205Z","steps":["trace[651324004] 'agreement among raft nodes before linearized reading' (duration: 129.699272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:59.306562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.049807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1863"} {"level":"info","ts":"2026-10-03T10:02:59.306686Z","caller":"traceutil/trace.go:171","msg":"trace[1755602508] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25019; }","duration":"101.216333ms","start":"2026-10-03T10:02:59.205426Z","end":"2026-10-03T10:02:59.306643Z","steps":["trace[1755602508] 'agreement among raft nodes before linearized reading' (duration: 100.220335ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:59.582974Z","caller":"traceutil/trace.go:171","msg":"trace[1318644308] linearizableReadLoop","detail":"{readStateIndex:25834; appliedIndex:25833; }","duration":"219.694509ms","start":"2026-10-03T10:02:59.36324Z","end":"2026-10-03T10:02:59.582934Z","steps":["trace[1318644308] 'read index received' (duration: 130.947667ms)","trace[1318644308] 'applied index is now lower than readState.Index' (duration: 88.745302ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:02:59.583135Z","caller":"traceutil/trace.go:171","msg":"trace[1166744935] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"271.349029ms","start":"2026-10-03T10:02:59.311738Z","end":"2026-10-03T10:02:59.583087Z","steps":["trace[1166744935] 'process raft request' (duration: 182.464384ms)","trace[1166744935] 'compare' (duration: 88.542646ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:02:59.583279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.005448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-03T10:02:59.583327Z","caller":"traceutil/trace.go:171","msg":"trace[634772663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25020; }","duration":"220.110151ms","start":"2026-10-03T10:02:59.363203Z","end":"2026-10-03T10:02:59.583313Z","steps":["trace[634772663] 'agreement among raft nodes before linearized reading' (duration: 219.863114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:02:59.927581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.511648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:02:59.928012Z","caller":"traceutil/trace.go:171","msg":"trace[579686244] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"241.165772ms","start":"2026-10-03T10:02:59.686813Z","end":"2026-10-03T10:02:59.927979Z","steps":["trace[579686244] 'process raft request' (duration: 240.919085ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:59.92802Z","caller":"traceutil/trace.go:171","msg":"trace[1728177600] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"189.242444ms","start":"2026-10-03T10:02:59.738744Z","end":"2026-10-03T10:02:59.927986Z","steps":["trace[1728177600] 'process raft request' (duration: 189.050439ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:02:59.928027Z","caller":"traceutil/trace.go:171","msg":"trace[1277203131] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"339.37386ms","start":"2026-10-03T10:02:59.588607Z","end":"2026-10-03T10:02:59.927981Z","steps":["trace[1277203131] 'process raft request' (duration: 157.339129ms)","trace[1277203131] 'compare' (duration: 180.881929ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:02:59.929067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:02:59.588578Z","time spent":"339.543904ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:00.239452Z","caller":"traceutil/trace.go:171","msg":"trace[94311594] linearizableReadLoop","detail":"{readStateIndex:25838; appliedIndex:25837; }","duration":"223.329501ms","start":"2026-10-03T10:03:00.016093Z","end":"2026-10-03T10:03:00.239423Z","steps":["trace[94311594] 'read index received' (duration: 169.309024ms)","trace[94311594] 'applied index is now lower than readState.Index' (duration: 54.019527ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:00.239514Z","caller":"traceutil/trace.go:171","msg":"trace[1081335648] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"225.286166ms","start":"2026-10-03T10:03:00.01419Z","end":"2026-10-03T10:03:00.239476Z","steps":["trace[1081335648] 'process raft request' (duration: 171.099385ms)","trace[1081335648] 'compare' (duration: 53.997596ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:00.239756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.65005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T10:03:00.239795Z","caller":"traceutil/trace.go:171","msg":"trace[714032078] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25024; }","duration":"223.745033ms","start":"2026-10-03T10:03:00.016034Z","end":"2026-10-03T10:03:00.239779Z","steps":["trace[714032078] 'agreement among raft nodes before linearized reading' (duration: 223.549147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:00.330781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.872018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-03T10:03:00.330884Z","caller":"traceutil/trace.go:171","msg":"trace[1320410576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25025; }","duration":"291.021633ms","start":"2026-10-03T10:03:00.03984Z","end":"2026-10-03T10:03:00.330861Z","steps":["trace[1320410576] 'agreement among raft nodes before linearized reading' (duration: 290.729084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:00.331094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.604687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:03:00.331108Z","caller":"traceutil/trace.go:171","msg":"trace[890363026] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"309.634905ms","start":"2026-10-03T10:03:00.02144Z","end":"2026-10-03T10:03:00.331075Z","steps":["trace[890363026] 'process raft request' (duration: 308.877953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:00.331143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.954425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:00.331163Z","caller":"traceutil/trace.go:171","msg":"trace[1695567044] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25025; }","duration":"125.70337ms","start":"2026-10-03T10:03:00.205444Z","end":"2026-10-03T10:03:00.331148Z","steps":["trace[1695567044] 'agreement among raft nodes before linearized reading' (duration: 125.546355ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:00.331209Z","caller":"traceutil/trace.go:171","msg":"trace[328653369] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25025; }","duration":"127.082939ms","start":"2026-10-03T10:03:00.204108Z","end":"2026-10-03T10:03:00.331191Z","steps":["trace[328653369] 'agreement among raft nodes before linearized reading' (duration: 126.971116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:00.331261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:00.021406Z","time spent":"309.768138ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:00.385397Z","caller":"traceutil/trace.go:171","msg":"trace[543710829] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"141.215325ms","start":"2026-10-03T10:03:00.244156Z","end":"2026-10-03T10:03:00.385371Z","steps":["trace[543710829] 'process raft request' (duration: 141.062371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:00.684624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.86214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T10:03:00.684721Z","caller":"traceutil/trace.go:171","msg":"trace[1009450937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25027; }","duration":"154.014604ms","start":"2026-10-03T10:03:00.530689Z","end":"2026-10-03T10:03:00.684704Z","steps":["trace[1009450937] 'range keys from in-memory index tree' (duration: 153.689206ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:11.315093Z","caller":"traceutil/trace.go:171","msg":"trace[1307004174] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"217.841737ms","start":"2026-10-03T10:03:11.097222Z","end":"2026-10-03T10:03:11.315064Z","steps":["trace[1307004174] 'process raft request' (duration: 217.643412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:11.892193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.768069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:03:11.892419Z","caller":"traceutil/trace.go:171","msg":"trace[877198816] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"712.142518ms","start":"2026-10-03T10:03:11.180233Z","end":"2026-10-03T10:03:11.892375Z","steps":["trace[877198816] 'process raft request' (duration: 418.103721ms)","trace[877198816] 'compare' (duration: 293.540203ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:11.892508Z","caller":"traceutil/trace.go:171","msg":"trace[1867650608] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"543.409199ms","start":"2026-10-03T10:03:11.349091Z","end":"2026-10-03T10:03:11.8925Z","steps":["trace[1867650608] 'process raft request' (duration: 543.224144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:11.892566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.349072Z","time spent":"543.462981ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:11.892605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.180206Z","time spent":"712.302522ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:11.892473Z","caller":"traceutil/trace.go:171","msg":"trace[1912568843] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"471.763527ms","start":"2026-10-03T10:03:11.420692Z","end":"2026-10-03T10:03:11.892456Z","steps":["trace[1912568843] 'process raft request' (duration: 471.719026ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:11.892747Z","caller":"traceutil/trace.go:171","msg":"trace[437481126] linearizableReadLoop","detail":"{readStateIndex:25922; appliedIndex:25920; }","duration":"691.385515ms","start":"2026-10-03T10:03:11.201272Z","end":"2026-10-03T10:03:11.892657Z","steps":["trace[437481126] 'read index received' (duration: 113.652772ms)","trace[437481126] 'applied index is now lower than readState.Index' (duration: 577.730393ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:11.892868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.420644Z","time spent":"472.114628ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:11.893096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.837439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:11.893145Z","caller":"traceutil/trace.go:171","msg":"trace[1553718531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25108; }","duration":"659.87789ms","start":"2026-10-03T10:03:11.233247Z","end":"2026-10-03T10:03:11.893125Z","steps":["trace[1553718531] 'agreement among raft nodes before linearized reading' (duration: 659.793508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:11.893175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.233214Z","time spent":"659.955502ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T10:03:11.893182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.878738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:03:11.893232Z","caller":"traceutil/trace.go:171","msg":"trace[1650403064] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25108; }","duration":"692.000902ms","start":"2026-10-03T10:03:11.201219Z","end":"2026-10-03T10:03:11.893219Z","steps":["trace[1650403064] 'agreement among raft nodes before linearized reading' (duration: 691.724614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:11.893269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.201198Z","time spent":"692.059654ms","remote":"127.0.0.1:44618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":92,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:03:11.89352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.406164ms","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-10-03T10:03:11.893583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.599428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:11.89362Z","caller":"traceutil/trace.go:171","msg":"trace[1150644147] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25108; }","duration":"557.659339ms","start":"2026-10-03T10:03:11.335948Z","end":"2026-10-03T10:03:11.893607Z","steps":["trace[1150644147] 'agreement among raft nodes before linearized reading' (duration: 557.599848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:11.893645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.335931Z","time spent":"557.70665ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:03:11.893666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.98727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:03:11.893737Z","caller":"traceutil/trace.go:171","msg":"trace[71619474] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25108; }","duration":"172.093713ms","start":"2026-10-03T10:03:11.72163Z","end":"2026-10-03T10:03:11.893724Z","steps":["trace[71619474] 'agreement among raft nodes before linearized reading' (duration: 171.9785ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:11.893582Z","caller":"traceutil/trace.go:171","msg":"trace[1828431416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25108; }","duration":"254.518147ms","start":"2026-10-03T10:03:11.639047Z","end":"2026-10-03T10:03:11.893566Z","steps":["trace[1828431416] 'agreement among raft nodes before linearized reading' (duration: 254.321542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:12.499824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15451182922435356024,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-03T10:03:12.524968Z","caller":"traceutil/trace.go:171","msg":"trace[2081768753] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"557.092974ms","start":"2026-10-03T10:03:11.967851Z","end":"2026-10-03T10:03:12.524944Z","steps":["trace[2081768753] 'process raft request' (duration: 556.94122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:12.525108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.967819Z","time spent":"557.223088ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1560,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T10:03:12.572096Z","caller":"traceutil/trace.go:171","msg":"trace[394234726] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"593.723452ms","start":"2026-10-03T10:03:11.978344Z","end":"2026-10-03T10:03:12.572068Z","steps":["trace[394234726] 'process raft request' (duration: 593.499186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:12.572349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.978322Z","time spent":"593.859716ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:12.852338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.27618ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-03T10:03:12.853072Z","caller":"traceutil/trace.go:171","msg":"trace[1407131820] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"570.530951ms","start":"2026-10-03T10:03:12.282513Z","end":"2026-10-03T10:03:12.853044Z","steps":["trace[1407131820] 'process raft request' (duration: 569.998126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:12.853234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.282482Z","time spent":"570.651955ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:13.013524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.350152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:03:13.013873Z","caller":"traceutil/trace.go:171","msg":"trace[1514285474] linearizableReadLoop","detail":"{readStateIndex:25930; appliedIndex:25925; }","duration":"1.015079854s","start":"2026-10-03T10:03:11.998764Z","end":"2026-10-03T10:03:13.013844Z","steps":["trace[1514285474] 'read index received' (duration: 526.104564ms)","trace[1514285474] 'applied index is now lower than readState.Index' (duration: 488.97301ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:13.014168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015391753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-03T10:03:13.014209Z","caller":"traceutil/trace.go:171","msg":"trace[1729361017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25114; }","duration":"1.015494065s","start":"2026-10-03T10:03:11.998704Z","end":"2026-10-03T10:03:13.014198Z","steps":["trace[1729361017] 'agreement among raft nodes before linearized reading' (duration: 1.01531288s)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.014244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:11.998665Z","time spent":"1.015570877s","remote":"127.0.0.1:44610","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-10-03T10:03:13.014307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.056179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T10:03:13.014397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.16829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T10:03:13.014448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.819011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T10:03:13.014495Z","caller":"traceutil/trace.go:171","msg":"trace[1180628430] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25114; }","duration":"737.330725ms","start":"2026-10-03T10:03:12.277142Z","end":"2026-10-03T10:03:13.014473Z","steps":["trace[1180628430] 'agreement among raft nodes before linearized reading' (duration: 737.16346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.014459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.580526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"warn","ts":"2026-10-03T10:03:13.014546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.277112Z","time spent":"737.423057ms","remote":"127.0.0.1:44656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-10-03T10:03:13.014546Z","caller":"traceutil/trace.go:171","msg":"trace[729602450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25114; }","duration":"157.993096ms","start":"2026-10-03T10:03:12.85653Z","end":"2026-10-03T10:03:13.014523Z","steps":["trace[729602450] 'agreement among raft nodes before linearized reading' (duration: 157.646997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.014553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.987255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T10:03:13.014463Z","caller":"traceutil/trace.go:171","msg":"trace[165353440] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25114; }","duration":"986.349067ms","start":"2026-10-03T10:03:12.028085Z","end":"2026-10-03T10:03:13.014434Z","steps":["trace[165353440] 'agreement among raft nodes before linearized reading' (duration: 986.016398ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:13.014583Z","caller":"traceutil/trace.go:171","msg":"trace[490342678] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25114; }","duration":"479.783772ms","start":"2026-10-03T10:03:12.534768Z","end":"2026-10-03T10:03:13.014552Z","steps":["trace[490342678] 'agreement among raft nodes before linearized reading' (duration: 479.466523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.014707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.53474Z","time spent":"479.919496ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1601,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-10-03T10:03:13.014723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.152408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-03T10:03:13.014535Z","caller":"traceutil/trace.go:171","msg":"trace[1390961047] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"638.807328ms","start":"2026-10-03T10:03:12.37565Z","end":"2026-10-03T10:03:13.014457Z","steps":["trace[1390961047] 'process raft request' (duration: 477.379635ms)","trace[1390961047] 'compare' (duration: 160.116216ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:13.014615Z","caller":"traceutil/trace.go:171","msg":"trace[1836435081] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25114; }","duration":"565.077717ms","start":"2026-10-03T10:03:12.449517Z","end":"2026-10-03T10:03:13.014595Z","steps":["trace[1836435081] 'agreement among raft nodes before linearized reading' (duration: 564.910133ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:13.014762Z","caller":"traceutil/trace.go:171","msg":"trace[1683387513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25114; }","duration":"571.23237ms","start":"2026-10-03T10:03:12.443519Z","end":"2026-10-03T10:03:13.014752Z","steps":["trace[1683387513] 'agreement among raft nodes before linearized reading' (duration: 571.119797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.014793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.449499Z","time spent":"565.278234ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-03T10:03:13.014805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.44349Z","time spent":"571.307032ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-03T10:03:13.01473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.028067Z","time spent":"986.648286ms","remote":"127.0.0.1:44770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-03T10:03:13.014357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.246755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:03:13.014967Z","caller":"traceutil/trace.go:171","msg":"trace[1878812771] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25114; }","duration":"397.909164ms","start":"2026-10-03T10:03:12.617022Z","end":"2026-10-03T10:03:13.014931Z","steps":["trace[1878812771] 'agreement among raft nodes before linearized reading' (duration: 397.209274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.014941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.851239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T10:03:13.014342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.048786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"warn","ts":"2026-10-03T10:03:13.015037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.616995Z","time spent":"398.019626ms","remote":"127.0.0.1:51054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-10-03T10:03:13.015077Z","caller":"traceutil/trace.go:171","msg":"trace[445911539] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25114; }","duration":"482.854558ms","start":"2026-10-03T10:03:12.532201Z","end":"2026-10-03T10:03:13.015055Z","steps":["trace[445911539] 'agreement among raft nodes before linearized reading' (duration: 481.898842ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:13.015061Z","caller":"traceutil/trace.go:171","msg":"trace[784644677] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25114; }","duration":"889.028424ms","start":"2026-10-03T10:03:12.125988Z","end":"2026-10-03T10:03:13.015016Z","steps":["trace[784644677] 'agreement among raft nodes before linearized reading' (duration: 888.773287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.015101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.375607Z","time spent":"639.2361ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:13.015132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.532177Z","time spent":"482.939801ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1601,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-10-03T10:03:13.015141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.125968Z","time spent":"889.154078ms","remote":"127.0.0.1:49736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:03:13.015792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.693187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-03T10:03:13.015836Z","caller":"traceutil/trace.go:171","msg":"trace[1264913909] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25114; }","duration":"784.015275ms","start":"2026-10-03T10:03:12.231811Z","end":"2026-10-03T10:03:13.015826Z","steps":["trace[1264913909] 'agreement among raft nodes before linearized reading' (duration: 782.611306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.015864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:12.231771Z","time spent":"784.084427ms","remote":"127.0.0.1:44438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2696,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-10-03T10:03:13.19692Z","caller":"traceutil/trace.go:171","msg":"trace[1197755772] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"176.995239ms","start":"2026-10-03T10:03:13.019866Z","end":"2026-10-03T10:03:13.196861Z","steps":["trace[1197755772] 'process raft request' (duration: 164.899589ms)","trace[1197755772] 'compare' (duration: 11.831772ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:13.300131Z","caller":"traceutil/trace.go:171","msg":"trace[1486091260] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"279.504638ms","start":"2026-10-03T10:03:13.0206Z","end":"2026-10-03T10:03:13.300105Z","steps":["trace[1486091260] 'process raft request' (duration: 279.419725ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:13.300238Z","caller":"traceutil/trace.go:171","msg":"trace[1127732457] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"279.678003ms","start":"2026-10-03T10:03:13.020535Z","end":"2026-10-03T10:03:13.300213Z","steps":["trace[1127732457] 'process raft request' (duration: 279.329863ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:13.30049Z","caller":"traceutil/trace.go:171","msg":"trace[1593577507] linearizableReadLoop","detail":"{readStateIndex:25936; appliedIndex:25931; }","duration":"276.687129ms","start":"2026-10-03T10:03:13.023783Z","end":"2026-10-03T10:03:13.30047Z","steps":["trace[1593577507] 'read index received' (duration: 160.919378ms)","trace[1593577507] 'applied index is now lower than readState.Index' (duration: 115.766821ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:13.30049Z","caller":"traceutil/trace.go:171","msg":"trace[1386047219] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"279.788866ms","start":"2026-10-03T10:03:13.020637Z","end":"2026-10-03T10:03:13.300426Z","steps":["trace[1386047219] 'process raft request' (duration: 279.429386ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:13.300747Z","caller":"traceutil/trace.go:171","msg":"trace[502963712] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"276.573795ms","start":"2026-10-03T10:03:13.024147Z","end":"2026-10-03T10:03:13.300721Z","steps":["trace[502963712] 'process raft request' (duration: 276.241896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.300795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.998148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-03T10:03:13.300598Z","caller":"traceutil/trace.go:171","msg":"trace[1768873479] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"278.829168ms","start":"2026-10-03T10:03:13.021758Z","end":"2026-10-03T10:03:13.300587Z","steps":["trace[1768873479] 'process raft request' (duration: 278.552021ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:13.300986Z","caller":"traceutil/trace.go:171","msg":"trace[2127807541] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25120; }","duration":"277.212763ms","start":"2026-10-03T10:03:13.023759Z","end":"2026-10-03T10:03:13.300971Z","steps":["trace[2127807541] 'agreement among raft nodes before linearized reading' (duration: 276.864213ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:13.807205Z","caller":"traceutil/trace.go:171","msg":"trace[1196069103] linearizableReadLoop","detail":"{readStateIndex:25939; appliedIndex:25938; }","duration":"434.683026ms","start":"2026-10-03T10:03:13.372499Z","end":"2026-10-03T10:03:13.807182Z","steps":["trace[1196069103] 'read index received' (duration: 434.47337ms)","trace[1196069103] 'applied index is now lower than readState.Index' (duration: 208.806µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:13.807338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.813919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:13.807372Z","caller":"traceutil/trace.go:171","msg":"trace[1825535388] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:0; response_revision:25122; }","duration":"434.919003ms","start":"2026-10-03T10:03:13.372441Z","end":"2026-10-03T10:03:13.80736Z","steps":["trace[1825535388] 'agreement among raft nodes before linearized reading' (duration: 434.831631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.807405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:13.372412Z","time spent":"434.985235ms","remote":"127.0.0.1:44452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-10-03T10:03:13.807381Z","caller":"traceutil/trace.go:171","msg":"trace[1688702657] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"487.026576ms","start":"2026-10-03T10:03:13.320325Z","end":"2026-10-03T10:03:13.807352Z","steps":["trace[1688702657] 'process raft request' (duration: 486.704667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:13.807527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:13.320301Z","time spent":"487.141319ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:14.238637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.794728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:03:14.238887Z","caller":"traceutil/trace.go:171","msg":"trace[2056363606] linearizableReadLoop","detail":"{readStateIndex:25942; appliedIndex:25939; }","duration":"278.543892ms","start":"2026-10-03T10:03:13.960325Z","end":"2026-10-03T10:03:14.238869Z","steps":["trace[2056363606] 'read index received' (duration: 9.454046ms)","trace[2056363606] 'applied index is now lower than readState.Index' (duration: 269.089156ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:14.239073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.744907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T10:03:14.239106Z","caller":"traceutil/trace.go:171","msg":"trace[1300724467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25125; }","duration":"278.825659ms","start":"2026-10-03T10:03:13.96027Z","end":"2026-10-03T10:03:14.239096Z","steps":["trace[1300724467] 'agreement among raft nodes before linearized reading' (duration: 278.675825ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:14.23922Z","caller":"traceutil/trace.go:171","msg":"trace[1051908033] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"339.799271ms","start":"2026-10-03T10:03:13.8994Z","end":"2026-10-03T10:03:14.239199Z","steps":["trace[1051908033] 'process raft request' (duration: 339.352349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:14.239283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:13.89937Z","time spent":"339.875554ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:14.239454Z","caller":"traceutil/trace.go:171","msg":"trace[141571693] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"336.294123ms","start":"2026-10-03T10:03:13.903147Z","end":"2026-10-03T10:03:14.239441Z","steps":["trace[141571693] 'process raft request' (duration: 335.684796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:14.23952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:13.90312Z","time spent":"336.362325ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:14.239345Z","caller":"traceutil/trace.go:171","msg":"trace[1611518932] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"428.492602ms","start":"2026-10-03T10:03:13.81082Z","end":"2026-10-03T10:03:14.239313Z","steps":["trace[1611518932] 'process raft request' (duration: 158.949284ms)","trace[1611518932] 'compare' (duration: 268.456748ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:14.240175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:13.8108Z","time spent":"429.298855ms","remote":"127.0.0.1:44550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1240,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T10:03:14.435481Z","caller":"traceutil/trace.go:171","msg":"trace[1664010422] linearizableReadLoop","detail":"{readStateIndex:25943; appliedIndex:25942; }","duration":"190.491049ms","start":"2026-10-03T10:03:14.244953Z","end":"2026-10-03T10:03:14.435444Z","steps":["trace[1664010422] 'read index received' (duration: 183.592235ms)","trace[1664010422] 'applied index is now lower than readState.Index' (duration: 6.897304ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:14.4355Z","caller":"traceutil/trace.go:171","msg":"trace[1755858609] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"190.648714ms","start":"2026-10-03T10:03:14.244818Z","end":"2026-10-03T10:03:14.435466Z","steps":["trace[1755858609] 'process raft request' (duration: 183.663468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:14.435769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.796307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129909"} {"level":"info","ts":"2026-10-03T10:03:14.435839Z","caller":"traceutil/trace.go:171","msg":"trace[95512829] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25126; }","duration":"190.897001ms","start":"2026-10-03T10:03:14.244927Z","end":"2026-10-03T10:03:14.435824Z","steps":["trace[95512829] 'agreement among raft nodes before linearized reading' (duration: 190.647464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:14.43724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.595672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:204"} {"level":"info","ts":"2026-10-03T10:03:14.437291Z","caller":"traceutil/trace.go:171","msg":"trace[1064988810] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:25126; }","duration":"190.711865ms","start":"2026-10-03T10:03:14.246567Z","end":"2026-10-03T10:03:14.437279Z","steps":["trace[1064988810] 'agreement among raft nodes before linearized reading' (duration: 190.570342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:14.437292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.889461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-10-03T10:03:14.437396Z","caller":"traceutil/trace.go:171","msg":"trace[479847568] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:25126; }","duration":"191.027724ms","start":"2026-10-03T10:03:14.246345Z","end":"2026-10-03T10:03:14.437373Z","steps":["trace[479847568] 'agreement among raft nodes before linearized reading' (duration: 190.756567ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:16.004509Z","caller":"traceutil/trace.go:171","msg":"trace[1146535757] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"191.97816ms","start":"2026-10-03T10:03:15.812495Z","end":"2026-10-03T10:03:16.004473Z","steps":["trace[1146535757] 'process raft request' (duration: 191.586829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:17.534634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.97676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-03T10:03:17.534779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.665792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-03T10:03:17.534824Z","caller":"traceutil/trace.go:171","msg":"trace[1187244119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25202; }","duration":"192.235788ms","start":"2026-10-03T10:03:17.342549Z","end":"2026-10-03T10:03:17.534784Z","steps":["trace[1187244119] 'range keys from in-memory index tree' (duration: 191.746144ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:17.534861Z","caller":"traceutil/trace.go:171","msg":"trace[400981324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25202; }","duration":"191.757705ms","start":"2026-10-03T10:03:17.343081Z","end":"2026-10-03T10:03:17.534839Z","steps":["trace[400981324] 'range keys from in-memory index tree' (duration: 191.413925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:17.53469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.46287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-03T10:03:17.534949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.418123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-03T10:03:17.534981Z","caller":"traceutil/trace.go:171","msg":"trace[1484737329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25202; }","duration":"197.481615ms","start":"2026-10-03T10:03:17.337488Z","end":"2026-10-03T10:03:17.53497Z","steps":["trace[1484737329] 'range keys from in-memory index tree' (duration: 197.324361ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:17.534993Z","caller":"traceutil/trace.go:171","msg":"trace[166811036] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25202; }","duration":"189.864662ms","start":"2026-10-03T10:03:17.345103Z","end":"2026-10-03T10:03:17.534967Z","steps":["trace[166811036] 'range keys from in-memory index tree' (duration: 189.234394ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:21.274443Z","caller":"traceutil/trace.go:171","msg":"trace[1614586928] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"232.300783ms","start":"2026-10-03T10:03:21.042115Z","end":"2026-10-03T10:03:21.274416Z","steps":["trace[1614586928] 'process raft request' (duration: 154.824028ms)","trace[1614586928] 'compare' (duration: 77.376332ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:21.338158Z","caller":"traceutil/trace.go:171","msg":"trace[228891423] linearizableReadLoop","detail":"{readStateIndex:26066; appliedIndex:26064; }","duration":"189.207713ms","start":"2026-10-03T10:03:21.148919Z","end":"2026-10-03T10:03:21.338127Z","steps":["trace[228891423] 'read index received' (duration: 48.023919ms)","trace[228891423] 'applied index is now lower than readState.Index' (duration: 141.182394ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:21.338227Z","caller":"traceutil/trace.go:171","msg":"trace[620670382] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"286.958928ms","start":"2026-10-03T10:03:21.051231Z","end":"2026-10-03T10:03:21.33819Z","steps":["trace[620670382] 'process raft request' (duration: 286.746572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:21.338332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.388898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:21.338376Z","caller":"traceutil/trace.go:171","msg":"trace[63769650] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25247; }","duration":"189.496781ms","start":"2026-10-03T10:03:21.148866Z","end":"2026-10-03T10:03:21.338362Z","steps":["trace[63769650] 'agreement among raft nodes before linearized reading' (duration: 189.396708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:21.338386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.081515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:21.338466Z","caller":"traceutil/trace.go:171","msg":"trace[942696635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25247; }","duration":"103.215438ms","start":"2026-10-03T10:03:21.235231Z","end":"2026-10-03T10:03:21.338446Z","steps":["trace[942696635] 'agreement among raft nodes before linearized reading' (duration: 103.077824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:21.338562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.249719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-03T10:03:21.338651Z","caller":"traceutil/trace.go:171","msg":"trace[1207758297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25247; }","duration":"103.379093ms","start":"2026-10-03T10:03:21.235245Z","end":"2026-10-03T10:03:21.338624Z","steps":["trace[1207758297] 'agreement among raft nodes before linearized reading' (duration: 103.145386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:33.371325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.265786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:03:33.371464Z","caller":"traceutil/trace.go:171","msg":"trace[643717120] linearizableReadLoop","detail":"{readStateIndex:26169; appliedIndex:26168; }","duration":"317.99721ms","start":"2026-10-03T10:03:33.053434Z","end":"2026-10-03T10:03:33.371431Z","steps":["trace[643717120] 'read index received' (duration: 118.454157ms)","trace[643717120] 'applied index is now lower than readState.Index' (duration: 199.541473ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:33.371467Z","caller":"traceutil/trace.go:171","msg":"trace[1374989286] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"321.839607ms","start":"2026-10-03T10:03:33.049599Z","end":"2026-10-03T10:03:33.371438Z","steps":["trace[1374989286] 'process raft request' (duration: 122.347275ms)","trace[1374989286] 'compare' (duration: 199.04341ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:33.371614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.049577Z","time spent":"321.962061ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:33.371632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.206065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-10-03T10:03:33.371667Z","caller":"traceutil/trace.go:171","msg":"trace[1426984707] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25347; }","duration":"318.294028ms","start":"2026-10-03T10:03:33.05336Z","end":"2026-10-03T10:03:33.371654Z","steps":["trace[1426984707] 'agreement among raft nodes before linearized reading' (duration: 318.135164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:33.371726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.053335Z","time spent":"318.38341ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-10-03T10:03:33.444222Z","caller":"traceutil/trace.go:171","msg":"trace[638596317] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"126.762579ms","start":"2026-10-03T10:03:33.31743Z","end":"2026-10-03T10:03:33.444192Z","steps":["trace[638596317] 'process raft request' (duration: 126.600015ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:33.673456Z","caller":"traceutil/trace.go:171","msg":"trace[811904747] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"198.458983ms","start":"2026-10-03T10:03:33.47496Z","end":"2026-10-03T10:03:33.673419Z","steps":["trace[811904747] 'process raft request' (duration: 185.986153ms)","trace[811904747] 'compare' (duration: 11.843882ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:33.744077Z","caller":"traceutil/trace.go:171","msg":"trace[843758603] linearizableReadLoop","detail":"{readStateIndex:26173; appliedIndex:26171; }","duration":"112.678183ms","start":"2026-10-03T10:03:33.631376Z","end":"2026-10-03T10:03:33.744054Z","steps":["trace[843758603] 'read index received' (duration: 29.493558ms)","trace[843758603] 'applied index is now lower than readState.Index' (duration: 83.183525ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:33.744217Z","caller":"traceutil/trace.go:171","msg":"trace[1073248773] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"268.553631ms","start":"2026-10-03T10:03:33.475652Z","end":"2026-10-03T10:03:33.744206Z","steps":["trace[1073248773] 'process raft request' (duration: 268.260782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:33.744923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.492057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-03T10:03:33.74506Z","caller":"traceutil/trace.go:171","msg":"trace[1749770234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25351; }","duration":"113.716043ms","start":"2026-10-03T10:03:33.631321Z","end":"2026-10-03T10:03:33.745037Z","steps":["trace[1749770234] 'agreement among raft nodes before linearized reading' (duration: 113.424465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:33.745331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.320242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-03T10:03:33.745349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.31312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T10:03:33.745405Z","caller":"traceutil/trace.go:171","msg":"trace[605088577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25351; }","duration":"113.445636ms","start":"2026-10-03T10:03:33.631942Z","end":"2026-10-03T10:03:33.745387Z","steps":["trace[605088577] 'agreement among raft nodes before linearized reading' (duration: 113.277611ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:33.745414Z","caller":"traceutil/trace.go:171","msg":"trace[882672076] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25351; }","duration":"109.412292ms","start":"2026-10-03T10:03:33.635983Z","end":"2026-10-03T10:03:33.745395Z","steps":["trace[882672076] 'agreement among raft nodes before linearized reading' (duration: 109.194886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:33.746122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.125927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T10:03:33.746269Z","caller":"traceutil/trace.go:171","msg":"trace[1011835311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25351; }","duration":"114.789313ms","start":"2026-10-03T10:03:33.631461Z","end":"2026-10-03T10:03:33.74625Z","steps":["trace[1011835311] 'agreement among raft nodes before linearized reading' (duration: 112.980302ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:34.121328Z","caller":"traceutil/trace.go:171","msg":"trace[531906267] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"364.723872ms","start":"2026-10-03T10:03:33.756578Z","end":"2026-10-03T10:03:34.121301Z","steps":["trace[531906267] 'process raft request' (duration: 364.548907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.12149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.756534Z","time spent":"364.872026ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:34.157592Z","caller":"traceutil/trace.go:171","msg":"trace[644146772] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"400.744243ms","start":"2026-10-03T10:03:33.756822Z","end":"2026-10-03T10:03:34.157566Z","steps":["trace[644146772] 'process raft request' (duration: 400.659061ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:34.157624Z","caller":"traceutil/trace.go:171","msg":"trace[434931941] linearizableReadLoop","detail":"{readStateIndex:26177; appliedIndex:26173; }","duration":"398.608223ms","start":"2026-10-03T10:03:33.758997Z","end":"2026-10-03T10:03:34.157605Z","steps":["trace[434931941] 'read index received' (duration: 362.200251ms)","trace[434931941] 'applied index is now lower than readState.Index' (duration: 36.407012ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:34.15766Z","caller":"traceutil/trace.go:171","msg":"trace[1589239317] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"400.877867ms","start":"2026-10-03T10:03:33.756737Z","end":"2026-10-03T10:03:34.157615Z","steps":["trace[1589239317] 'process raft request' (duration: 380.095143ms)","trace[1589239317] 'compare' (duration: 20.441444ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:34.157774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.756817Z","time spent":"400.850766ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:34.157849Z","caller":"traceutil/trace.go:171","msg":"trace[1857702811] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"401.068022ms","start":"2026-10-03T10:03:33.756768Z","end":"2026-10-03T10:03:34.157836Z","steps":["trace[1857702811] 'process raft request' (duration: 400.64311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.157883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.907964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-03T10:03:34.157932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.756713Z","time spent":"401.059012ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:34.157942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.756748Z","time spent":"401.130664ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:34.157966Z","caller":"traceutil/trace.go:171","msg":"trace[1993733061] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25356; }","duration":"377.011217ms","start":"2026-10-03T10:03:33.780931Z","end":"2026-10-03T10:03:34.157943Z","steps":["trace[1993733061] 'agreement among raft nodes before linearized reading' (duration: 376.849402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.158024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.780871Z","time spent":"377.135271ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:03:34.15827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.765949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:34.158315Z","caller":"traceutil/trace.go:171","msg":"trace[735267631] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25356; }","duration":"335.845971ms","start":"2026-10-03T10:03:33.822453Z","end":"2026-10-03T10:03:34.158299Z","steps":["trace[735267631] 'agreement among raft nodes before linearized reading' (duration: 335.740688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.158348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.822437Z","time spent":"335.902982ms","remote":"127.0.0.1:38378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:03:34.158357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.350974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19190"} {"level":"info","ts":"2026-10-03T10:03:34.158425Z","caller":"traceutil/trace.go:171","msg":"trace[1198632420] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25356; }","duration":"399.420596ms","start":"2026-10-03T10:03:33.75899Z","end":"2026-10-03T10:03:34.15841Z","steps":["trace[1198632420] 'agreement among raft nodes before linearized reading' (duration: 399.226931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.158463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:33.758943Z","time spent":"399.512258ms","remote":"127.0.0.1:44536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19213,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-03T10:03:34.64266Z","caller":"traceutil/trace.go:171","msg":"trace[305975794] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"327.195887ms","start":"2026-10-03T10:03:34.315437Z","end":"2026-10-03T10:03:34.642633Z","steps":["trace[305975794] 'process raft request' (duration: 326.922279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.642842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:34.315413Z","time spent":"327.34108ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T10:03:34.922644Z","caller":"traceutil/trace.go:171","msg":"trace[1802945197] linearizableReadLoop","detail":"{readStateIndex:26180; appliedIndex:26179; }","duration":"460.611943ms","start":"2026-10-03T10:03:34.462006Z","end":"2026-10-03T10:03:34.922618Z","steps":["trace[1802945197] 'read index received' (duration: 180.660062ms)","trace[1802945197] 'applied index is now lower than readState.Index' (duration: 279.950531ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:34.922825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.802578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:34.922805Z","caller":"traceutil/trace.go:171","msg":"trace[1479171010] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"605.843032ms","start":"2026-10-03T10:03:34.316897Z","end":"2026-10-03T10:03:34.92274Z","steps":["trace[1479171010] 'process raft request' (duration: 569.543923ms)","trace[1479171010] 'compare' (duration: 36.066002ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:34.922864Z","caller":"traceutil/trace.go:171","msg":"trace[1310490110] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25358; }","duration":"460.908422ms","start":"2026-10-03T10:03:34.461945Z","end":"2026-10-03T10:03:34.922853Z","steps":["trace[1310490110] 'agreement among raft nodes before linearized reading' (duration: 460.809949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.922941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:34.46192Z","time spent":"461.010664ms","remote":"127.0.0.1:49364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-10-03T10:03:34.923069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:34.316874Z","time spent":"606.056167ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:34.933272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.271343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:03:34.933332Z","caller":"traceutil/trace.go:171","msg":"trace[1993661640] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25359; }","duration":"450.375016ms","start":"2026-10-03T10:03:34.482943Z","end":"2026-10-03T10:03:34.933318Z","steps":["trace[1993661640] 'agreement among raft nodes before linearized reading' (duration: 450.243492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.933375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:34.482925Z","time spent":"450.436817ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-10-03T10:03:34.933378Z","caller":"traceutil/trace.go:171","msg":"trace[1989304] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"280.317262ms","start":"2026-10-03T10:03:34.653035Z","end":"2026-10-03T10:03:34.933352Z","steps":["trace[1989304] 'process raft request' (duration: 280.018314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.93356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.274245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T10:03:34.933614Z","caller":"traceutil/trace.go:171","msg":"trace[2087179948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25359; }","duration":"402.369698ms","start":"2026-10-03T10:03:34.531231Z","end":"2026-10-03T10:03:34.933601Z","steps":["trace[2087179948] 'agreement among raft nodes before linearized reading' (duration: 402.196443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:34.933646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:34.531213Z","time spent":"402.425969ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-03T10:03:38.311638Z","caller":"traceutil/trace.go:171","msg":"trace[1876523047] linearizableReadLoop","detail":"{readStateIndex:26210; appliedIndex:26209; }","duration":"140.593538ms","start":"2026-10-03T10:03:38.171022Z","end":"2026-10-03T10:03:38.311616Z","steps":["trace[1876523047] 'read index received' (duration: 121.678197ms)","trace[1876523047] 'applied index is now lower than readState.Index' (duration: 18.914441ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:38.311752Z","caller":"traceutil/trace.go:171","msg":"trace[1009966643] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"140.775903ms","start":"2026-10-03T10:03:38.170943Z","end":"2026-10-03T10:03:38.311719Z","steps":["trace[1009966643] 'process raft request' (duration: 121.863452ms)","trace[1009966643] 'compare' (duration: 18.697825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:38.311889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.602859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-03T10:03:38.311924Z","caller":"traceutil/trace.go:171","msg":"trace[2121616564] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25387; }","duration":"134.685372ms","start":"2026-10-03T10:03:38.177227Z","end":"2026-10-03T10:03:38.311913Z","steps":["trace[2121616564] 'agreement among raft nodes before linearized reading' (duration: 134.526858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:38.311934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.879846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-03T10:03:38.312013Z","caller":"traceutil/trace.go:171","msg":"trace[31424136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25387; }","duration":"141.017659ms","start":"2026-10-03T10:03:38.170975Z","end":"2026-10-03T10:03:38.311992Z","steps":["trace[31424136] 'agreement among raft nodes before linearized reading' (duration: 140.765912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:38.312319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.2809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-03T10:03:38.312382Z","caller":"traceutil/trace.go:171","msg":"trace[1309765214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25387; }","duration":"134.354923ms","start":"2026-10-03T10:03:38.178012Z","end":"2026-10-03T10:03:38.312367Z","steps":["trace[1309765214] 'agreement among raft nodes before linearized reading' (duration: 133.789387ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:38.610438Z","caller":"traceutil/trace.go:171","msg":"trace[432477777] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"294.655224ms","start":"2026-10-03T10:03:38.315742Z","end":"2026-10-03T10:03:38.610397Z","steps":["trace[432477777] 'process raft request' (duration: 222.062455ms)","trace[432477777] 'compare' (duration: 72.047664ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:38.619725Z","caller":"traceutil/trace.go:171","msg":"trace[198421029] linearizableReadLoop","detail":"{readStateIndex:26213; appliedIndex:26210; }","duration":"237.975052ms","start":"2026-10-03T10:03:38.381723Z","end":"2026-10-03T10:03:38.619698Z","steps":["trace[198421029] 'read index received' (duration: 156.277828ms)","trace[198421029] 'applied index is now lower than readState.Index' (duration: 81.695494ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:38.619804Z","caller":"traceutil/trace.go:171","msg":"trace[1838216428] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"303.733719ms","start":"2026-10-03T10:03:38.316045Z","end":"2026-10-03T10:03:38.619779Z","steps":["trace[1838216428] 'process raft request' (duration: 303.443541ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:38.61984Z","caller":"traceutil/trace.go:171","msg":"trace[1636571094] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"302.017361ms","start":"2026-10-03T10:03:38.317812Z","end":"2026-10-03T10:03:38.619829Z","steps":["trace[1636571094] 'process raft request' (duration: 301.824695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:38.619952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:38.317797Z","time spent":"302.069693ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:38.619958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T10:03:38.316033Z","time spent":"303.837502ms","remote":"127.0.0.1:44610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T10:03:38.620055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.327083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-10-03T10:03:38.620108Z","caller":"traceutil/trace.go:171","msg":"trace[2084413473] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25390; }","duration":"238.448486ms","start":"2026-10-03T10:03:38.381642Z","end":"2026-10-03T10:03:38.620091Z","steps":["trace[2084413473] 'agreement among raft nodes before linearized reading' (duration: 238.22294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:38.620551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.635706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:03:38.620596Z","caller":"traceutil/trace.go:171","msg":"trace[1336089581] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25390; }","duration":"147.743169ms","start":"2026-10-03T10:03:38.472843Z","end":"2026-10-03T10:03:38.620586Z","steps":["trace[1336089581] 'agreement among raft nodes before linearized reading' (duration: 147.610775ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:40.118094Z","caller":"traceutil/trace.go:171","msg":"trace[111018635] linearizableReadLoop","detail":"{readStateIndex:26222; appliedIndex:26221; }","duration":"132.986784ms","start":"2026-10-03T10:03:39.98508Z","end":"2026-10-03T10:03:40.118066Z","steps":["trace[111018635] 'read index received' (duration: 132.761508ms)","trace[111018635] 'applied index is now lower than readState.Index' (duration: 224.476µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:40.118145Z","caller":"traceutil/trace.go:171","msg":"trace[636155837] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"136.184724ms","start":"2026-10-03T10:03:39.981934Z","end":"2026-10-03T10:03:40.118119Z","steps":["trace[636155837] 'process raft request' (duration: 135.978478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:40.118315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.20359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-03T10:03:40.11835Z","caller":"traceutil/trace.go:171","msg":"trace[1521755772] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25399; }","duration":"133.282593ms","start":"2026-10-03T10:03:39.985054Z","end":"2026-10-03T10:03:40.118337Z","steps":["trace[1521755772] 'agreement among raft nodes before linearized reading' (duration: 133.119728ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:41.29226Z","caller":"traceutil/trace.go:171","msg":"trace[2031732772] transaction","detail":"{read_only:false; response_revision:25420; number_of_response:1; }","duration":"101.394088ms","start":"2026-10-03T10:03:41.190828Z","end":"2026-10-03T10:03:41.292222Z","steps":["trace[2031732772] 'process raft request' (duration: 101.032317ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:44.445141Z","caller":"traceutil/trace.go:171","msg":"trace[659033318] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"113.846427ms","start":"2026-10-03T10:03:44.331271Z","end":"2026-10-03T10:03:44.445117Z","steps":["trace[659033318] 'process raft request' (duration: 113.638111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:47.960479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.811595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-03T10:03:47.960592Z","caller":"traceutil/trace.go:171","msg":"trace[1694206554] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25471; }","duration":"119.981809ms","start":"2026-10-03T10:03:47.840582Z","end":"2026-10-03T10:03:47.960564Z","steps":["trace[1694206554] 'count revisions from in-memory index tree' (duration: 119.716192ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:49.707013Z","caller":"traceutil/trace.go:171","msg":"trace[2030008139] linearizableReadLoop","detail":"{readStateIndex:26311; appliedIndex:26310; }","duration":"155.472176ms","start":"2026-10-03T10:03:49.551512Z","end":"2026-10-03T10:03:49.706985Z","steps":["trace[2030008139] 'read index received' (duration: 116.221754ms)","trace[2030008139] 'applied index is now lower than readState.Index' (duration: 39.249212ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:49.707042Z","caller":"traceutil/trace.go:171","msg":"trace[1321657863] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"187.210437ms","start":"2026-10-03T10:03:49.519799Z","end":"2026-10-03T10:03:49.707009Z","steps":["trace[1321657863] 'process raft request' (duration: 148.059227ms)","trace[1321657863] 'compare' (duration: 38.860702ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:03:49.707272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.736753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-03T10:03:49.707353Z","caller":"traceutil/trace.go:171","msg":"trace[766203300] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25486; }","duration":"155.852107ms","start":"2026-10-03T10:03:49.551483Z","end":"2026-10-03T10:03:49.707335Z","steps":["trace[766203300] 'agreement among raft nodes before linearized reading' (duration: 155.6213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:51.382973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.13073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-03T10:03:51.38306Z","caller":"traceutil/trace.go:171","msg":"trace[1956426735] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25498; }","duration":"148.284584ms","start":"2026-10-03T10:03:51.234753Z","end":"2026-10-03T10:03:51.383038Z","steps":["trace[1956426735] 'range keys from in-memory index tree' (duration: 147.887892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:51.383055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.846162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:51.383167Z","caller":"traceutil/trace.go:171","msg":"trace[780647831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25498; }","duration":"148.025197ms","start":"2026-10-03T10:03:51.235117Z","end":"2026-10-03T10:03:51.383142Z","steps":["trace[780647831] 'range keys from in-memory index tree' (duration: 147.745989ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:51.516041Z","caller":"traceutil/trace.go:171","msg":"trace[1459179484] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"101.654824ms","start":"2026-10-03T10:03:51.414347Z","end":"2026-10-03T10:03:51.516002Z","steps":["trace[1459179484] 'process raft request' (duration: 101.424548ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:51.65992Z","caller":"traceutil/trace.go:171","msg":"trace[612435090] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"126.671757ms","start":"2026-10-03T10:03:51.533215Z","end":"2026-10-03T10:03:51.659886Z","steps":["trace[612435090] 'process raft request' (duration: 126.42136ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:53.961148Z","caller":"traceutil/trace.go:171","msg":"trace[1659739440] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"167.865724ms","start":"2026-10-03T10:03:53.793247Z","end":"2026-10-03T10:03:53.961113Z","steps":["trace[1659739440] 'process raft request' (duration: 118.277801ms)","trace[1659739440] 'compare' (duration: 49.328096ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:54.627665Z","caller":"traceutil/trace.go:171","msg":"trace[821529876] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"102.89319ms","start":"2026-10-03T10:03:54.524751Z","end":"2026-10-03T10:03:54.627644Z","steps":["trace[821529876] 'process raft request' (duration: 102.742796ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:54.910427Z","caller":"traceutil/trace.go:171","msg":"trace[838851718] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"167.972787ms","start":"2026-10-03T10:03:54.742424Z","end":"2026-10-03T10:03:54.910397Z","steps":["trace[838851718] 'process raft request' (duration: 167.898395ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:03:54.910502Z","caller":"traceutil/trace.go:171","msg":"trace[905665010] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"170.019525ms","start":"2026-10-03T10:03:54.740386Z","end":"2026-10-03T10:03:54.910406Z","steps":["trace[905665010] 'process raft request' (duration: 114.906207ms)","trace[905665010] 'compare' (duration: 54.874001ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:55.128737Z","caller":"traceutil/trace.go:171","msg":"trace[1878550599] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"140.124425ms","start":"2026-10-03T10:03:54.988549Z","end":"2026-10-03T10:03:55.128673Z","steps":["trace[1878550599] 'process raft request' (duration: 102.680583ms)","trace[1878550599] 'compare' (duration: 37.252467ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:55.128734Z","caller":"traceutil/trace.go:171","msg":"trace[802462603] linearizableReadLoop","detail":"{readStateIndex:26349; appliedIndex:26348; }","duration":"135.262188ms","start":"2026-10-03T10:03:54.993446Z","end":"2026-10-03T10:03:55.128708Z","steps":["trace[802462603] 'read index received' (duration: 97.798866ms)","trace[802462603] 'applied index is now lower than readState.Index' (duration: 37.461122ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:03:55.128741Z","caller":"traceutil/trace.go:171","msg":"trace[323638986] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"127.922982ms","start":"2026-10-03T10:03:55.00079Z","end":"2026-10-03T10:03:55.128713Z","steps":["trace[323638986] 'process raft request' (duration: 127.803749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:55.128965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.502155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-03T10:03:55.129016Z","caller":"traceutil/trace.go:171","msg":"trace[368844885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25525; }","duration":"135.613328ms","start":"2026-10-03T10:03:54.993389Z","end":"2026-10-03T10:03:55.129002Z","steps":["trace[368844885] 'agreement among raft nodes before linearized reading' (duration: 135.408472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:55.129316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.42371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-03T10:03:55.129416Z","caller":"traceutil/trace.go:171","msg":"trace[426032460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25525; }","duration":"100.601335ms","start":"2026-10-03T10:03:55.028789Z","end":"2026-10-03T10:03:55.12939Z","steps":["trace[426032460] 'agreement among raft nodes before linearized reading' (duration: 100.249345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:03:55.334636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.780885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:03:55.334745Z","caller":"traceutil/trace.go:171","msg":"trace[162175946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25526; }","duration":"103.953629ms","start":"2026-10-03T10:03:55.230772Z","end":"2026-10-03T10:03:55.334725Z","steps":["trace[162175946] 'range keys from in-memory index tree' (duration: 103.673841ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:10.808113Z","caller":"traceutil/trace.go:171","msg":"trace[583707323] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"112.325264ms","start":"2026-10-03T10:04:10.695762Z","end":"2026-10-03T10:04:10.808087Z","steps":["trace[583707323] 'process raft request' (duration: 112.150829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:11.019579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.279848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:04:11.019765Z","caller":"traceutil/trace.go:171","msg":"trace[1963246504] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"172.491124ms","start":"2026-10-03T10:04:10.847254Z","end":"2026-10-03T10:04:11.019745Z","steps":["trace[1963246504] 'process raft request' (duration: 67.933758ms)","trace[1963246504] 'compare' (duration: 104.125234ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:11.211752Z","caller":"traceutil/trace.go:171","msg":"trace[1712295652] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"186.553599ms","start":"2026-10-03T10:04:11.025122Z","end":"2026-10-03T10:04:11.211676Z","steps":["trace[1712295652] 'process raft request' (duration: 112.320185ms)","trace[1712295652] 'compare' (duration: 73.924215ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:11.367646Z","caller":"traceutil/trace.go:171","msg":"trace[2031713547] linearizableReadLoop","detail":"{readStateIndex:26469; appliedIndex:26468; }","duration":"134.795535ms","start":"2026-10-03T10:04:11.23282Z","end":"2026-10-03T10:04:11.367615Z","steps":["trace[2031713547] 'read index received' (duration: 134.640071ms)","trace[2031713547] 'applied index is now lower than readState.Index' (duration: 154.134µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:04:11.367829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.9821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:04:11.367717Z","caller":"traceutil/trace.go:171","msg":"trace[1626491977] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"142.331837ms","start":"2026-10-03T10:04:11.22533Z","end":"2026-10-03T10:04:11.367662Z","steps":["trace[1626491977] 'process raft request' (duration: 142.07814ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:11.367913Z","caller":"traceutil/trace.go:171","msg":"trace[1900840924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25640; }","duration":"135.125155ms","start":"2026-10-03T10:04:11.232766Z","end":"2026-10-03T10:04:11.367892Z","steps":["trace[1900840924] 'agreement among raft nodes before linearized reading' (duration: 134.96747ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:11.383529Z","caller":"traceutil/trace.go:171","msg":"trace[207292379] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"149.825547ms","start":"2026-10-03T10:04:11.233678Z","end":"2026-10-03T10:04:11.383504Z","steps":["trace[207292379] 'process raft request' (duration: 149.644782ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:11.383831Z","caller":"traceutil/trace.go:171","msg":"trace[122934631] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"149.876189ms","start":"2026-10-03T10:04:11.233902Z","end":"2026-10-03T10:04:11.383779Z","steps":["trace[122934631] 'process raft request' (duration: 149.55897ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:11.907356Z","caller":"traceutil/trace.go:171","msg":"trace[1872564246] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"155.316821ms","start":"2026-10-03T10:04:11.75201Z","end":"2026-10-03T10:04:11.907327Z","steps":["trace[1872564246] 'process raft request' (duration: 155.077065ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:11.966177Z","caller":"traceutil/trace.go:171","msg":"trace[503165210] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"110.366769ms","start":"2026-10-03T10:04:11.855784Z","end":"2026-10-03T10:04:11.96615Z","steps":["trace[503165210] 'process raft request' (duration: 110.232255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:12.206067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.42914ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-03T10:04:12.20643Z","caller":"traceutil/trace.go:171","msg":"trace[860628666] linearizableReadLoop","detail":"{readStateIndex:26477; appliedIndex:26475; }","duration":"205.523532ms","start":"2026-10-03T10:04:12.000885Z","end":"2026-10-03T10:04:12.206409Z","steps":["trace[860628666] 'read index received' (duration: 46.453055ms)","trace[860628666] 'applied index is now lower than readState.Index' (duration: 159.069437ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:12.206572Z","caller":"traceutil/trace.go:171","msg":"trace[2017957116] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"225.958135ms","start":"2026-10-03T10:04:11.980601Z","end":"2026-10-03T10:04:12.206559Z","steps":["trace[2017957116] 'process raft request' (duration: 225.573024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:12.206711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.445443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-03T10:04:12.206746Z","caller":"traceutil/trace.go:171","msg":"trace[1471972861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25647; }","duration":"161.530606ms","start":"2026-10-03T10:04:12.045205Z","end":"2026-10-03T10:04:12.206736Z","steps":["trace[1471972861] 'agreement among raft nodes before linearized reading' (duration: 161.375031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:12.206843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.954143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:04:12.206882Z","caller":"traceutil/trace.go:171","msg":"trace[695924702] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25647; }","duration":"205.994334ms","start":"2026-10-03T10:04:12.000875Z","end":"2026-10-03T10:04:12.206869Z","steps":["trace[695924702] 'agreement among raft nodes before linearized reading' (duration: 205.933723ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:29.974847Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006842.wal"} {"level":"warn","ts":"2026-10-03T10:04:33.743546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.808578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:04:33.743744Z","caller":"traceutil/trace.go:171","msg":"trace[767610378] linearizableReadLoop","detail":"{readStateIndex:26792; appliedIndex:26790; }","duration":"182.850035ms","start":"2026-10-03T10:04:33.560878Z","end":"2026-10-03T10:04:33.743728Z","steps":["trace[767610378] 'read index received' (duration: 33.79672ms)","trace[767610378] 'applied index is now lower than readState.Index' (duration: 149.048805ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:33.743852Z","caller":"traceutil/trace.go:171","msg":"trace[390007219] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"205.738317ms","start":"2026-10-03T10:04:33.538072Z","end":"2026-10-03T10:04:33.74381Z","steps":["trace[390007219] 'process raft request' (duration: 205.574532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:33.743925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.04316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T10:04:33.74396Z","caller":"traceutil/trace.go:171","msg":"trace[431286014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25956; }","duration":"183.103381ms","start":"2026-10-03T10:04:33.560848Z","end":"2026-10-03T10:04:33.743951Z","steps":["trace[431286014] 'agreement among raft nodes before linearized reading' (duration: 182.954687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:33.744195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.890541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T10:04:33.744183Z","caller":"traceutil/trace.go:171","msg":"trace[98662996] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"273.777198ms","start":"2026-10-03T10:04:33.470373Z","end":"2026-10-03T10:04:33.74415Z","steps":["trace[98662996] 'process raft request' (duration: 124.28029ms)","trace[98662996] 'compare' (duration: 148.447668ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:33.744254Z","caller":"traceutil/trace.go:171","msg":"trace[954051917] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25956; }","duration":"153.997984ms","start":"2026-10-03T10:04:33.590239Z","end":"2026-10-03T10:04:33.744237Z","steps":["trace[954051917] 'agreement among raft nodes before linearized reading' (duration: 153.598423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:34.078138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.963064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T10:04:34.078253Z","caller":"traceutil/trace.go:171","msg":"trace[1666985353] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"277.7814ms","start":"2026-10-03T10:04:33.800452Z","end":"2026-10-03T10:04:34.078234Z","steps":["trace[1666985353] 'process raft request' (duration: 134.60921ms)","trace[1666985353] 'compare' (duration: 142.550673ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:34.114264Z","caller":"traceutil/trace.go:171","msg":"trace[76588990] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"122.293834ms","start":"2026-10-03T10:04:33.991946Z","end":"2026-10-03T10:04:34.11424Z","steps":["trace[76588990] 'process raft request' (duration: 122.15765ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:34.326767Z","caller":"traceutil/trace.go:171","msg":"trace[1690969588] linearizableReadLoop","detail":"{readStateIndex:26796; appliedIndex:26795; }","duration":"191.951791ms","start":"2026-10-03T10:04:34.134793Z","end":"2026-10-03T10:04:34.326745Z","steps":["trace[1690969588] 'read index received' (duration: 191.696353ms)","trace[1690969588] 'applied index is now lower than readState.Index' (duration: 254.477µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:34.326833Z","caller":"traceutil/trace.go:171","msg":"trace[1424915514] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"283.232403ms","start":"2026-10-03T10:04:34.043571Z","end":"2026-10-03T10:04:34.326803Z","steps":["trace[1424915514] 'process raft request' (duration: 282.984856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:34.326979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.167906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:04:34.327029Z","caller":"traceutil/trace.go:171","msg":"trace[558322081] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25960; }","duration":"192.236378ms","start":"2026-10-03T10:04:34.134777Z","end":"2026-10-03T10:04:34.327014Z","steps":["trace[558322081] 'agreement among raft nodes before linearized reading' (duration: 192.106654ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:34.449683Z","caller":"traceutil/trace.go:171","msg":"trace[1520924892] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"107.556691ms","start":"2026-10-03T10:04:34.34209Z","end":"2026-10-03T10:04:34.449646Z","steps":["trace[1520924892] 'process raft request' (duration: 16.425951ms)","trace[1520924892] 'compare' (duration: 90.998366ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:34.82275Z","caller":"traceutil/trace.go:171","msg":"trace[1631182099] linearizableReadLoop","detail":"{readStateIndex:26798; appliedIndex:26797; }","duration":"206.613291ms","start":"2026-10-03T10:04:34.616059Z","end":"2026-10-03T10:04:34.822672Z","steps":["trace[1631182099] 'read index received' (duration: 143.049197ms)","trace[1631182099] 'applied index is now lower than readState.Index' (duration: 63.559874ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:34.82288Z","caller":"traceutil/trace.go:171","msg":"trace[2096701195] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"298.7715ms","start":"2026-10-03T10:04:34.524095Z","end":"2026-10-03T10:04:34.822867Z","steps":["trace[2096701195] 'process raft request' (duration: 235.004709ms)","trace[2096701195] 'compare' (duration: 63.449612ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T10:04:34.823044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.146161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T10:04:34.823037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.883419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:04:34.823101Z","caller":"traceutil/trace.go:171","msg":"trace[1117685221] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25962; }","duration":"205.235343ms","start":"2026-10-03T10:04:34.61785Z","end":"2026-10-03T10:04:34.823086Z","steps":["trace[1117685221] 'agreement among raft nodes before linearized reading' (duration: 205.12614ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:34.823109Z","caller":"traceutil/trace.go:171","msg":"trace[2118331211] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25962; }","duration":"206.978192ms","start":"2026-10-03T10:04:34.616113Z","end":"2026-10-03T10:04:34.823091Z","steps":["trace[2118331211] 'agreement among raft nodes before linearized reading' (duration: 206.858799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:34.82308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.003022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:04:34.823164Z","caller":"traceutil/trace.go:171","msg":"trace[218219506] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:25962; }","duration":"207.140537ms","start":"2026-10-03T10:04:34.616004Z","end":"2026-10-03T10:04:34.823145Z","steps":["trace[218219506] 'agreement among raft nodes before linearized reading' (duration: 207.000393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T10:04:34.823237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.743287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T10:04:34.823261Z","caller":"traceutil/trace.go:171","msg":"trace[1956353044] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25962; }","duration":"205.784349ms","start":"2026-10-03T10:04:34.617469Z","end":"2026-10-03T10:04:34.823253Z","steps":["trace[1956353044] 'agreement among raft nodes before linearized reading' (duration: 205.748458ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:35.145454Z","caller":"traceutil/trace.go:171","msg":"trace[1029710013] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"214.625097ms","start":"2026-10-03T10:04:34.930808Z","end":"2026-10-03T10:04:35.145433Z","steps":["trace[1029710013] 'process raft request' (duration: 214.520894ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:04:35.145505Z","caller":"traceutil/trace.go:171","msg":"trace[608717351] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"247.046677ms","start":"2026-10-03T10:04:34.898387Z","end":"2026-10-03T10:04:35.145433Z","steps":["trace[608717351] 'process raft request' (duration: 157.187004ms)","trace[608717351] 'compare' (duration: 89.474212ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T10:04:43.157671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22902} {"level":"info","ts":"2026-10-03T10:04:43.304885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22902,"took":"142.189853ms","hash":492308421,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":38748160,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-10-03T10:04:43.305028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":492308421,"revision":22902,"compact-revision":20132} {"level":"info","ts":"2026-10-03T10:06:05.433661Z","caller":"traceutil/trace.go:171","msg":"trace[2111383441] transaction","detail":"{read_only:false; response_revision:26824; number_of_response:1; }","duration":"101.371346ms","start":"2026-10-03T10:06:05.332249Z","end":"2026-10-03T10:06:05.43362Z","steps":["trace[2111383441] 'process raft request' (duration: 101.074648ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:06:29.614568Z","caller":"traceutil/trace.go:171","msg":"trace[1367092191] transaction","detail":"{read_only:false; response_revision:27026; number_of_response:1; }","duration":"103.301311ms","start":"2026-10-03T10:06:29.511229Z","end":"2026-10-03T10:06:29.61453Z","steps":["trace[1367092191] 'process raft request' (duration: 103.053834ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T10:09:43.16409Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26051} {"level":"info","ts":"2026-10-03T10:09:43.329657Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26051,"took":"161.438171ms","hash":4071158895,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":35741696,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-03T10:09:43.329726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4071158895,"revision":26051,"compact-revision":22902} {"level":"info","ts":"2026-10-03T10:10:19.323114Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68b29b9453c9d66d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T10:10:19.331903Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-03T10:10:19.33202Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-03T10:14:43.175344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28785} {"level":"info","ts":"2026-10-03T10:14:43.32778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28785,"took":"148.033585ms","hash":1444817619,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":31064064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T10:14:43.327848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1444817619,"revision":28785,"compact-revision":26051} {"level":"info","ts":"2026-10-03T10:19:43.182795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31003} {"level":"info","ts":"2026-10-03T10:19:43.315789Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31003,"took":"129.037001ms","hash":1540031740,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T10:19:43.315962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1540031740,"revision":31003,"compact-revision":28785} {"level":"info","ts":"2026-10-03T10:24:43.191379Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33266} {"level":"info","ts":"2026-10-03T10:24:43.368487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33266,"took":"168.953201ms","hash":2406805963,"current-db-size-bytes":57999360,"current-db-size":"58 MB","current-db-size-in-use-bytes":31707136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T10:24:43.368581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2406805963,"revision":33266,"compact-revision":31003} {"level":"info","ts":"2026-10-03T10:29:43.199074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35557} {"level":"info","ts":"2026-10-03T10:29:43.351683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35557,"took":"147.322095ms","hash":34488242,"current-db-size-bytes":59736064,"current-db-size":"60 MB","current-db-size-in-use-bytes":31952896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T10:29:43.351771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":34488242,"revision":35557,"compact-revision":33266} {"level":"info","ts":"2026-10-03T10:31:50.487926Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68b29b9453c9d66d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T10:31:50.495067Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-03T10:31:50.495207Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-03T10:34:43.207228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37785} {"level":"info","ts":"2026-10-03T10:34:43.335799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37785,"took":"123.316402ms","hash":3382460690,"current-db-size-bytes":61857792,"current-db-size":"62 MB","current-db-size-in-use-bytes":32731136,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T10:34:43.335874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3382460690,"revision":37785,"compact-revision":35557} {"level":"info","ts":"2026-10-03T10:39:43.214177Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40066} {"level":"info","ts":"2026-10-03T10:39:43.350206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40066,"took":"130.149664ms","hash":2150001618,"current-db-size-bytes":61857792,"current-db-size":"62 MB","current-db-size-in-use-bytes":30814208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T10:39:43.350408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2150001618,"revision":40066,"compact-revision":37785}