{"level":"warn","ts":"2026-05-09T16:55:57.861003Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:55:57.861108Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-05-09T16:55:57.861186Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:55:57.861204Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-09T16:55:57.861228Z","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-05-09T16:55:57.861981Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-09T16:55:57.862064Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-05-09T16:55:57.865769Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.464833ms"} {"level":"info","ts":"2026-05-09T16:55:57.872611Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-09T16:55:57.872702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T16:55:57.872737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-09T16:55:57.872754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T16:55:57.872773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-09T16:55:57.872819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-09T16:55:57.880012Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T16:55:57.881567Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T16:55:57.882907Z","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-05-09T16:55:57.885576Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T16:55:57.885781Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T16:55:57.886118Z","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-05-09T16:55:57.886339Z","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-05-09T16:55:57.886377Z","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-05-09T16:55:57.889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-09T16:55:57.890134Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-09T16:55:57.892123Z","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-05-09T16:55:57.89225Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-09T16:55:57.892294Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-09T16:55:57.892502Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T16:55:57.892569Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T16:55:57.973433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T16:55:57.973553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T16:55:57.973617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-09T16:55:57.973663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-09T16:55:57.973691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-09T16:55:57.97372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-09T16:55:57.97375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-09T16:55:57.974528Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:55:57.975518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:55:57.975561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:55:57.975524Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T16:55:57.97587Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T16:55:57.975948Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T16:55:57.976266Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:55:57.976382Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:55:57.976432Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:55:57.977993Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T16:55:57.991008Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-05-09T16:56:28.958754Z","caller":"traceutil/trace.go:171","msg":"trace[1495485324] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"109.594666ms","start":"2026-05-09T16:56:28.849126Z","end":"2026-05-09T16:56:28.958721Z","steps":["trace[1495485324] 'process raft request' (duration: 97.489775ms)","trace[1495485324] 'compare' (duration: 11.977798ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:56:35.101069Z","caller":"traceutil/trace.go:171","msg":"trace[975556091] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"127.341223ms","start":"2026-05-09T16:56:34.9737Z","end":"2026-05-09T16:56:35.101041Z","steps":["trace[975556091] 'process raft request' (duration: 125.54512ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:56:35.359528Z","caller":"traceutil/trace.go:171","msg":"trace[459703784] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"194.450716ms","start":"2026-05-09T16:56:35.165038Z","end":"2026-05-09T16:56:35.359489Z","steps":["trace[459703784] 'process raft request' (duration: 174.397284ms)","trace[459703784] 'compare' (duration: 19.787936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:56:35.863498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.145226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2jcbw\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-09T16:56:35.863694Z","caller":"traceutil/trace.go:171","msg":"trace[582535301] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2jcbw; range_end:; response_count:1; response_revision:623; }","duration":"398.382212ms","start":"2026-05-09T16:56:35.465291Z","end":"2026-05-09T16:56:35.863673Z","steps":["trace[582535301] 'range keys from in-memory index tree' (duration: 397.988231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:56:35.863735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:56:35.465264Z","time spent":"398.461733ms","remote":"127.0.0.1:59402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-2jcbw\" "} {"level":"info","ts":"2026-05-09T16:56:35.916087Z","caller":"traceutil/trace.go:171","msg":"trace[38019526] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"118.44331ms","start":"2026-05-09T16:56:35.797617Z","end":"2026-05-09T16:56:35.91606Z","steps":["trace[38019526] 'process raft request' (duration: 117.870826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.929798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.157335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:58:08.929952Z","caller":"traceutil/trace.go:171","msg":"trace[162066308] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"342.442785ms","start":"2026-05-09T16:58:08.587488Z","end":"2026-05-09T16:58:08.929931Z","steps":["trace[162066308] 'read index received' (duration: 106.45392ms)","trace[162066308] 'applied index is now lower than readState.Index' (duration: 235.987375ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:58:08.930008Z","caller":"traceutil/trace.go:171","msg":"trace[1064431073] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"399.557199ms","start":"2026-05-09T16:58:08.530384Z","end":"2026-05-09T16:58:08.929941Z","steps":["trace[1064431073] 'process raft request' (duration: 163.558324ms)","trace[1064431073] 'compare' (duration: 234.975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:08.930183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.692942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-09T16:58:08.930251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.305659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aae81b36-1f3e-45c1-9c15-f7e0832e5152\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-09T16:58:08.930277Z","caller":"traceutil/trace.go:171","msg":"trace[1242059300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1864; }","duration":"342.824055ms","start":"2026-05-09T16:58:08.587438Z","end":"2026-05-09T16:58:08.930262Z","steps":["trace[1242059300] 'agreement among raft nodes before linearized reading' (duration: 342.60299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.930267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:08.530352Z","time spent":"399.756433ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:58:08.930316Z","caller":"traceutil/trace.go:171","msg":"trace[1017318547] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aae81b36-1f3e-45c1-9c15-f7e0832e5152; range_end:; response_count:1; response_revision:1864; }","duration":"309.458263ms","start":"2026-05-09T16:58:08.62084Z","end":"2026-05-09T16:58:08.930298Z","steps":["trace[1017318547] 'agreement among raft nodes before linearized reading' (duration: 309.250608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.930308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.730483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-09T16:58:08.93036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:08.620817Z","time spent":"309.530664ms","remote":"127.0.0.1:59402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aae81b36-1f3e-45c1-9c15-f7e0832e5152\" "} {"level":"info","ts":"2026-05-09T16:58:08.930405Z","caller":"traceutil/trace.go:171","msg":"trace[120004572] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1864; }","duration":"167.884937ms","start":"2026-05-09T16:58:08.762499Z","end":"2026-05-09T16:58:08.930384Z","steps":["trace[120004572] 'agreement among raft nodes before linearized reading' (duration: 167.59641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:08.930336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:58:08.587417Z","time spent":"342.904546ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T16:58:09.099278Z","caller":"traceutil/trace.go:171","msg":"trace[1749228515] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1938; }","duration":"132.774663ms","start":"2026-05-09T16:58:08.966481Z","end":"2026-05-09T16:58:09.099256Z","steps":["trace[1749228515] 'read index received' (duration: 12.395538ms)","trace[1749228515] 'applied index is now lower than readState.Index' (duration: 120.375625ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:58:09.099427Z","caller":"traceutil/trace.go:171","msg":"trace[1186674371] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"164.29839ms","start":"2026-05-09T16:58:08.935111Z","end":"2026-05-09T16:58:09.099409Z","steps":["trace[1186674371] 'process raft request' (duration: 93.59589ms)","trace[1186674371] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:537; } (duration: 70.159427ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:09.099699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.210393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aae81b36-1f3e-45c1-9c15-f7e0832e5152\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-09T16:58:09.099769Z","caller":"traceutil/trace.go:171","msg":"trace[305294529] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aae81b36-1f3e-45c1-9c15-f7e0832e5152; range_end:; response_count:1; response_revision:1866; }","duration":"133.331255ms","start":"2026-05-09T16:58:08.966422Z","end":"2026-05-09T16:58:09.099754Z","steps":["trace[305294529] 'agreement among raft nodes before linearized reading' (duration: 133.11618ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:09.384029Z","caller":"traceutil/trace.go:171","msg":"trace[97896293] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"276.170462ms","start":"2026-05-09T16:58:09.107825Z","end":"2026-05-09T16:58:09.383995Z","steps":["trace[97896293] 'process raft request' (duration: 276.026858ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:25.415269Z","caller":"traceutil/trace.go:171","msg":"trace[350473396] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2075; }","duration":"160.314035ms","start":"2026-05-09T16:58:25.254936Z","end":"2026-05-09T16:58:25.41525Z","steps":["trace[350473396] 'read index received' (duration: 160.138271ms)","trace[350473396] 'applied index is now lower than readState.Index' (duration: 175.144µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:58:25.41533Z","caller":"traceutil/trace.go:171","msg":"trace[520309962] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"177.313003ms","start":"2026-05-09T16:58:25.237992Z","end":"2026-05-09T16:58:25.415305Z","steps":["trace[520309962] 'process raft request' (duration: 177.109049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:25.415487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.53954ms","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-05-09T16:58:25.415532Z","caller":"traceutil/trace.go:171","msg":"trace[530431863] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1998; }","duration":"160.645062ms","start":"2026-05-09T16:58:25.254875Z","end":"2026-05-09T16:58:25.41552Z","steps":["trace[530431863] 'agreement among raft nodes before linearized reading' (duration: 160.460568ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:25.599757Z","caller":"traceutil/trace.go:171","msg":"trace[1106089411] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"100.495027ms","start":"2026-05-09T16:58:25.499238Z","end":"2026-05-09T16:58:25.599733Z","steps":["trace[1106089411] 'process raft request' (duration: 100.283472ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:58.864994Z","caller":"traceutil/trace.go:171","msg":"trace[1748012873] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"114.14916ms","start":"2026-05-09T16:58:58.750812Z","end":"2026-05-09T16:58:58.864961Z","steps":["trace[1748012873] 'process raft request' (duration: 113.882663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:59:05.26017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.195506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:59:05.260298Z","caller":"traceutil/trace.go:171","msg":"trace[931942563] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"264.443658ms","start":"2026-05-09T16:59:04.995832Z","end":"2026-05-09T16:59:05.260276Z","steps":["trace[931942563] 'process raft request' (duration: 134.030447ms)","trace[931942563] 'compare' (duration: 130.030322ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:59:05.270769Z","caller":"traceutil/trace.go:171","msg":"trace[1232456841] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2299; }","duration":"235.79282ms","start":"2026-05-09T16:59:05.034946Z","end":"2026-05-09T16:59:05.270739Z","steps":["trace[1232456841] 'read index received' (duration: 94.924379ms)","trace[1232456841] 'applied index is now lower than readState.Index' (duration: 140.866831ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:59:05.270944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.005216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:59:05.270921Z","caller":"traceutil/trace.go:171","msg":"trace[1721949869] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"274.697855ms","start":"2026-05-09T16:59:04.996149Z","end":"2026-05-09T16:59:05.270847Z","steps":["trace[1721949869] 'process raft request' (duration: 274.451298ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:05.271023Z","caller":"traceutil/trace.go:171","msg":"trace[1784730523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2215; }","duration":"236.116658ms","start":"2026-05-09T16:59:05.034882Z","end":"2026-05-09T16:59:05.270998Z","steps":["trace[1784730523] 'agreement among raft nodes before linearized reading' (duration: 235.976205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:59:05.535927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.500831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:59:05.536041Z","caller":"traceutil/trace.go:171","msg":"trace[1412853807] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2301; }","duration":"265.164305ms","start":"2026-05-09T16:59:05.270858Z","end":"2026-05-09T16:59:05.536023Z","steps":["trace[1412853807] 'read index received' (duration: 122.470869ms)","trace[1412853807] 'applied index is now lower than readState.Index' (duration: 142.692286ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:59:05.536149Z","caller":"traceutil/trace.go:171","msg":"trace[213339569] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"353.894225ms","start":"2026-05-09T16:59:05.182225Z","end":"2026-05-09T16:59:05.536119Z","steps":["trace[213339569] 'process raft request' (duration: 211.107238ms)","trace[213339569] 'compare' (duration: 142.361337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:59:05.536188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.867424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:59:05.536262Z","caller":"traceutil/trace.go:171","msg":"trace[1229205220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2216; }","duration":"263.985377ms","start":"2026-05-09T16:59:05.272256Z","end":"2026-05-09T16:59:05.536241Z","steps":["trace[1229205220] 'agreement among raft nodes before linearized reading' (duration: 263.875845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:59:05.536256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.62112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T16:59:05.536303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:59:05.182204Z","time spent":"354.026918ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:59:05.536317Z","caller":"traceutil/trace.go:171","msg":"trace[1900396126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2216; }","duration":"271.764664ms","start":"2026-05-09T16:59:05.264539Z","end":"2026-05-09T16:59:05.536304Z","steps":["trace[1900396126] 'agreement among raft nodes before linearized reading' (duration: 271.566929ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:48.123988Z","caller":"traceutil/trace.go:171","msg":"trace[1097411191] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"107.976872ms","start":"2026-05-09T16:59:48.015936Z","end":"2026-05-09T16:59:48.123912Z","steps":["trace[1097411191] 'process raft request' (duration: 105.943923ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:00:22.059Z","caller":"traceutil/trace.go:171","msg":"trace[929970272] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"126.680345ms","start":"2026-05-09T17:00:21.932279Z","end":"2026-05-09T17:00:22.05896Z","steps":["trace[929970272] 'process raft request' (duration: 88.220542ms)","trace[929970272] 'compare' (duration: 38.192807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:04:15.069852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.490323ms","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-05-09T17:04:15.069952Z","caller":"traceutil/trace.go:171","msg":"trace[1398154943] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4862; }","duration":"101.627175ms","start":"2026-05-09T17:04:14.968306Z","end":"2026-05-09T17:04:15.069933Z","steps":["trace[1398154943] 'range keys from in-memory index tree' (duration: 101.359169ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:15.093323Z","caller":"traceutil/trace.go:171","msg":"trace[783842983] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"110.371563ms","start":"2026-05-09T17:04:14.982915Z","end":"2026-05-09T17:04:15.093287Z","steps":["trace[783842983] 'process raft request' (duration: 110.145398ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:21.248635Z","caller":"traceutil/trace.go:171","msg":"trace[1959038366] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"145.278547ms","start":"2026-05-09T17:04:21.103333Z","end":"2026-05-09T17:04:21.248612Z","steps":["trace[1959038366] 'process raft request' (duration: 145.151704ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:21.77035Z","caller":"traceutil/trace.go:171","msg":"trace[178401272] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"165.315715ms","start":"2026-05-09T17:04:21.604999Z","end":"2026-05-09T17:04:21.770315Z","steps":["trace[178401272] 'process raft request' (duration: 165.101629ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:21.895945Z","caller":"traceutil/trace.go:171","msg":"trace[803694724] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"115.560637ms","start":"2026-05-09T17:04:21.780361Z","end":"2026-05-09T17:04:21.895922Z","steps":["trace[803694724] 'process raft request' (duration: 94.797002ms)","trace[803694724] 'compare' (duration: 20.534579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:04:31.096963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.185365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T17:04:31.097221Z","caller":"traceutil/trace.go:171","msg":"trace[2063048493] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"133.816303ms","start":"2026-05-09T17:04:30.963393Z","end":"2026-05-09T17:04:31.09721Z","steps":["trace[2063048493] 'process raft request' (duration: 133.771062ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:31.097326Z","caller":"traceutil/trace.go:171","msg":"trace[885612357] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"218.029732ms","start":"2026-05-09T17:04:30.879258Z","end":"2026-05-09T17:04:31.097287Z","steps":["trace[885612357] 'process raft request' (duration: 82.471799ms)","trace[885612357] 'compare' (duration: 135.096683ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:04:31.097466Z","caller":"traceutil/trace.go:171","msg":"trace[2020319334] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"217.992581ms","start":"2026-05-09T17:04:30.879461Z","end":"2026-05-09T17:04:31.097454Z","steps":["trace[2020319334] 'process raft request' (duration: 217.647963ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:31.097543Z","caller":"traceutil/trace.go:171","msg":"trace[251954881] linearizableReadLoop","detail":"{readStateIndex:5433; appliedIndex:5431; }","duration":"170.095689ms","start":"2026-05-09T17:04:30.927436Z","end":"2026-05-09T17:04:31.097531Z","steps":["trace[251954881] 'read index received' (duration: 34.30986ms)","trace[251954881] 'applied index is now lower than readState.Index' (duration: 135.784939ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:04:31.097744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.925988ms","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-05-09T17:04:31.097775Z","caller":"traceutil/trace.go:171","msg":"trace[685121027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5237; }","duration":"126.99864ms","start":"2026-05-09T17:04:30.970769Z","end":"2026-05-09T17:04:31.097767Z","steps":["trace[685121027] 'agreement among raft nodes before linearized reading' (duration: 126.883337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:04:31.097801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.352115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tcp5g\" ","response":"range_response_count:1 size:9938"} {"level":"warn","ts":"2026-05-09T17:04:31.097849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.971005ms","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-05-09T17:04:31.097893Z","caller":"traceutil/trace.go:171","msg":"trace[296425977] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-tcp5g; range_end:; response_count:1; response_revision:5237; }","duration":"170.499968ms","start":"2026-05-09T17:04:30.927372Z","end":"2026-05-09T17:04:31.097872Z","steps":["trace[296425977] 'agreement among raft nodes before linearized reading' (duration: 170.219932ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:31.097918Z","caller":"traceutil/trace.go:171","msg":"trace[949554467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5237; }","duration":"113.082748ms","start":"2026-05-09T17:04:30.984817Z","end":"2026-05-09T17:04:31.0979Z","steps":["trace[949554467] 'agreement among raft nodes before linearized reading' (duration: 112.916374ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:31.532023Z","caller":"traceutil/trace.go:171","msg":"trace[1723982081] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"192.118793ms","start":"2026-05-09T17:04:31.339862Z","end":"2026-05-09T17:04:31.53198Z","steps":["trace[1723982081] 'process raft request' (duration: 182.909424ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:31.532721Z","caller":"traceutil/trace.go:171","msg":"trace[371424720] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"191.423497ms","start":"2026-05-09T17:04:31.34125Z","end":"2026-05-09T17:04:31.532673Z","steps":["trace[371424720] 'process raft request' (duration: 190.643418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:04:31.53311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.090567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-bhtsq\" ","response":"range_response_count:1 size:5851"} {"level":"info","ts":"2026-05-09T17:04:31.533163Z","caller":"traceutil/trace.go:171","msg":"trace[540115531] range","detail":"{range_begin:/registry/pods/monitoring/vector-bhtsq; range_end:; response_count:1; response_revision:5249; }","duration":"193.276812ms","start":"2026-05-09T17:04:31.339873Z","end":"2026-05-09T17:04:31.53315Z","steps":["trace[540115531] 'agreement among raft nodes before linearized reading' (duration: 193.077266ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:31.532901Z","caller":"traceutil/trace.go:171","msg":"trace[968929287] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5443; }","duration":"192.530593ms","start":"2026-05-09T17:04:31.340021Z","end":"2026-05-09T17:04:31.532552Z","steps":["trace[968929287] 'read index received' (duration: 26.662385ms)","trace[968929287] 'applied index is now lower than readState.Index' (duration: 165.867018ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:04:31.545504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.703046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-09T17:04:31.545577Z","caller":"traceutil/trace.go:171","msg":"trace[1414489723] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:5250; }","duration":"137.830628ms","start":"2026-05-09T17:04:31.407725Z","end":"2026-05-09T17:04:31.545556Z","steps":["trace[1414489723] 'agreement among raft nodes before linearized reading' (duration: 137.668115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:04:31.545786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.343544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-09T17:04:31.545864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.037036ms","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-05-09T17:04:31.545898Z","caller":"traceutil/trace.go:171","msg":"trace[1961513470] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5250; }","duration":"183.499208ms","start":"2026-05-09T17:04:31.362354Z","end":"2026-05-09T17:04:31.545853Z","steps":["trace[1961513470] 'agreement among raft nodes before linearized reading' (duration: 183.286493ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:31.545919Z","caller":"traceutil/trace.go:171","msg":"trace[801600758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5250; }","duration":"193.143599ms","start":"2026-05-09T17:04:31.352763Z","end":"2026-05-09T17:04:31.545907Z","steps":["trace[801600758] 'agreement among raft nodes before linearized reading' (duration: 192.996405ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:38.935738Z","caller":"traceutil/trace.go:171","msg":"trace[1822414539] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"126.674212ms","start":"2026-05-09T17:04:38.809035Z","end":"2026-05-09T17:04:38.93571Z","steps":["trace[1822414539] 'process raft request' (duration: 124.651164ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:39.221933Z","caller":"traceutil/trace.go:171","msg":"trace[1403916232] linearizableReadLoop","detail":"{readStateIndex:5611; appliedIndex:5610; }","duration":"188.361384ms","start":"2026-05-09T17:04:39.033553Z","end":"2026-05-09T17:04:39.221914Z","steps":["trace[1403916232] 'read index received' (duration: 188.314593ms)","trace[1403916232] 'applied index is now lower than readState.Index' (duration: 45.901µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:04:39.222076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.519468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:04:39.222054Z","caller":"traceutil/trace.go:171","msg":"trace[1560988644] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"196.245883ms","start":"2026-05-09T17:04:39.025775Z","end":"2026-05-09T17:04:39.222021Z","steps":["trace[1560988644] 'process raft request' (duration: 196.016857ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:39.222105Z","caller":"traceutil/trace.go:171","msg":"trace[2002219122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5410; }","duration":"188.606231ms","start":"2026-05-09T17:04:39.033491Z","end":"2026-05-09T17:04:39.222097Z","steps":["trace[2002219122] 'agreement among raft nodes before linearized reading' (duration: 188.522949ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:39.241966Z","caller":"traceutil/trace.go:171","msg":"trace[1218647707] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"117.114714ms","start":"2026-05-09T17:04:39.124827Z","end":"2026-05-09T17:04:39.241942Z","steps":["trace[1218647707] 'process raft request' (duration: 116.95211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:04:39.242141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.698519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T17:04:39.242199Z","caller":"traceutil/trace.go:171","msg":"trace[1691738163] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5411; }","duration":"187.804331ms","start":"2026-05-09T17:04:39.054378Z","end":"2026-05-09T17:04:39.242182Z","steps":["trace[1691738163] 'agreement among raft nodes before linearized reading' (duration: 187.712979ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:39.415369Z","caller":"traceutil/trace.go:171","msg":"trace[385007944] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"167.472126ms","start":"2026-05-09T17:04:39.247807Z","end":"2026-05-09T17:04:39.415279Z","steps":["trace[385007944] 'process raft request' (duration: 93.731027ms)","trace[385007944] 'compare' (duration: 73.618577ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:04:41.307129Z","caller":"traceutil/trace.go:171","msg":"trace[1315745881] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"229.092626ms","start":"2026-05-09T17:04:41.077993Z","end":"2026-05-09T17:04:41.307086Z","steps":["trace[1315745881] 'process raft request' (duration: 168.381327ms)","trace[1315745881] 'compare' (duration: 60.452552ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:04:41.30717Z","caller":"traceutil/trace.go:171","msg":"trace[2138958122] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"228.886352ms","start":"2026-05-09T17:04:41.078243Z","end":"2026-05-09T17:04:41.307129Z","steps":["trace[2138958122] 'process raft request' (duration: 228.745897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:41.307449Z","caller":"traceutil/trace.go:171","msg":"trace[512442034] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"228.913672ms","start":"2026-05-09T17:04:41.078522Z","end":"2026-05-09T17:04:41.307435Z","steps":["trace[512442034] 'process raft request' (duration: 228.523672ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:41.307465Z","caller":"traceutil/trace.go:171","msg":"trace[1834804721] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"227.932699ms","start":"2026-05-09T17:04:41.079507Z","end":"2026-05-09T17:04:41.30744Z","steps":["trace[1834804721] 'process raft request' (duration: 227.648352ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:41.307511Z","caller":"traceutil/trace.go:171","msg":"trace[1485447767] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"228.541203ms","start":"2026-05-09T17:04:41.07894Z","end":"2026-05-09T17:04:41.307481Z","steps":["trace[1485447767] 'process raft request' (duration: 228.173724ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:41.307797Z","caller":"traceutil/trace.go:171","msg":"trace[135320457] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"225.489981ms","start":"2026-05-09T17:04:41.082284Z","end":"2026-05-09T17:04:41.307774Z","steps":["trace[135320457] 'process raft request' (duration: 224.917467ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:41.308105Z","caller":"traceutil/trace.go:171","msg":"trace[590929703] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"224.896936ms","start":"2026-05-09T17:04:41.083179Z","end":"2026-05-09T17:04:41.308076Z","steps":["trace[590929703] 'process raft request' (duration: 224.078937ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:50.282101Z","caller":"traceutil/trace.go:171","msg":"trace[1049075152] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"101.034721ms","start":"2026-05-09T17:04:50.181039Z","end":"2026-05-09T17:04:50.282074Z","steps":["trace[1049075152] 'process raft request' (duration: 95.301134ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:50.282548Z","caller":"traceutil/trace.go:171","msg":"trace[1869390359] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"101.287526ms","start":"2026-05-09T17:04:50.181248Z","end":"2026-05-09T17:04:50.282536Z","steps":["trace[1869390359] 'process raft request' (duration: 100.751844ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:50.282595Z","caller":"traceutil/trace.go:171","msg":"trace[1944250978] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"100.291273ms","start":"2026-05-09T17:04:50.182256Z","end":"2026-05-09T17:04:50.282547Z","steps":["trace[1944250978] 'process raft request' (duration: 100.018676ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:05:58.341113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3027} {"level":"info","ts":"2026-05-09T17:05:58.426226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3027,"took":"82.971379ms","hash":1229395974,"current-db-size-bytes":58191872,"current-db-size":"58 MB","current-db-size-in-use-bytes":31875072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T17:05:58.426322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1229395974,"revision":3027,"compact-revision":-1} {"level":"info","ts":"2026-05-09T17:10:53.090859Z","caller":"traceutil/trace.go:171","msg":"trace[1254428809] transaction","detail":"{read_only:false; response_revision:8286; number_of_response:1; }","duration":"137.993692ms","start":"2026-05-09T17:10:52.952644Z","end":"2026-05-09T17:10:53.090638Z","steps":["trace[1254428809] 'process raft request' (duration: 137.773097ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:53.546962Z","caller":"traceutil/trace.go:171","msg":"trace[1919120973] linearizableReadLoop","detail":"{readStateIndex:8579; appliedIndex:8578; }","duration":"210.1669ms","start":"2026-05-09T17:10:53.336758Z","end":"2026-05-09T17:10:53.546925Z","steps":["trace[1919120973] 'read index received' (duration: 120.445052ms)","trace[1919120973] 'applied index is now lower than readState.Index' (duration: 89.719858ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:53.547124Z","caller":"traceutil/trace.go:171","msg":"trace[1210893173] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"219.785159ms","start":"2026-05-09T17:10:53.327322Z","end":"2026-05-09T17:10:53.547108Z","steps":["trace[1210893173] 'process raft request' (duration: 129.927798ms)","trace[1210893173] 'compare' (duration: 89.38014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:10:53.547549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.758763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T17:10:53.547629Z","caller":"traceutil/trace.go:171","msg":"trace[1288916174] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8288; }","duration":"210.893287ms","start":"2026-05-09T17:10:53.336717Z","end":"2026-05-09T17:10:53.54761Z","steps":["trace[1288916174] 'agreement among raft nodes before linearized reading' (duration: 210.729003ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:53.953742Z","caller":"traceutil/trace.go:171","msg":"trace[1622689258] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"361.764518ms","start":"2026-05-09T17:10:53.591949Z","end":"2026-05-09T17:10:53.953713Z","steps":["trace[1622689258] 'process raft request' (duration: 328.744608ms)","trace[1622689258] 'compare' (duration: 32.561749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:10:53.953908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:10:53.591938Z","time spent":"361.87786ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:10:54.947179Z","caller":"traceutil/trace.go:171","msg":"trace[1936333088] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"232.053044ms","start":"2026-05-09T17:10:54.715076Z","end":"2026-05-09T17:10:54.947129Z","steps":["trace[1936333088] 'process raft request' (duration: 231.958392ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:54.94731Z","caller":"traceutil/trace.go:171","msg":"trace[803435103] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"252.928823ms","start":"2026-05-09T17:10:54.69434Z","end":"2026-05-09T17:10:54.947268Z","steps":["trace[803435103] 'process raft request' (duration: 212.053595ms)","trace[803435103] 'compare' (duration: 40.416568ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:55.759367Z","caller":"traceutil/trace.go:171","msg":"trace[39565136] transaction","detail":"{read_only:false; response_revision:8299; number_of_response:1; }","duration":"164.944098ms","start":"2026-05-09T17:10:55.594361Z","end":"2026-05-09T17:10:55.759305Z","steps":["trace[39565136] 'process raft request' (duration: 112.651226ms)","trace[39565136] 'compare' (duration: 52.061907ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:58.348567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6145} {"level":"info","ts":"2026-05-09T17:10:58.441728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6145,"took":"90.174058ms","hash":2432201578,"current-db-size-bytes":58191872,"current-db-size":"58 MB","current-db-size-in-use-bytes":23015424,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-09T17:10:58.441804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2432201578,"revision":6145,"compact-revision":3027} {"level":"info","ts":"2026-05-09T17:11:35.242297Z","caller":"traceutil/trace.go:171","msg":"trace[1232212368] linearizableReadLoop","detail":"{readStateIndex:8912; appliedIndex:8911; }","duration":"113.066286ms","start":"2026-05-09T17:11:35.129205Z","end":"2026-05-09T17:11:35.242272Z","steps":["trace[1232212368] 'read index received' (duration: 112.884081ms)","trace[1232212368] 'applied index is now lower than readState.Index' (duration: 181.275µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:11:35.242323Z","caller":"traceutil/trace.go:171","msg":"trace[2134309620] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"143.750979ms","start":"2026-05-09T17:11:35.098549Z","end":"2026-05-09T17:11:35.2423Z","steps":["trace[2134309620] 'process raft request' (duration: 143.556605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:11:35.242485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.26199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T17:11:35.242523Z","caller":"traceutil/trace.go:171","msg":"trace[1009370238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8607; }","duration":"113.351222ms","start":"2026-05-09T17:11:35.129162Z","end":"2026-05-09T17:11:35.242513Z","steps":["trace[1009370238] 'agreement among raft nodes before linearized reading' (duration: 113.211249ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:11:35.481817Z","caller":"traceutil/trace.go:171","msg":"trace[504144363] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"235.059826ms","start":"2026-05-09T17:11:35.24673Z","end":"2026-05-09T17:11:35.48179Z","steps":["trace[504144363] 'process raft request' (duration: 234.899242ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:11:35.564953Z","caller":"traceutil/trace.go:171","msg":"trace[1885828534] linearizableReadLoop","detail":"{readStateIndex:8914; appliedIndex:8912; }","duration":"161.231419ms","start":"2026-05-09T17:11:35.403695Z","end":"2026-05-09T17:11:35.564927Z","steps":["trace[1885828534] 'read index received' (duration: 78.015697ms)","trace[1885828534] 'applied index is now lower than readState.Index' (duration: 83.214322ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:11:35.565028Z","caller":"traceutil/trace.go:171","msg":"trace[369813114] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"232.065314ms","start":"2026-05-09T17:11:35.332939Z","end":"2026-05-09T17:11:35.565004Z","steps":["trace[369813114] 'process raft request' (duration: 231.696056ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:11:35.565027Z","caller":"traceutil/trace.go:171","msg":"trace[400431574] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"159.879276ms","start":"2026-05-09T17:11:35.405104Z","end":"2026-05-09T17:11:35.564984Z","steps":["trace[400431574] 'process raft request' (duration: 159.64662ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:11:35.565106Z","caller":"traceutil/trace.go:171","msg":"trace[1276771739] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"152.423697ms","start":"2026-05-09T17:11:35.412663Z","end":"2026-05-09T17:11:35.565087Z","steps":["trace[1276771739] 'process raft request' (duration: 152.190812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:11:35.588746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.024018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-f5hlw\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-05-09T17:11:35.588823Z","caller":"traceutil/trace.go:171","msg":"trace[643245931] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-f5hlw; range_end:; response_count:1; response_revision:8611; }","duration":"185.11327ms","start":"2026-05-09T17:11:35.403687Z","end":"2026-05-09T17:11:35.588801Z","steps":["trace[643245931] 'agreement among raft nodes before linearized reading' (duration: 161.450064ms)","trace[643245931] 'range keys from in-memory index tree' (duration: 23.458742ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:11:36.051803Z","caller":"traceutil/trace.go:171","msg":"trace[1422266430] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"101.741334ms","start":"2026-05-09T17:11:35.950028Z","end":"2026-05-09T17:11:36.051769Z","steps":["trace[1422266430] 'process raft request' (duration: 101.460498ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:11:36.348558Z","caller":"traceutil/trace.go:171","msg":"trace[1291773230] linearizableReadLoop","detail":"{readStateIndex:8922; appliedIndex:8921; }","duration":"104.87238ms","start":"2026-05-09T17:11:36.243658Z","end":"2026-05-09T17:11:36.348531Z","steps":["trace[1291773230] 'read index received' (duration: 104.714997ms)","trace[1291773230] 'applied index is now lower than readState.Index' (duration: 156.193µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:11:36.348603Z","caller":"traceutil/trace.go:171","msg":"trace[136788774] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"219.701898ms","start":"2026-05-09T17:11:36.128865Z","end":"2026-05-09T17:11:36.348567Z","steps":["trace[136788774] 'process raft request' (duration: 219.471763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:11:36.348867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.193338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-09T17:11:36.348919Z","caller":"traceutil/trace.go:171","msg":"trace[1832137286] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8617; }","duration":"105.320561ms","start":"2026-05-09T17:11:36.243585Z","end":"2026-05-09T17:11:36.348906Z","steps":["trace[1832137286] 'agreement among raft nodes before linearized reading' (duration: 105.051105ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:12:25.340171Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d6.wal"} {"level":"info","ts":"2026-05-09T17:13:33.939654Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:13:33.945103Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T17:13:33.945202Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T17:15:58.354939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8321} {"level":"info","ts":"2026-05-09T17:15:58.432067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8321,"took":"74.898675ms","hash":987820569,"current-db-size-bytes":58191872,"current-db-size":"58 MB","current-db-size-in-use-bytes":26361856,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-09T17:15:58.432138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":987820569,"revision":8321,"compact-revision":6145} {"level":"info","ts":"2026-05-09T17:16:11.491286Z","caller":"traceutil/trace.go:171","msg":"trace[450586256] transaction","detail":"{read_only:false; response_revision:11069; number_of_response:1; }","duration":"113.091051ms","start":"2026-05-09T17:16:11.378164Z","end":"2026-05-09T17:16:11.491255Z","steps":["trace[450586256] 'process raft request' (duration: 112.923727ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:16:29.259088Z","caller":"traceutil/trace.go:171","msg":"trace[1945500267] transaction","detail":"{read_only:false; response_revision:11171; number_of_response:1; }","duration":"100.217041ms","start":"2026-05-09T17:16:29.158844Z","end":"2026-05-09T17:16:29.259061Z","steps":["trace[1945500267] 'process raft request' (duration: 100.000076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:16:29.517564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.074236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-backup-6578754fbc-xsx5j.18adf522ccb2b3ee\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-05-09T17:16:29.517681Z","caller":"traceutil/trace.go:171","msg":"trace[399610814] range","detail":"{range_begin:/registry/events/openstack/cinder-backup-6578754fbc-xsx5j.18adf522ccb2b3ee; range_end:; response_count:1; response_revision:11171; }","duration":"140.25102ms","start":"2026-05-09T17:16:29.377402Z","end":"2026-05-09T17:16:29.517653Z","steps":["trace[399610814] 'range keys from in-memory index tree' (duration: 139.867912ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:16:29.686669Z","caller":"traceutil/trace.go:171","msg":"trace[1818815086] linearizableReadLoop","detail":"{readStateIndex:11553; appliedIndex:11552; }","duration":"117.778222ms","start":"2026-05-09T17:16:29.568864Z","end":"2026-05-09T17:16:29.686642Z","steps":["trace[1818815086] 'read index received' (duration: 96.923992ms)","trace[1818815086] 'applied index is now lower than readState.Index' (duration: 20.8532ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:16:29.686844Z","caller":"traceutil/trace.go:171","msg":"trace[211284764] transaction","detail":"{read_only:false; response_revision:11174; number_of_response:1; }","duration":"118.504679ms","start":"2026-05-09T17:16:29.568225Z","end":"2026-05-09T17:16:29.686729Z","steps":["trace[211284764] 'process raft request' (duration: 97.63751ms)","trace[211284764] 'compare' (duration: 20.670474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:16:29.686998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.84302ms","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-05-09T17:16:29.686979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.095649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-volume-c76bcc554-5mzff.18adf522d2cbbdd5\" ","response":"range_response_count:1 size:801"} {"level":"info","ts":"2026-05-09T17:16:29.687073Z","caller":"traceutil/trace.go:171","msg":"trace[1467343404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11174; }","duration":"111.962733ms","start":"2026-05-09T17:16:29.575091Z","end":"2026-05-09T17:16:29.687053Z","steps":["trace[1467343404] 'agreement among raft nodes before linearized reading' (duration: 111.736247ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:16:29.687125Z","caller":"traceutil/trace.go:171","msg":"trace[1152777846] range","detail":"{range_begin:/registry/events/openstack/cinder-volume-c76bcc554-5mzff.18adf522d2cbbdd5; range_end:; response_count:1; response_revision:11174; }","duration":"118.265683ms","start":"2026-05-09T17:16:29.568808Z","end":"2026-05-09T17:16:29.687073Z","steps":["trace[1152777846] 'agreement among raft nodes before linearized reading' (duration: 117.989257ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:20:58.360942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10953} {"level":"info","ts":"2026-05-09T17:20:58.452053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10953,"took":"88.676619ms","hash":1080943394,"current-db-size-bytes":58191872,"current-db-size":"58 MB","current-db-size-in-use-bytes":27983872,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T17:20:58.452108Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1080943394,"revision":10953,"compact-revision":8321} {"level":"info","ts":"2026-05-09T17:22:13.573216Z","caller":"traceutil/trace.go:171","msg":"trace[278820526] transaction","detail":"{read_only:false; response_revision:13532; number_of_response:1; }","duration":"125.698266ms","start":"2026-05-09T17:22:13.447495Z","end":"2026-05-09T17:22:13.573193Z","steps":["trace[278820526] 'process raft request' (duration: 125.507851ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:22:16.343573Z","caller":"traceutil/trace.go:171","msg":"trace[1397091287] transaction","detail":"{read_only:false; response_revision:13546; number_of_response:1; }","duration":"123.113813ms","start":"2026-05-09T17:22:16.220434Z","end":"2026-05-09T17:22:16.343548Z","steps":["trace[1397091287] 'process raft request' (duration: 122.95968ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:22:35.056755Z","caller":"traceutil/trace.go:171","msg":"trace[1888985912] transaction","detail":"{read_only:false; response_revision:13648; number_of_response:1; }","duration":"124.565331ms","start":"2026-05-09T17:22:34.932161Z","end":"2026-05-09T17:22:35.056726Z","steps":["trace[1888985912] 'process raft request' (duration: 124.391447ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:22:49.582476Z","caller":"traceutil/trace.go:171","msg":"trace[1236990153] linearizableReadLoop","detail":"{readStateIndex:14208; appliedIndex:14207; }","duration":"123.288451ms","start":"2026-05-09T17:22:49.459163Z","end":"2026-05-09T17:22:49.582451Z","steps":["trace[1236990153] 'read index received' (duration: 123.116147ms)","trace[1236990153] 'applied index is now lower than readState.Index' (duration: 171.234µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:22:49.582581Z","caller":"traceutil/trace.go:171","msg":"trace[1386098904] transaction","detail":"{read_only:false; response_revision:13738; number_of_response:1; }","duration":"125.364241ms","start":"2026-05-09T17:22:49.457191Z","end":"2026-05-09T17:22:49.582555Z","steps":["trace[1386098904] 'process raft request' (duration: 125.070364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:22:49.582717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.534468ms","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-05-09T17:22:49.582809Z","caller":"traceutil/trace.go:171","msg":"trace[147924111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13738; }","duration":"123.67394ms","start":"2026-05-09T17:22:49.459112Z","end":"2026-05-09T17:22:49.582786Z","steps":["trace[147924111] 'agreement among raft nodes before linearized reading' (duration: 123.447885ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:23:11.398385Z","caller":"traceutil/trace.go:171","msg":"trace[571650000] linearizableReadLoop","detail":"{readStateIndex:14528; appliedIndex:14527; }","duration":"247.849623ms","start":"2026-05-09T17:23:11.150509Z","end":"2026-05-09T17:23:11.398359Z","steps":["trace[571650000] 'read index received' (duration: 247.71677ms)","trace[571650000] 'applied index is now lower than readState.Index' (duration: 131.353µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:23:11.398463Z","caller":"traceutil/trace.go:171","msg":"trace[2020942943] transaction","detail":"{read_only:false; response_revision:14045; number_of_response:1; }","duration":"257.760521ms","start":"2026-05-09T17:23:11.140659Z","end":"2026-05-09T17:23:11.398419Z","steps":["trace[2020942943] 'process raft request' (duration: 257.505675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:23:11.398632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.11639ms","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-05-09T17:23:11.398677Z","caller":"traceutil/trace.go:171","msg":"trace[622586733] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14045; }","duration":"248.222052ms","start":"2026-05-09T17:23:11.150443Z","end":"2026-05-09T17:23:11.398665Z","steps":["trace[622586733] 'agreement among raft nodes before linearized reading' (duration: 248.050178ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:23:11.403644Z","caller":"traceutil/trace.go:171","msg":"trace[1586780992] transaction","detail":"{read_only:false; response_revision:14046; number_of_response:1; }","duration":"224.74605ms","start":"2026-05-09T17:23:11.17887Z","end":"2026-05-09T17:23:11.403616Z","steps":["trace[1586780992] 'process raft request' (duration: 224.551276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:23:11.403745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.947623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T17:23:11.403849Z","caller":"traceutil/trace.go:171","msg":"trace[105467353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14046; }","duration":"194.078565ms","start":"2026-05-09T17:23:11.209747Z","end":"2026-05-09T17:23:11.403825Z","steps":["trace[105467353] 'agreement among raft nodes before linearized reading' (duration: 193.86225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:24:37.123033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.217017ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T17:24:37.123133Z","caller":"traceutil/trace.go:171","msg":"trace[1454149223] linearizableReadLoop","detail":"{readStateIndex:15362; appliedIndex:15361; }","duration":"166.743152ms","start":"2026-05-09T17:24:36.956371Z","end":"2026-05-09T17:24:37.123115Z","steps":["trace[1454149223] 'read index received' (duration: 31.351102ms)","trace[1454149223] 'applied index is now lower than readState.Index' (duration: 135.39042ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:24:37.123238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.935528ms","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-05-09T17:24:37.123266Z","caller":"traceutil/trace.go:171","msg":"trace[1474099286] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14857; }","duration":"114.00856ms","start":"2026-05-09T17:24:37.00925Z","end":"2026-05-09T17:24:37.123258Z","steps":["trace[1474099286] 'agreement among raft nodes before linearized reading' (duration: 113.945958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:24:37.123354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.944107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-65tvm\" ","response":"range_response_count:1 size:22128"} {"level":"warn","ts":"2026-05-09T17:24:37.123404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.31077ms","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-05-09T17:24:37.123425Z","caller":"traceutil/trace.go:171","msg":"trace[758983796] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-65tvm; range_end:; response_count:1; response_revision:14857; }","duration":"167.043519ms","start":"2026-05-09T17:24:36.956365Z","end":"2026-05-09T17:24:37.123409Z","steps":["trace[758983796] 'agreement among raft nodes before linearized reading' (duration: 166.806504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:24:37.123418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.871132ms","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-05-09T17:24:37.123518Z","caller":"traceutil/trace.go:171","msg":"trace[1271143662] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14857; }","duration":"113.051325ms","start":"2026-05-09T17:24:37.010449Z","end":"2026-05-09T17:24:37.1235Z","steps":["trace[1271143662] 'agreement among raft nodes before linearized reading' (duration: 112.843521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:24:37.123676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.139199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9pq94\" ","response":"range_response_count:1 size:20764"} {"level":"info","ts":"2026-05-09T17:24:37.12371Z","caller":"traceutil/trace.go:171","msg":"trace[1034604953] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9pq94; range_end:; response_count:1; response_revision:14857; }","duration":"103.19021ms","start":"2026-05-09T17:24:37.020513Z","end":"2026-05-09T17:24:37.123703Z","steps":["trace[1034604953] 'agreement among raft nodes before linearized reading' (duration: 103.077888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:24:37.123733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.334887ms","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-05-09T17:24:37.123901Z","caller":"traceutil/trace.go:171","msg":"trace[1653544803] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14857; }","duration":"114.502281ms","start":"2026-05-09T17:24:37.009313Z","end":"2026-05-09T17:24:37.123815Z","steps":["trace[1653544803] 'agreement among raft nodes before linearized reading' (duration: 114.147163ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:24:37.123465Z","caller":"traceutil/trace.go:171","msg":"trace[306578813] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14857; }","duration":"102.382071ms","start":"2026-05-09T17:24:37.021062Z","end":"2026-05-09T17:24:37.123444Z","steps":["trace[306578813] 'agreement among raft nodes before linearized reading' (duration: 102.191267ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:25:58.366867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13013} {"level":"info","ts":"2026-05-09T17:25:58.448082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13013,"took":"78.394507ms","hash":3143458800,"current-db-size-bytes":58191872,"current-db-size":"58 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T17:25:58.448143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3143458800,"revision":13013,"compact-revision":10953} {"level":"info","ts":"2026-05-09T17:29:54.614316Z","caller":"traceutil/trace.go:171","msg":"trace[2123178560] transaction","detail":"{read_only:false; response_revision:17261; number_of_response:1; }","duration":"138.962998ms","start":"2026-05-09T17:29:54.475333Z","end":"2026-05-09T17:29:54.614296Z","steps":["trace[2123178560] 'process raft request' (duration: 138.810185ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:30:00.987554Z","caller":"traceutil/trace.go:171","msg":"trace[262869898] transaction","detail":"{read_only:false; response_revision:17314; number_of_response:1; }","duration":"101.553974ms","start":"2026-05-09T17:30:00.885974Z","end":"2026-05-09T17:30:00.987528Z","steps":["trace[262869898] 'process raft request' (duration: 101.402021ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:30:01.442931Z","caller":"traceutil/trace.go:171","msg":"trace[679134893] transaction","detail":"{read_only:false; response_revision:17315; number_of_response:1; }","duration":"319.736583ms","start":"2026-05-09T17:30:01.123167Z","end":"2026-05-09T17:30:01.442904Z","steps":["trace[679134893] 'process raft request' (duration: 319.555709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:30:01.443081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:30:01.123144Z","time spent":"319.863206ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:30:01.457927Z","caller":"traceutil/trace.go:171","msg":"trace[1765616748] transaction","detail":"{read_only:false; response_revision:17316; number_of_response:1; }","duration":"332.697643ms","start":"2026-05-09T17:30:01.125211Z","end":"2026-05-09T17:30:01.457908Z","steps":["trace[1765616748] 'process raft request' (duration: 332.510779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:30:01.458034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:30:01.125198Z","time spent":"332.779845ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:30:01.458218Z","caller":"traceutil/trace.go:171","msg":"trace[157743006] transaction","detail":"{read_only:false; response_revision:17317; number_of_response:1; }","duration":"274.833941ms","start":"2026-05-09T17:30:01.183365Z","end":"2026-05-09T17:30:01.458199Z","steps":["trace[157743006] 'process raft request' (duration: 274.487993ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:30:01.458237Z","caller":"traceutil/trace.go:171","msg":"trace[623943847] linearizableReadLoop","detail":"{readStateIndex:17905; appliedIndex:17902; }","duration":"211.569821ms","start":"2026-05-09T17:30:01.246642Z","end":"2026-05-09T17:30:01.458212Z","steps":["trace[623943847] 'read index received' (duration: 196.190323ms)","trace[623943847] 'applied index is now lower than readState.Index' (duration: 15.378498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:30:01.458444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.778176ms","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-05-09T17:30:01.458484Z","caller":"traceutil/trace.go:171","msg":"trace[2065247853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17318; }","duration":"211.89972ms","start":"2026-05-09T17:30:01.246573Z","end":"2026-05-09T17:30:01.458473Z","steps":["trace[2065247853] 'agreement among raft nodes before linearized reading' (duration: 211.743826ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:30:01.594384Z","caller":"traceutil/trace.go:171","msg":"trace[998578002] linearizableReadLoop","detail":"{readStateIndex:17907; appliedIndex:17906; }","duration":"105.08501ms","start":"2026-05-09T17:30:01.489257Z","end":"2026-05-09T17:30:01.594342Z","steps":["trace[998578002] 'read index received' (duration: 98.535622ms)","trace[998578002] 'applied index is now lower than readState.Index' (duration: 6.548108ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:30:01.594436Z","caller":"traceutil/trace.go:171","msg":"trace[164012122] transaction","detail":"{read_only:false; response_revision:17319; number_of_response:1; }","duration":"131.617792ms","start":"2026-05-09T17:30:01.462786Z","end":"2026-05-09T17:30:01.594403Z","steps":["trace[164012122] 'process raft request' (duration: 124.997813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:30:01.594767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.481319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T17:30:01.594874Z","caller":"traceutil/trace.go:171","msg":"trace[618264960] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:17319; }","duration":"105.639232ms","start":"2026-05-09T17:30:01.489208Z","end":"2026-05-09T17:30:01.594848Z","steps":["trace[618264960] 'agreement among raft nodes before linearized reading' (duration: 105.297704ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:30:58.372524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15404} {"level":"info","ts":"2026-05-09T17:30:58.467498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15404,"took":"92.330834ms","hash":2775275101,"current-db-size-bytes":58191872,"current-db-size":"58 MB","current-db-size-in-use-bytes":33759232,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T17:30:58.467587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2775275101,"revision":15404,"compact-revision":13013} {"level":"info","ts":"2026-05-09T17:34:10.013785Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:34:10.018592Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T17:34:10.018713Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T17:35:35.05717Z","caller":"traceutil/trace.go:171","msg":"trace[367409307] linearizableReadLoop","detail":"{readStateIndex:20604; appliedIndex:20603; }","duration":"270.138275ms","start":"2026-05-09T17:35:34.786996Z","end":"2026-05-09T17:35:35.057135Z","steps":["trace[367409307] 'read index received' (duration: 183.419756ms)","trace[367409307] 'applied index is now lower than readState.Index' (duration: 86.717259ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:35:35.057484Z","caller":"traceutil/trace.go:171","msg":"trace[1699738419] transaction","detail":"{read_only:false; response_revision:19929; number_of_response:1; }","duration":"406.272622ms","start":"2026-05-09T17:35:34.65118Z","end":"2026-05-09T17:35:35.057453Z","steps":["trace[1699738419] 'process raft request' (duration: 319.288097ms)","trace[1699738419] 'compare' (duration: 86.110265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:35:35.057607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.585936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:35:35.05769Z","caller":"traceutil/trace.go:171","msg":"trace[90803394] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:19929; }","duration":"270.73472ms","start":"2026-05-09T17:35:34.786942Z","end":"2026-05-09T17:35:35.057676Z","steps":["trace[90803394] 'agreement among raft nodes before linearized reading' (duration: 270.578756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:35:35.057847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:35:34.651166Z","time spent":"406.391325ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:35:35.369549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.565044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:35:35.369825Z","caller":"traceutil/trace.go:171","msg":"trace[1889394561] transaction","detail":"{read_only:false; response_revision:19931; number_of_response:1; }","duration":"250.381963ms","start":"2026-05-09T17:35:35.119417Z","end":"2026-05-09T17:35:35.369799Z","steps":["trace[1889394561] 'process raft request' (duration: 250.301701ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:35:35.369897Z","caller":"traceutil/trace.go:171","msg":"trace[811531686] linearizableReadLoop","detail":"{readStateIndex:20605; appliedIndex:20604; }","duration":"251.423266ms","start":"2026-05-09T17:35:35.118409Z","end":"2026-05-09T17:35:35.369833Z","steps":["trace[811531686] 'read index received' (duration: 102.461583ms)","trace[811531686] 'applied index is now lower than readState.Index' (duration: 148.959323ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:35:35.369912Z","caller":"traceutil/trace.go:171","msg":"trace[2116584782] transaction","detail":"{read_only:false; response_revision:19930; number_of_response:1; }","duration":"251.585302ms","start":"2026-05-09T17:35:35.118275Z","end":"2026-05-09T17:35:35.369861Z","steps":["trace[2116584782] 'process raft request' (duration: 102.569507ms)","trace[2116584782] 'compare' (duration: 148.330918ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:35:35.370248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.749745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-09T17:35:35.370315Z","caller":"traceutil/trace.go:171","msg":"trace[1008156869] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19931; }","duration":"252.243297ms","start":"2026-05-09T17:35:35.118056Z","end":"2026-05-09T17:35:35.370299Z","steps":["trace[1008156869] 'agreement among raft nodes before linearized reading' (duration: 251.91994ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:35:35.434082Z","caller":"traceutil/trace.go:171","msg":"trace[1041100615] transaction","detail":"{read_only:false; response_revision:19932; number_of_response:1; }","duration":"113.448026ms","start":"2026-05-09T17:35:35.320607Z","end":"2026-05-09T17:35:35.434055Z","steps":["trace[1041100615] 'process raft request' (duration: 113.236791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:35:35.434356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.35934ms","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-05-09T17:35:35.434498Z","caller":"traceutil/trace.go:171","msg":"trace[622570338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19932; }","duration":"314.529663ms","start":"2026-05-09T17:35:35.119943Z","end":"2026-05-09T17:35:35.434472Z","steps":["trace[622570338] 'agreement among raft nodes before linearized reading' (duration: 314.094602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:35:35.434611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:35:35.119936Z","time spent":"314.656406ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T17:35:35.728247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.549075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:35:35.728389Z","caller":"traceutil/trace.go:171","msg":"trace[2129106831] linearizableReadLoop","detail":"{readStateIndex:20609; appliedIndex:20607; }","duration":"227.528438ms","start":"2026-05-09T17:35:35.500836Z","end":"2026-05-09T17:35:35.728364Z","steps":["trace[2129106831] 'read index received' (duration: 13.797829ms)","trace[2129106831] 'applied index is now lower than readState.Index' (duration: 213.729029ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:35:35.728616Z","caller":"traceutil/trace.go:171","msg":"trace[993948813] transaction","detail":"{read_only:false; response_revision:19934; number_of_response:1; }","duration":"288.295158ms","start":"2026-05-09T17:35:35.440192Z","end":"2026-05-09T17:35:35.728487Z","steps":["trace[993948813] 'process raft request' (duration: 180.406863ms)","trace[993948813] 'compare' (duration: 107.388672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:35:35.72864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.412356ms","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-05-09T17:35:35.728699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.95767ms","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-05-09T17:35:35.728719Z","caller":"traceutil/trace.go:171","msg":"trace[1613336821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19934; }","duration":"157.498678ms","start":"2026-05-09T17:35:35.571201Z","end":"2026-05-09T17:35:35.728699Z","steps":["trace[1613336821] 'agreement among raft nodes before linearized reading' (duration: 157.306003ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:35:35.72877Z","caller":"traceutil/trace.go:171","msg":"trace[2132486329] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19934; }","duration":"148.068123ms","start":"2026-05-09T17:35:35.580681Z","end":"2026-05-09T17:35:35.728749Z","steps":["trace[2132486329] 'agreement among raft nodes before linearized reading' (duration: 147.869788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:35:35.728878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.03424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:35:35.728958Z","caller":"traceutil/trace.go:171","msg":"trace[1708954184] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19934; }","duration":"228.160913ms","start":"2026-05-09T17:35:35.50078Z","end":"2026-05-09T17:35:35.728941Z","steps":["trace[1708954184] 'agreement among raft nodes before linearized reading' (duration: 228.02045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:35:35.966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.690101ms","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-05-09T17:35:35.966092Z","caller":"traceutil/trace.go:171","msg":"trace[1770265392] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:19936; }","duration":"136.846834ms","start":"2026-05-09T17:35:35.829223Z","end":"2026-05-09T17:35:35.96607Z","steps":["trace[1770265392] 'range keys from in-memory index tree' (duration: 136.514036ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:35:58.378639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17975} {"level":"info","ts":"2026-05-09T17:35:58.490037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17975,"took":"107.927696ms","hash":949071783,"current-db-size-bytes":58191872,"current-db-size":"58 MB","current-db-size-in-use-bytes":29241344,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-09T17:35:58.490107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":949071783,"revision":17975,"compact-revision":15404} {"level":"info","ts":"2026-05-09T17:39:20.453759Z","caller":"traceutil/trace.go:171","msg":"trace[490894282] transaction","detail":"{read_only:false; response_revision:22276; number_of_response:1; }","duration":"308.553911ms","start":"2026-05-09T17:39:20.145117Z","end":"2026-05-09T17:39:20.453671Z","steps":["trace[490894282] 'process raft request' (duration: 308.293124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:39:20.454038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:39:20.145085Z","time spent":"308.805357ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:39:20.662428Z","caller":"traceutil/trace.go:171","msg":"trace[1018676235] linearizableReadLoop","detail":"{readStateIndex:23024; appliedIndex:23023; }","duration":"142.267154ms","start":"2026-05-09T17:39:20.520126Z","end":"2026-05-09T17:39:20.662393Z","steps":["trace[1018676235] 'read index received' (duration: 122.519823ms)","trace[1018676235] 'applied index is now lower than readState.Index' (duration: 19.745551ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:39:20.662512Z","caller":"traceutil/trace.go:171","msg":"trace[1565675826] transaction","detail":"{read_only:false; response_revision:22278; number_of_response:1; }","duration":"166.257417ms","start":"2026-05-09T17:39:20.496221Z","end":"2026-05-09T17:39:20.662478Z","steps":["trace[1565675826] 'process raft request' (duration: 146.429274ms)","trace[1565675826] 'compare' (duration: 19.591077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:39:20.662652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.507509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:39:20.662835Z","caller":"traceutil/trace.go:171","msg":"trace[1626242953] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22278; }","duration":"142.726195ms","start":"2026-05-09T17:39:20.520091Z","end":"2026-05-09T17:39:20.662817Z","steps":["trace[1626242953] 'agreement among raft nodes before linearized reading' (duration: 142.492979ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:40:58.384944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20055} {"level":"info","ts":"2026-05-09T17:40:58.485685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20055,"took":"97.133847ms","hash":3568715829,"current-db-size-bytes":58191872,"current-db-size":"58 MB","current-db-size-in-use-bytes":41807872,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-09T17:40:58.485768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3568715829,"revision":20055,"compact-revision":17975} {"level":"info","ts":"2026-05-09T17:42:40.964236Z","caller":"traceutil/trace.go:171","msg":"trace[2114046339] linearizableReadLoop","detail":"{readStateIndex:25139; appliedIndex:25138; }","duration":"110.417194ms","start":"2026-05-09T17:42:40.853788Z","end":"2026-05-09T17:42:40.964205Z","steps":["trace[2114046339] 'read index received' (duration: 110.332882ms)","trace[2114046339] 'applied index is now lower than readState.Index' (duration: 82.912µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:40.964299Z","caller":"traceutil/trace.go:171","msg":"trace[213056158] transaction","detail":"{read_only:false; response_revision:24340; number_of_response:1; }","duration":"116.454049ms","start":"2026-05-09T17:42:40.847818Z","end":"2026-05-09T17:42:40.964272Z","steps":["trace[213056158] 'process raft request' (duration: 116.250594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:40.964394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.581838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:40.964437Z","caller":"traceutil/trace.go:171","msg":"trace[331943018] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24340; }","duration":"110.67431ms","start":"2026-05-09T17:42:40.853752Z","end":"2026-05-09T17:42:40.964426Z","steps":["trace[331943018] 'agreement among raft nodes before linearized reading' (duration: 110.588578ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:41.822775Z","caller":"traceutil/trace.go:171","msg":"trace[100803083] linearizableReadLoop","detail":"{readStateIndex:25141; appliedIndex:25140; }","duration":"385.23959ms","start":"2026-05-09T17:42:41.43751Z","end":"2026-05-09T17:42:41.82275Z","steps":["trace[100803083] 'read index received' (duration: 384.653666ms)","trace[100803083] 'applied index is now lower than readState.Index' (duration: 585.014µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:41.822882Z","caller":"traceutil/trace.go:171","msg":"trace[1143089641] transaction","detail":"{read_only:false; response_revision:24342; number_of_response:1; }","duration":"418.727149ms","start":"2026-05-09T17:42:41.404103Z","end":"2026-05-09T17:42:41.82283Z","steps":["trace[1143089641] 'process raft request' (duration: 418.444912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:41.822992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.297959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T17:42:41.823041Z","caller":"traceutil/trace.go:171","msg":"trace[1128788495] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:24342; }","duration":"291.395031ms","start":"2026-05-09T17:42:41.531634Z","end":"2026-05-09T17:42:41.823029Z","steps":["trace[1128788495] 'agreement among raft nodes before linearized reading' (duration: 291.244668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:41.823072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.39967ms","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-05-09T17:42:41.822993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.482545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"warn","ts":"2026-05-09T17:42:41.823133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.404071Z","time spent":"418.936244ms","remote":"127.0.0.1:59402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8449,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:41.82315Z","caller":"traceutil/trace.go:171","msg":"trace[46199483] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24342; }","duration":"385.69164ms","start":"2026-05-09T17:42:41.437446Z","end":"2026-05-09T17:42:41.823137Z","steps":["trace[46199483] 'agreement among raft nodes before linearized reading' (duration: 385.413823ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:41.823146Z","caller":"traceutil/trace.go:171","msg":"trace[1617566010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24342; }","duration":"275.493432ms","start":"2026-05-09T17:42:41.547635Z","end":"2026-05-09T17:42:41.823129Z","steps":["trace[1617566010] 'agreement among raft nodes before linearized reading' (duration: 275.317478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:41.823195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.437408Z","time spent":"385.775272ms","remote":"127.0.0.1:59368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T17:42:41.823048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.906366ms","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-05-09T17:42:41.823377Z","caller":"traceutil/trace.go:171","msg":"trace[379732036] 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:24342; }","duration":"111.286805ms","start":"2026-05-09T17:42:41.712047Z","end":"2026-05-09T17:42:41.823334Z","steps":["trace[379732036] 'agreement among raft nodes before linearized reading' (duration: 110.867505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.149898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.006216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:42.150199Z","caller":"traceutil/trace.go:171","msg":"trace[2123190770] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"319.265887ms","start":"2026-05-09T17:42:41.830913Z","end":"2026-05-09T17:42:42.150179Z","steps":["trace[2123190770] 'process raft request' (duration: 319.085613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.150281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.830893Z","time spent":"319.351548ms","remote":"127.0.0.1:59488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1168,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:42.150314Z","caller":"traceutil/trace.go:171","msg":"trace[193550303] transaction","detail":"{read_only:false; response_revision:24343; number_of_response:1; }","duration":"320.105476ms","start":"2026-05-09T17:42:41.830181Z","end":"2026-05-09T17:42:42.150286Z","steps":["trace[193550303] 'process raft request' (duration: 172.618939ms)","trace[193550303] 'compare' (duration: 146.817272ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:42.150398Z","caller":"traceutil/trace.go:171","msg":"trace[1808129952] transaction","detail":"{read_only:false; response_revision:24346; number_of_response:1; }","duration":"318.16284ms","start":"2026-05-09T17:42:41.832222Z","end":"2026-05-09T17:42:42.150385Z","steps":["trace[1808129952] 'process raft request' (duration: 317.906304ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:42.150407Z","caller":"traceutil/trace.go:171","msg":"trace[1939904087] transaction","detail":"{read_only:false; response_revision:24345; number_of_response:1; }","duration":"318.449687ms","start":"2026-05-09T17:42:41.831935Z","end":"2026-05-09T17:42:42.150385Z","steps":["trace[1939904087] 'process raft request' (duration: 318.141919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.150467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.832198Z","time spent":"318.235121ms","remote":"127.0.0.1:59552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:42.150467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.830152Z","time spent":"320.235079ms","remote":"127.0.0.1:59480","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-05-09T17:42:42.150513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.831928Z","time spent":"318.536508ms","remote":"127.0.0.1:59382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:42.150711Z","caller":"traceutil/trace.go:171","msg":"trace[774681875] linearizableReadLoop","detail":"{readStateIndex:25147; appliedIndex:25142; }","duration":"308.712294ms","start":"2026-05-09T17:42:41.841977Z","end":"2026-05-09T17:42:42.150689Z","steps":["trace[774681875] 'read index received' (duration: 160.900069ms)","trace[774681875] 'applied index is now lower than readState.Index' (duration: 147.811325ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:42.150772Z","caller":"traceutil/trace.go:171","msg":"trace[294522384] transaction","detail":"{read_only:false; response_revision:24347; number_of_response:1; }","duration":"316.226964ms","start":"2026-05-09T17:42:41.834513Z","end":"2026-05-09T17:42:42.15074Z","steps":["trace[294522384] 'process raft request' (duration: 315.818264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.150913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.221394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-aae81b36-1f3e-45c1-9c15-f7e0832e5152\" ","response":"range_response_count:1 size:1304"} {"level":"warn","ts":"2026-05-09T17:42:42.150946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.669483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-09T17:42:42.150946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.834503Z","time spent":"316.359747ms","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:42.150973Z","caller":"traceutil/trace.go:171","msg":"trace[614970295] range","detail":"{range_begin:/registry/persistentvolumes/pvc-aae81b36-1f3e-45c1-9c15-f7e0832e5152; range_end:; response_count:1; response_revision:24347; }","duration":"324.296085ms","start":"2026-05-09T17:42:41.82666Z","end":"2026-05-09T17:42:42.150956Z","steps":["trace[614970295] 'agreement among raft nodes before linearized reading' (duration: 324.135211ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:42.150983Z","caller":"traceutil/trace.go:171","msg":"trace[2042378247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24347; }","duration":"321.760934ms","start":"2026-05-09T17:42:41.829212Z","end":"2026-05-09T17:42:42.150973Z","steps":["trace[2042378247] 'agreement among raft nodes before linearized reading' (duration: 321.525439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.151018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.826645Z","time spent":"324.363967ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1327,"request content":"key:\"/registry/persistentvolumes/pvc-aae81b36-1f3e-45c1-9c15-f7e0832e5152\" "} {"level":"warn","ts":"2026-05-09T17:42:42.151022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.829187Z","time spent":"321.826056ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T17:42:42.151025Z","caller":"traceutil/trace.go:171","msg":"trace[1355767342] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"189.246424ms","start":"2026-05-09T17:42:41.961762Z","end":"2026-05-09T17:42:42.151008Z","steps":["trace[1355767342] 'process raft request' (duration: 189.179023ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:42.15103Z","caller":"traceutil/trace.go:171","msg":"trace[2094513681] transaction","detail":"{read_only:false; response_revision:24349; number_of_response:1; }","duration":"304.685477ms","start":"2026-05-09T17:42:41.846315Z","end":"2026-05-09T17:42:42.151Z","steps":["trace[2094513681] 'process raft request' (duration: 304.538684ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:42.151082Z","caller":"traceutil/trace.go:171","msg":"trace[1213358188] transaction","detail":"{read_only:false; response_revision:24348; number_of_response:1; }","duration":"308.186112ms","start":"2026-05-09T17:42:41.842885Z","end":"2026-05-09T17:42:42.151071Z","steps":["trace[1213358188] 'process raft request' (duration: 307.721361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.151132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.842865Z","time spent":"308.242144ms","remote":"127.0.0.1:59238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:42.151175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:41.8463Z","time spent":"304.801201ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:42.151446Z","caller":"traceutil/trace.go:171","msg":"trace[1464990146] transaction","detail":"{read_only:false; response_revision:24351; number_of_response:1; }","duration":"168.859479ms","start":"2026-05-09T17:42:41.982578Z","end":"2026-05-09T17:42:42.151437Z","steps":["trace[1464990146] 'process raft request' (duration: 168.398247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.151473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.141245ms","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-05-09T17:42:42.151521Z","caller":"traceutil/trace.go:171","msg":"trace[2080195671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24351; }","duration":"244.212196ms","start":"2026-05-09T17:42:41.907298Z","end":"2026-05-09T17:42:42.15151Z","steps":["trace[2080195671] 'agreement among raft nodes before linearized reading' (duration: 244.095143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.151333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.789595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:42.15331Z","caller":"traceutil/trace.go:171","msg":"trace[355634677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24351; }","duration":"115.783591ms","start":"2026-05-09T17:42:42.037489Z","end":"2026-05-09T17:42:42.153273Z","steps":["trace[355634677] 'agreement among raft nodes before linearized reading' (duration: 113.799575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:42.153624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.714051ms","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-05-09T17:42:42.153686Z","caller":"traceutil/trace.go:171","msg":"trace[268451219] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24351; }","duration":"284.811764ms","start":"2026-05-09T17:42:41.868862Z","end":"2026-05-09T17:42:42.153673Z","steps":["trace[268451219] 'agreement among raft nodes before linearized reading' (duration: 282.362866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:46.350655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.359104ms","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-05-09T17:42:46.350715Z","caller":"traceutil/trace.go:171","msg":"trace[212494200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24383; }","duration":"100.470017ms","start":"2026-05-09T17:42:46.250233Z","end":"2026-05-09T17:42:46.350703Z","steps":["trace[212494200] 'range keys from in-memory index tree' (duration: 100.222621ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:46.38664Z","caller":"traceutil/trace.go:171","msg":"trace[1135894873] transaction","detail":"{read_only:false; response_revision:24385; number_of_response:1; }","duration":"131.777704ms","start":"2026-05-09T17:42:46.254838Z","end":"2026-05-09T17:42:46.386616Z","steps":["trace[1135894873] 'process raft request' (duration: 128.697931ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:46.386618Z","caller":"traceutil/trace.go:171","msg":"trace[1666406271] linearizableReadLoop","detail":"{readStateIndex:25185; appliedIndex:25183; }","duration":"131.523017ms","start":"2026-05-09T17:42:46.255066Z","end":"2026-05-09T17:42:46.386589Z","steps":["trace[1666406271] 'read index received' (duration: 87.368345ms)","trace[1666406271] 'applied index is now lower than readState.Index' (duration: 44.153652ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:46.386804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.721534ms","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-05-09T17:42:46.386845Z","caller":"traceutil/trace.go:171","msg":"trace[2100788610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24385; }","duration":"131.776905ms","start":"2026-05-09T17:42:46.255057Z","end":"2026-05-09T17:42:46.386834Z","steps":["trace[2100788610] 'agreement among raft nodes before linearized reading' (duration: 131.62669ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:46.528251Z","caller":"traceutil/trace.go:171","msg":"trace[461338785] linearizableReadLoop","detail":"{readStateIndex:25186; appliedIndex:25185; }","duration":"141.472955ms","start":"2026-05-09T17:42:46.386754Z","end":"2026-05-09T17:42:46.528227Z","steps":["trace[461338785] 'read index received' (duration: 141.350813ms)","trace[461338785] 'applied index is now lower than readState.Index' (duration: 121.132µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:46.528297Z","caller":"traceutil/trace.go:171","msg":"trace[835549416] transaction","detail":"{read_only:false; response_revision:24386; number_of_response:1; }","duration":"172.824703ms","start":"2026-05-09T17:42:46.355433Z","end":"2026-05-09T17:42:46.528258Z","steps":["trace[835549416] 'process raft request' (duration: 172.643869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:46.528457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.156024ms","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-05-09T17:42:46.528497Z","caller":"traceutil/trace.go:171","msg":"trace[2034597370] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24386; }","duration":"270.228035ms","start":"2026-05-09T17:42:46.258255Z","end":"2026-05-09T17:42:46.528483Z","steps":["trace[2034597370] 'agreement among raft nodes before linearized reading' (duration: 270.086192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:46.528676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.009772ms","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-05-09T17:42:46.528838Z","caller":"traceutil/trace.go:171","msg":"trace[1874533086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24386; }","duration":"273.197066ms","start":"2026-05-09T17:42:46.255618Z","end":"2026-05-09T17:42:46.528815Z","steps":["trace[1874533086] 'agreement among raft nodes before linearized reading' (duration: 272.876479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:46.743228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.398651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:46.743322Z","caller":"traceutil/trace.go:171","msg":"trace[2089780053] linearizableReadLoop","detail":"{readStateIndex:25187; appliedIndex:25186; }","duration":"214.986329ms","start":"2026-05-09T17:42:46.528315Z","end":"2026-05-09T17:42:46.743301Z","steps":["trace[2089780053] 'read index received' (duration: 92.447906ms)","trace[2089780053] 'applied index is now lower than readState.Index' (duration: 122.537383ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:46.74341Z","caller":"traceutil/trace.go:171","msg":"trace[2100239402] transaction","detail":"{read_only:false; response_revision:24387; number_of_response:1; }","duration":"351.780652ms","start":"2026-05-09T17:42:46.391608Z","end":"2026-05-09T17:42:46.743388Z","steps":["trace[2100239402] 'process raft request' (duration: 229.142397ms)","trace[2100239402] 'compare' (duration: 122.295497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:46.743506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:46.391582Z","time spent":"351.878305ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:46.743515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.092711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-05-09T17:42:46.743553Z","caller":"traceutil/trace.go:171","msg":"trace[1495843273] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:24387; }","duration":"306.169953ms","start":"2026-05-09T17:42:46.437371Z","end":"2026-05-09T17:42:46.743541Z","steps":["trace[1495843273] 'agreement among raft nodes before linearized reading' (duration: 306.03378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:46.743584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:46.437352Z","time spent":"306.224335ms","remote":"127.0.0.1:59368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-09T17:42:46.749199Z","caller":"traceutil/trace.go:171","msg":"trace[1234980344] transaction","detail":"{read_only:false; response_revision:24389; number_of_response:1; }","duration":"216.174766ms","start":"2026-05-09T17:42:46.533005Z","end":"2026-05-09T17:42:46.74918Z","steps":["trace[1234980344] 'process raft request' (duration: 216.135425ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:46.749293Z","caller":"traceutil/trace.go:171","msg":"trace[1860099024] transaction","detail":"{read_only:false; response_revision:24388; number_of_response:1; }","duration":"216.526534ms","start":"2026-05-09T17:42:46.532738Z","end":"2026-05-09T17:42:46.749265Z","steps":["trace[1860099024] 'process raft request' (duration: 216.251288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:46.771969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.594375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:46.772029Z","caller":"traceutil/trace.go:171","msg":"trace[527053249] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24390; }","duration":"143.662906ms","start":"2026-05-09T17:42:46.628353Z","end":"2026-05-09T17:42:46.772016Z","steps":["trace[527053249] 'agreement among raft nodes before linearized reading' (duration: 143.576704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:47.506307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.174787ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T17:42:47.506922Z","caller":"traceutil/trace.go:171","msg":"trace[1740819130] transaction","detail":"{read_only:false; response_revision:24391; number_of_response:1; }","duration":"527.292769ms","start":"2026-05-09T17:42:46.979598Z","end":"2026-05-09T17:42:47.506891Z","steps":["trace[1740819130] 'process raft request' (duration: 526.870019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:47.507129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:46.979573Z","time spent":"527.462982ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:47.524273Z","caller":"traceutil/trace.go:171","msg":"trace[1970003190] linearizableReadLoop","detail":"{readStateIndex:25193; appliedIndex:25190; }","duration":"487.985681ms","start":"2026-05-09T17:42:47.036264Z","end":"2026-05-09T17:42:47.52425Z","steps":["trace[1970003190] 'read index received' (duration: 195.636617ms)","trace[1970003190] 'applied index is now lower than readState.Index' (duration: 292.347844ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:47.524276Z","caller":"traceutil/trace.go:171","msg":"trace[869126515] transaction","detail":"{read_only:false; response_revision:24392; number_of_response:1; }","duration":"536.204242ms","start":"2026-05-09T17:42:46.988049Z","end":"2026-05-09T17:42:47.524254Z","steps":["trace[869126515] 'process raft request' (duration: 518.825736ms)","trace[869126515] 'compare' (duration: 17.18883ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:47.525295Z","caller":"traceutil/trace.go:171","msg":"trace[595771859] transaction","detail":"{read_only:false; response_revision:24393; number_of_response:1; }","duration":"175.20569ms","start":"2026-05-09T17:42:47.350066Z","end":"2026-05-09T17:42:47.525272Z","steps":["trace[595771859] 'process raft request' (duration: 174.141834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:47.52558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:46.988021Z","time spent":"537.478092ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:47.525379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.106149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:47.525771Z","caller":"traceutil/trace.go:171","msg":"trace[1860447681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24393; }","duration":"489.534858ms","start":"2026-05-09T17:42:47.036222Z","end":"2026-05-09T17:42:47.525756Z","steps":["trace[1860447681] 'agreement among raft nodes before linearized reading' (duration: 488.153885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:47.525844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:47.036201Z","time spent":"489.630271ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T17:42:48.14496Z","caller":"traceutil/trace.go:171","msg":"trace[43239263] transaction","detail":"{read_only:false; response_revision:24394; number_of_response:1; }","duration":"236.972093ms","start":"2026-05-09T17:42:47.907964Z","end":"2026-05-09T17:42:48.144936Z","steps":["trace[43239263] 'process raft request' (duration: 236.816669ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:48.360281Z","caller":"traceutil/trace.go:171","msg":"trace[1795804221] linearizableReadLoop","detail":"{readStateIndex:25196; appliedIndex:25195; }","duration":"211.557658ms","start":"2026-05-09T17:42:48.148694Z","end":"2026-05-09T17:42:48.360251Z","steps":["trace[1795804221] 'read index received' (duration: 185.337712ms)","trace[1795804221] 'applied index is now lower than readState.Index' (duration: 26.217755ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:48.360313Z","caller":"traceutil/trace.go:171","msg":"trace[673745397] transaction","detail":"{read_only:false; response_revision:24397; number_of_response:1; }","duration":"184.02784ms","start":"2026-05-09T17:42:48.176261Z","end":"2026-05-09T17:42:48.360289Z","steps":["trace[673745397] 'process raft request' (duration: 183.974109ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:48.360415Z","caller":"traceutil/trace.go:171","msg":"trace[1070081253] transaction","detail":"{read_only:false; response_revision:24395; number_of_response:1; }","duration":"451.02402ms","start":"2026-05-09T17:42:47.909367Z","end":"2026-05-09T17:42:48.360391Z","steps":["trace[1070081253] 'process raft request' (duration: 424.743023ms)","trace[1070081253] 'compare' (duration: 25.917058ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:48.360531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:47.909342Z","time spent":"451.112031ms","remote":"127.0.0.1:59518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:48.360594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.883615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18adf695eba611ab\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-05-09T17:42:48.360651Z","caller":"traceutil/trace.go:171","msg":"trace[28837515] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18adf695eba611ab; range_end:; response_count:1; response_revision:24397; }","duration":"211.979768ms","start":"2026-05-09T17:42:48.148656Z","end":"2026-05-09T17:42:48.360636Z","steps":["trace[28837515] 'agreement among raft nodes before linearized reading' (duration: 211.831754ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:48.360599Z","caller":"traceutil/trace.go:171","msg":"trace[1683857445] transaction","detail":"{read_only:false; response_revision:24396; number_of_response:1; }","duration":"184.905161ms","start":"2026-05-09T17:42:48.175679Z","end":"2026-05-09T17:42:48.360584Z","steps":["trace[1683857445] 'process raft request' (duration: 184.494482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.362355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.664764ms","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-05-09T17:42:48.362403Z","caller":"traceutil/trace.go:171","msg":"trace[1456084885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24398; }","duration":"186.713835ms","start":"2026-05-09T17:42:48.175676Z","end":"2026-05-09T17:42:48.36239Z","steps":["trace[1456084885] 'agreement among raft nodes before linearized reading' (duration: 186.584982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.362807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.095083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T17:42:48.36284Z","caller":"traceutil/trace.go:171","msg":"trace[1754828827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24398; }","duration":"187.167705ms","start":"2026-05-09T17:42:48.175666Z","end":"2026-05-09T17:42:48.362833Z","steps":["trace[1754828827] 'agreement among raft nodes before linearized reading' (duration: 187.114304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.660978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.320185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:48.661183Z","caller":"traceutil/trace.go:171","msg":"trace[2020199112] linearizableReadLoop","detail":"{readStateIndex:25202; appliedIndex:25200; }","duration":"274.404656ms","start":"2026-05-09T17:42:48.386763Z","end":"2026-05-09T17:42:48.661167Z","steps":["trace[2020199112] 'read index received' (duration: 162.823555ms)","trace[2020199112] 'applied index is now lower than readState.Index' (duration: 111.580251ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:48.661417Z","caller":"traceutil/trace.go:171","msg":"trace[43339878] transaction","detail":"{read_only:false; response_revision:24400; number_of_response:1; }","duration":"288.886121ms","start":"2026-05-09T17:42:48.372517Z","end":"2026-05-09T17:42:48.661404Z","steps":["trace[43339878] 'process raft request' (duration: 177.060774ms)","trace[43339878] 'compare' (duration: 110.699721ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:48.661583Z","caller":"traceutil/trace.go:171","msg":"trace[825180932] transaction","detail":"{read_only:false; response_revision:24401; number_of_response:1; }","duration":"288.993815ms","start":"2026-05-09T17:42:48.372578Z","end":"2026-05-09T17:42:48.661571Z","steps":["trace[825180932] 'process raft request' (duration: 288.501622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.661797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.026262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18adf695575bb3e2\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-05-09T17:42:48.661834Z","caller":"traceutil/trace.go:171","msg":"trace[25219373] range","detail":"{range_begin:/registry/events/openstack/container-infra.18adf695575bb3e2; range_end:; response_count:1; response_revision:24401; }","duration":"275.089174ms","start":"2026-05-09T17:42:48.386735Z","end":"2026-05-09T17:42:48.661824Z","steps":["trace[25219373] 'agreement among raft nodes before linearized reading' (duration: 274.962151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.663038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.345029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:48.663147Z","caller":"traceutil/trace.go:171","msg":"trace[278110165] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24402; }","duration":"275.478221ms","start":"2026-05-09T17:42:48.387642Z","end":"2026-05-09T17:42:48.66312Z","steps":["trace[278110165] 'agreement among raft nodes before linearized reading' (duration: 275.300677ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:48.663633Z","caller":"traceutil/trace.go:171","msg":"trace[1736798439] transaction","detail":"{read_only:false; response_revision:24402; number_of_response:1; }","duration":"272.534661ms","start":"2026-05-09T17:42:48.391083Z","end":"2026-05-09T17:42:48.663618Z","steps":["trace[1736798439] 'process raft request' (duration: 271.705461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.664075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.287401ms","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-05-09T17:42:48.66412Z","caller":"traceutil/trace.go:171","msg":"trace[783236452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24402; }","duration":"131.371053ms","start":"2026-05-09T17:42:48.532738Z","end":"2026-05-09T17:42:48.664109Z","steps":["trace[783236452] 'agreement among raft nodes before linearized reading' (duration: 131.237589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.958209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.45542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:48.958464Z","caller":"traceutil/trace.go:171","msg":"trace[1843017730] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"290.155901ms","start":"2026-05-09T17:42:48.668263Z","end":"2026-05-09T17:42:48.958419Z","steps":["trace[1843017730] 'process raft request' (duration: 144.395924ms)","trace[1843017730] 'compare' (duration: 145.320247ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:48.95856Z","caller":"traceutil/trace.go:171","msg":"trace[432195940] transaction","detail":"{read_only:false; response_revision:24405; number_of_response:1; }","duration":"182.012932ms","start":"2026-05-09T17:42:48.77652Z","end":"2026-05-09T17:42:48.958532Z","steps":["trace[432195940] 'process raft request' (duration: 181.91617ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:48.958647Z","caller":"traceutil/trace.go:171","msg":"trace[288987349] linearizableReadLoop","detail":"{readStateIndex:25205; appliedIndex:25203; }","duration":"289.20542ms","start":"2026-05-09T17:42:48.669417Z","end":"2026-05-09T17:42:48.958623Z","steps":["trace[288987349] 'read index received' (duration: 64.470238ms)","trace[288987349] 'applied index is now lower than readState.Index' (duration: 224.733712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:48.958849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.405365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:48.958917Z","caller":"traceutil/trace.go:171","msg":"trace[313652888] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24405; }","duration":"289.528258ms","start":"2026-05-09T17:42:48.669375Z","end":"2026-05-09T17:42:48.958903Z","steps":["trace[313652888] 'agreement among raft nodes before linearized reading' (duration: 289.397525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.958957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.338575ms","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-05-09T17:42:48.958998Z","caller":"traceutil/trace.go:171","msg":"trace[425964486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24405; }","duration":"212.426868ms","start":"2026-05-09T17:42:48.746559Z","end":"2026-05-09T17:42:48.958986Z","steps":["trace[425964486] 'agreement among raft nodes before linearized reading' (duration: 212.276544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.959163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.118958ms","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-05-09T17:42:48.959198Z","caller":"traceutil/trace.go:171","msg":"trace[794298057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24405; }","duration":"206.185379ms","start":"2026-05-09T17:42:48.752998Z","end":"2026-05-09T17:42:48.959183Z","steps":["trace[794298057] 'agreement among raft nodes before linearized reading' (duration: 206.126608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:48.959209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.118567ms","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-05-09T17:42:48.959272Z","caller":"traceutil/trace.go:171","msg":"trace[1847121854] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24405; }","duration":"206.197699ms","start":"2026-05-09T17:42:48.753056Z","end":"2026-05-09T17:42:48.959254Z","steps":["trace[1847121854] 'agreement among raft nodes before linearized reading' (duration: 206.015215ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:49.266432Z","caller":"traceutil/trace.go:171","msg":"trace[585539715] transaction","detail":"{read_only:false; response_revision:24406; number_of_response:1; }","duration":"303.89291ms","start":"2026-05-09T17:42:48.962508Z","end":"2026-05-09T17:42:49.266401Z","steps":["trace[585539715] 'process raft request' (duration: 205.487322ms)","trace[585539715] 'compare' (duration: 98.167422ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:49.267199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:48.962487Z","time spent":"304.626967ms","remote":"127.0.0.1:59480","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-05-09T17:42:49.2706Z","caller":"traceutil/trace.go:171","msg":"trace[1922397711] transaction","detail":"{read_only:false; response_revision:24407; number_of_response:1; }","duration":"306.342428ms","start":"2026-05-09T17:42:48.964228Z","end":"2026-05-09T17:42:49.270571Z","steps":["trace[1922397711] 'process raft request' (duration: 306.117832ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:49.270747Z","caller":"traceutil/trace.go:171","msg":"trace[1334230717] linearizableReadLoop","detail":"{readStateIndex:25209; appliedIndex:25206; }","duration":"234.802311ms","start":"2026-05-09T17:42:49.035914Z","end":"2026-05-09T17:42:49.270716Z","steps":["trace[1334230717] 'read index received' (duration: 132.102901ms)","trace[1334230717] 'applied index is now lower than readState.Index' (duration: 102.69746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:49.270787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:48.96421Z","time spent":"306.478451ms","remote":"127.0.0.1:59480","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-05-09T17:42:49.27087Z","caller":"traceutil/trace.go:171","msg":"trace[1871144239] transaction","detail":"{read_only:false; response_revision:24408; number_of_response:1; }","duration":"306.236895ms","start":"2026-05-09T17:42:48.964609Z","end":"2026-05-09T17:42:49.270846Z","steps":["trace[1871144239] 'process raft request' (duration: 305.892747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:49.270936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.001986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:49.27097Z","caller":"traceutil/trace.go:171","msg":"trace[705680552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24408; }","duration":"235.092879ms","start":"2026-05-09T17:42:49.035866Z","end":"2026-05-09T17:42:49.270959Z","steps":["trace[705680552] 'agreement among raft nodes before linearized reading' (duration: 235.010677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:49.271004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:48.964592Z","time spent":"306.357238ms","remote":"127.0.0.1:59480","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-05-09T17:42:49.271195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.863685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:49.271246Z","caller":"traceutil/trace.go:171","msg":"trace[1842350353] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:24408; }","duration":"118.916976ms","start":"2026-05-09T17:42:49.152315Z","end":"2026-05-09T17:42:49.271232Z","steps":["trace[1842350353] 'agreement among raft nodes before linearized reading' (duration: 118.833734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:49.850811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.151724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:49.850918Z","caller":"traceutil/trace.go:171","msg":"trace[302357144] linearizableReadLoop","detail":"{readStateIndex:25211; appliedIndex:25210; }","duration":"203.158835ms","start":"2026-05-09T17:42:49.647742Z","end":"2026-05-09T17:42:49.850901Z","steps":["trace[302357144] 'read index received' (duration: 93.892199ms)","trace[302357144] 'applied index is now lower than readState.Index' (duration: 109.265346ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:49.850946Z","caller":"traceutil/trace.go:171","msg":"trace[1822890136] transaction","detail":"{read_only:false; response_revision:24410; number_of_response:1; }","duration":"320.67937ms","start":"2026-05-09T17:42:49.530238Z","end":"2026-05-09T17:42:49.850917Z","steps":["trace[1822890136] 'process raft request' (duration: 211.361802ms)","trace[1822890136] 'compare' (duration: 108.837917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:49.851095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.357831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-09T17:42:49.851099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:49.530219Z","time spent":"320.797762ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:49.851134Z","caller":"traceutil/trace.go:171","msg":"trace[1363568419] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24410; }","duration":"203.516365ms","start":"2026-05-09T17:42:49.647608Z","end":"2026-05-09T17:42:49.851124Z","steps":["trace[1363568419] 'agreement among raft nodes before linearized reading' (duration: 203.341181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:50.179199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.197385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:50.179282Z","caller":"traceutil/trace.go:171","msg":"trace[519629060] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24410; }","duration":"226.274997ms","start":"2026-05-09T17:42:49.952972Z","end":"2026-05-09T17:42:50.179247Z","steps":["trace[519629060] 'range keys from in-memory index tree' (duration: 226.167175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:50.179582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.636071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:42:50.179612Z","caller":"traceutil/trace.go:171","msg":"trace[1586154551] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:24410; }","duration":"100.704072ms","start":"2026-05-09T17:42:50.0789Z","end":"2026-05-09T17:42:50.179604Z","steps":["trace[1586154551] 'count revisions from in-memory index tree' (duration: 100.574948ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:50.739038Z","caller":"traceutil/trace.go:171","msg":"trace[829820255] transaction","detail":"{read_only:false; response_revision:24411; number_of_response:1; }","duration":"372.798643ms","start":"2026-05-09T17:42:50.366218Z","end":"2026-05-09T17:42:50.739017Z","steps":["trace[829820255] 'process raft request' (duration: 372.634609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:50.739181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:50.366194Z","time spent":"372.919006ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:50.751671Z","caller":"traceutil/trace.go:171","msg":"trace[444484017] linearizableReadLoop","detail":"{readStateIndex:25214; appliedIndex:25211; }","duration":"371.392031ms","start":"2026-05-09T17:42:50.38026Z","end":"2026-05-09T17:42:50.751652Z","steps":["trace[444484017] 'read index received' (duration: 358.577895ms)","trace[444484017] 'applied index is now lower than readState.Index' (duration: 12.813206ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:50.751947Z","caller":"traceutil/trace.go:171","msg":"trace[321402566] transaction","detail":"{read_only:false; response_revision:24412; number_of_response:1; }","duration":"383.343105ms","start":"2026-05-09T17:42:50.368559Z","end":"2026-05-09T17:42:50.751902Z","steps":["trace[321402566] 'process raft request' (duration: 382.900454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:50.752035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:50.368525Z","time spent":"383.455957ms","remote":"127.0.0.1:59480","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-05-09T17:42:50.75226Z","caller":"traceutil/trace.go:171","msg":"trace[1080105700] transaction","detail":"{read_only:false; response_revision:24413; number_of_response:1; }","duration":"383.587941ms","start":"2026-05-09T17:42:50.368661Z","end":"2026-05-09T17:42:50.752249Z","steps":["trace[1080105700] 'process raft request' (duration: 382.884194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:50.752325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:50.368647Z","time spent":"383.644822ms","remote":"127.0.0.1:59480","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-05-09T17:42:50.752371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.046383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:50.752466Z","caller":"traceutil/trace.go:171","msg":"trace[1744539663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24415; }","duration":"361.168986ms","start":"2026-05-09T17:42:50.391273Z","end":"2026-05-09T17:42:50.752442Z","steps":["trace[1744539663] 'agreement among raft nodes before linearized reading' (duration: 361.015793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:50.752515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:50.391264Z","time spent":"361.243558ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T17:42:50.752759Z","caller":"traceutil/trace.go:171","msg":"trace[1993537663] transaction","detail":"{read_only:false; response_revision:24414; number_of_response:1; }","duration":"175.987788ms","start":"2026-05-09T17:42:50.576756Z","end":"2026-05-09T17:42:50.752743Z","steps":["trace[1993537663] 'process raft request' (duration: 174.829731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:50.752936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.665831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:50.752979Z","caller":"traceutil/trace.go:171","msg":"trace[203806246] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24415; }","duration":"372.727331ms","start":"2026-05-09T17:42:50.380235Z","end":"2026-05-09T17:42:50.752962Z","steps":["trace[203806246] 'agreement among raft nodes before linearized reading' (duration: 372.66399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:50.753017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:50.380223Z","time spent":"372.783192ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:42:50.753215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.445969ms","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-05-09T17:42:50.75325Z","caller":"traceutil/trace.go:171","msg":"trace[1738849962] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24415; }","duration":"277.50854ms","start":"2026-05-09T17:42:50.475727Z","end":"2026-05-09T17:42:50.753235Z","steps":["trace[1738849962] 'agreement among raft nodes before linearized reading' (duration: 277.454349ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:50.928673Z","caller":"traceutil/trace.go:171","msg":"trace[100847745] transaction","detail":"{read_only:false; response_revision:24416; number_of_response:1; }","duration":"171.897742ms","start":"2026-05-09T17:42:50.756749Z","end":"2026-05-09T17:42:50.928647Z","steps":["trace[100847745] 'process raft request' (duration: 171.740798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:51.161139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.982025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:51.161271Z","caller":"traceutil/trace.go:171","msg":"trace[1891232103] transaction","detail":"{read_only:false; response_revision:24419; number_of_response:1; }","duration":"196.983979ms","start":"2026-05-09T17:42:50.964277Z","end":"2026-05-09T17:42:51.161261Z","steps":["trace[1891232103] 'process raft request' (duration: 196.962208ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:51.161449Z","caller":"traceutil/trace.go:171","msg":"trace[452347469] linearizableReadLoop","detail":"{readStateIndex:25218; appliedIndex:25216; }","duration":"372.094187ms","start":"2026-05-09T17:42:50.789349Z","end":"2026-05-09T17:42:51.161443Z","steps":["trace[452347469] 'read index received' (duration: 139.167741ms)","trace[452347469] 'applied index is now lower than readState.Index' (duration: 232.925686ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:51.161459Z","caller":"traceutil/trace.go:171","msg":"trace[1084769036] transaction","detail":"{read_only:false; response_revision:24418; number_of_response:1; }","duration":"274.714734ms","start":"2026-05-09T17:42:50.886708Z","end":"2026-05-09T17:42:51.161423Z","steps":["trace[1084769036] 'process raft request' (duration: 274.490909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:51.161545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.19419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:51.161563Z","caller":"traceutil/trace.go:171","msg":"trace[48337863] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24419; }","duration":"372.248551ms","start":"2026-05-09T17:42:50.789309Z","end":"2026-05-09T17:42:51.161558Z","steps":["trace[48337863] 'agreement among raft nodes before linearized reading' (duration: 372.21545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:51.161579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:50.789293Z","time spent":"372.281391ms","remote":"127.0.0.1:38272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:42:51.161687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.930299ms","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-05-09T17:42:51.1617Z","caller":"traceutil/trace.go:171","msg":"trace[1399322891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24419; }","duration":"199.97777ms","start":"2026-05-09T17:42:50.961719Z","end":"2026-05-09T17:42:51.161696Z","steps":["trace[1399322891] 'agreement among raft nodes before linearized reading' (duration: 199.928119ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:51.161742Z","caller":"traceutil/trace.go:171","msg":"trace[244939990] transaction","detail":"{read_only:false; response_revision:24417; number_of_response:1; }","duration":"404.748115ms","start":"2026-05-09T17:42:50.756977Z","end":"2026-05-09T17:42:51.161725Z","steps":["trace[244939990] 'process raft request' (duration: 273.142295ms)","trace[244939990] 'compare' (duration: 130.903973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:51.161891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.782177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:42:51.1619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:50.756969Z","time spent":"404.812296ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:51.161953Z","caller":"traceutil/trace.go:171","msg":"trace[609491671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24419; }","duration":"124.831947ms","start":"2026-05-09T17:42:51.037071Z","end":"2026-05-09T17:42:51.161903Z","steps":["trace[609491671] 'agreement among raft nodes before linearized reading' (duration: 124.807357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:51.577977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.461833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:51.57805Z","caller":"traceutil/trace.go:171","msg":"trace[117906252] linearizableReadLoop","detail":"{readStateIndex:25221; appliedIndex:25220; }","duration":"305.207681ms","start":"2026-05-09T17:42:51.272831Z","end":"2026-05-09T17:42:51.578039Z","steps":["trace[117906252] 'read index received' (duration: 39.609214ms)","trace[117906252] 'applied index is now lower than readState.Index' (duration: 265.597577ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:51.578203Z","caller":"traceutil/trace.go:171","msg":"trace[197325618] transaction","detail":"{read_only:false; response_revision:24420; number_of_response:1; }","duration":"410.36403ms","start":"2026-05-09T17:42:51.16783Z","end":"2026-05-09T17:42:51.578194Z","steps":["trace[197325618] 'process raft request' (duration: 144.628041ms)","trace[197325618] 'compare' (duration: 264.772166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:51.578249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:51.16782Z","time spent":"410.407001ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:51.578424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.601971ms","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-05-09T17:42:51.578443Z","caller":"traceutil/trace.go:171","msg":"trace[328439670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24420; }","duration":"305.654952ms","start":"2026-05-09T17:42:51.272783Z","end":"2026-05-09T17:42:51.578438Z","steps":["trace[328439670] 'agreement among raft nodes before linearized reading' (duration: 305.57903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:51.578458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:51.272764Z","time spent":"305.690452ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T17:42:51.578551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.425515ms","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-05-09T17:42:51.578569Z","caller":"traceutil/trace.go:171","msg":"trace[2126962812] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24420; }","duration":"302.481836ms","start":"2026-05-09T17:42:51.276082Z","end":"2026-05-09T17:42:51.578564Z","steps":["trace[2126962812] 'agreement among raft nodes before linearized reading' (duration: 302.437975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:51.578581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:51.276061Z","time spent":"302.517447ms","remote":"127.0.0.1:59480","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-05-09T17:42:51.578827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.23101ms","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-05-09T17:42:51.578846Z","caller":"traceutil/trace.go:171","msg":"trace[60051057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24420; }","duration":"302.268752ms","start":"2026-05-09T17:42:51.276574Z","end":"2026-05-09T17:42:51.578842Z","steps":["trace[60051057] 'agreement among raft nodes before linearized reading' (duration: 302.227281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:51.578859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:51.276565Z","time spent":"302.290482ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T17:43:01.845006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.91307ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-09T17:43:01.845293Z","caller":"traceutil/trace.go:171","msg":"trace[834309068] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"158.665666ms","start":"2026-05-09T17:43:01.686607Z","end":"2026-05-09T17:43:01.845273Z","steps":["trace[834309068] 'process raft request' (duration: 158.523292ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:01.845322Z","caller":"traceutil/trace.go:171","msg":"trace[1843198743] linearizableReadLoop","detail":"{readStateIndex:25354; appliedIndex:25353; }","duration":"210.80683ms","start":"2026-05-09T17:43:01.634486Z","end":"2026-05-09T17:43:01.845293Z","steps":["trace[1843198743] 'read index received' (duration: 50.552µs)","trace[1843198743] 'applied index is now lower than readState.Index' (duration: 210.753148ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:01.845132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:01.534465Z","time spent":"310.661041ms","remote":"127.0.0.1:59238","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-09T17:43:01.845502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.012815ms","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-05-09T17:43:01.845544Z","caller":"traceutil/trace.go:171","msg":"trace[1023425562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24551; }","duration":"211.173939ms","start":"2026-05-09T17:43:01.634356Z","end":"2026-05-09T17:43:01.84553Z","steps":["trace[1023425562] 'agreement among raft nodes before linearized reading' (duration: 211.011815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:01.845598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.993202ms","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-05-09T17:43:01.845598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.294695ms","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-05-09T17:43:01.845604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.379216ms","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-05-09T17:43:01.84564Z","caller":"traceutil/trace.go:171","msg":"trace[638258135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24551; }","duration":"151.071024ms","start":"2026-05-09T17:43:01.694555Z","end":"2026-05-09T17:43:01.845626Z","steps":["trace[638258135] 'agreement among raft nodes before linearized reading' (duration: 150.921041ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:01.845647Z","caller":"traceutil/trace.go:171","msg":"trace[1258817727] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24551; }","duration":"147.379346ms","start":"2026-05-09T17:43:01.698253Z","end":"2026-05-09T17:43:01.845633Z","steps":["trace[1258817727] 'agreement among raft nodes before linearized reading' (duration: 147.239203ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:01.845652Z","caller":"traceutil/trace.go:171","msg":"trace[42660355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24551; }","duration":"147.449618ms","start":"2026-05-09T17:43:01.698189Z","end":"2026-05-09T17:43:01.845639Z","steps":["trace[42660355] 'agreement among raft nodes before linearized reading' (duration: 147.318705ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:01.976036Z","caller":"traceutil/trace.go:171","msg":"trace[1409830056] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"129.056449ms","start":"2026-05-09T17:43:01.846952Z","end":"2026-05-09T17:43:01.976008Z","steps":["trace[1409830056] 'process raft request' (duration: 123.718551ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:02.1549Z","caller":"traceutil/trace.go:171","msg":"trace[1076099854] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"305.421346ms","start":"2026-05-09T17:43:01.849459Z","end":"2026-05-09T17:43:02.154881Z","steps":["trace[1076099854] 'process raft request' (duration: 238.54855ms)","trace[1076099854] 'compare' (duration: 66.653421ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:02.155021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:01.849437Z","time spent":"305.523008ms","remote":"127.0.0.1:59480","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-05-09T17:43:02.15525Z","caller":"traceutil/trace.go:171","msg":"trace[770513463] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"305.57789ms","start":"2026-05-09T17:43:01.849639Z","end":"2026-05-09T17:43:02.155216Z","steps":["trace[770513463] 'process raft request' (duration: 305.203301ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:02.155275Z","caller":"traceutil/trace.go:171","msg":"trace[1961074501] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"305.559449ms","start":"2026-05-09T17:43:01.849692Z","end":"2026-05-09T17:43:02.155251Z","steps":["trace[1961074501] 'process raft request' (duration: 305.392855ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:02.155309Z","caller":"traceutil/trace.go:171","msg":"trace[812076638] linearizableReadLoop","detail":"{readStateIndex:25361; appliedIndex:25356; }","duration":"176.49172ms","start":"2026-05-09T17:43:01.978795Z","end":"2026-05-09T17:43:02.155286Z","steps":["trace[812076638] 'read index received' (duration: 109.202235ms)","trace[812076638] 'applied index is now lower than readState.Index' (duration: 67.288505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:02.155404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:01.84967Z","time spent":"305.659101ms","remote":"127.0.0.1:59480","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-05-09T17:43:02.155408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:01.849629Z","time spent":"305.682302ms","remote":"127.0.0.1:59480","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-05-09T17:43:02.155461Z","caller":"traceutil/trace.go:171","msg":"trace[1096315116] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"305.537668ms","start":"2026-05-09T17:43:01.849909Z","end":"2026-05-09T17:43:02.155447Z","steps":["trace[1096315116] 'process raft request' (duration: 305.286562ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:02.155568Z","caller":"traceutil/trace.go:171","msg":"trace[1274108300] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"264.770736ms","start":"2026-05-09T17:43:01.890786Z","end":"2026-05-09T17:43:02.155556Z","steps":["trace[1274108300] 'process raft request' (duration: 264.455828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:02.155607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.820918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-05-09T17:43:02.155612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:01.849897Z","time spent":"305.635351ms","remote":"127.0.0.1:59480","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-05-09T17:43:02.155636Z","caller":"traceutil/trace.go:171","msg":"trace[537140448] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24557; }","duration":"176.91495ms","start":"2026-05-09T17:43:01.978714Z","end":"2026-05-09T17:43:02.155629Z","steps":["trace[537140448] 'agreement among raft nodes before linearized reading' (duration: 176.817908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:02.155667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.482797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:02.155698Z","caller":"traceutil/trace.go:171","msg":"trace[2029901599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24557; }","duration":"121.532889ms","start":"2026-05-09T17:43:02.034156Z","end":"2026-05-09T17:43:02.155689Z","steps":["trace[2029901599] 'agreement among raft nodes before linearized reading' (duration: 121.486318ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:02.471454Z","caller":"traceutil/trace.go:171","msg":"trace[1379534575] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"299.596067ms","start":"2026-05-09T17:43:02.1718Z","end":"2026-05-09T17:43:02.471396Z","steps":["trace[1379534575] 'process raft request' (duration: 284.230691ms)","trace[1379534575] 'compare' (duration: 15.195372ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:02.785972Z","caller":"traceutil/trace.go:171","msg":"trace[212084210] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"245.598048ms","start":"2026-05-09T17:43:02.540357Z","end":"2026-05-09T17:43:02.785955Z","steps":["trace[212084210] 'process raft request' (duration: 245.465425ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:02.884448Z","caller":"traceutil/trace.go:171","msg":"trace[1042100017] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"102.847544ms","start":"2026-05-09T17:43:02.781571Z","end":"2026-05-09T17:43:02.884418Z","steps":["trace[1042100017] 'process raft request' (duration: 102.248009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:03.229345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.884635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:43:03.229382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.804375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:03.229453Z","caller":"traceutil/trace.go:171","msg":"trace[1691568611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24563; }","duration":"194.0592ms","start":"2026-05-09T17:43:03.035375Z","end":"2026-05-09T17:43:03.229434Z","steps":["trace[1691568611] 'range keys from in-memory index tree' (duration: 193.792373ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:03.229478Z","caller":"traceutil/trace.go:171","msg":"trace[1902484129] 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:24563; }","duration":"209.957599ms","start":"2026-05-09T17:43:03.019498Z","end":"2026-05-09T17:43:03.229455Z","steps":["trace[1902484129] 'count revisions from in-memory index tree' (duration: 209.665122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:03.229597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.467629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:03.229688Z","caller":"traceutil/trace.go:171","msg":"trace[1689378449] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:24563; }","duration":"121.604902ms","start":"2026-05-09T17:43:03.108061Z","end":"2026-05-09T17:43:03.229666Z","steps":["trace[1689378449] 'count revisions from in-memory index tree' (duration: 121.378196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:03.229892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.829059ms","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-05-09T17:43:03.229953Z","caller":"traceutil/trace.go:171","msg":"trace[730570195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24563; }","duration":"241.927121ms","start":"2026-05-09T17:43:02.988012Z","end":"2026-05-09T17:43:03.229939Z","steps":["trace[730570195] 'range keys from in-memory index tree' (duration: 241.650035ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:03.312529Z","caller":"traceutil/trace.go:171","msg":"trace[1397368693] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"107.412271ms","start":"2026-05-09T17:43:03.205097Z","end":"2026-05-09T17:43:03.312509Z","steps":["trace[1397368693] 'process raft request' (duration: 107.124305ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:03.54131Z","caller":"traceutil/trace.go:171","msg":"trace[1675874233] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"224.340681ms","start":"2026-05-09T17:43:03.316942Z","end":"2026-05-09T17:43:03.541283Z","steps":["trace[1675874233] 'process raft request' (duration: 224.29446ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:03.541676Z","caller":"traceutil/trace.go:171","msg":"trace[1238683044] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"307.759632ms","start":"2026-05-09T17:43:03.233903Z","end":"2026-05-09T17:43:03.541662Z","steps":["trace[1238683044] 'process raft request' (duration: 230.398437ms)","trace[1238683044] 'compare' (duration: 76.796322ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:03.541734Z","caller":"traceutil/trace.go:171","msg":"trace[1062189805] linearizableReadLoop","detail":"{readStateIndex:25369; appliedIndex:25368; }","duration":"229.227219ms","start":"2026-05-09T17:43:03.312455Z","end":"2026-05-09T17:43:03.541682Z","steps":["trace[1062189805] 'read index received' (duration: 151.878994ms)","trace[1062189805] 'applied index is now lower than readState.Index' (duration: 77.344835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:03.541777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:03.233886Z","time spent":"307.822803ms","remote":"127.0.0.1:59480","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-05-09T17:43:03.541856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.949908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:03.541936Z","caller":"traceutil/trace.go:171","msg":"trace[861117249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24566; }","duration":"311.07578ms","start":"2026-05-09T17:43:03.230841Z","end":"2026-05-09T17:43:03.541917Z","steps":["trace[861117249] 'agreement among raft nodes before linearized reading' (duration: 310.947837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:03.541981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:03.230821Z","time spent":"311.152353ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T17:43:03.989175Z","caller":"traceutil/trace.go:171","msg":"trace[202303392] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"138.719159ms","start":"2026-05-09T17:43:03.850433Z","end":"2026-05-09T17:43:03.989152Z","steps":["trace[202303392] 'process raft request' (duration: 138.551445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:04.332743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.982797ms","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-05-09T17:43:04.332833Z","caller":"traceutil/trace.go:171","msg":"trace[750190946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24567; }","duration":"173.086869ms","start":"2026-05-09T17:43:04.159723Z","end":"2026-05-09T17:43:04.33281Z","steps":["trace[750190946] 'range keys from in-memory index tree' (duration: 172.815573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:04.332904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.852074ms","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-05-09T17:43:04.332935Z","caller":"traceutil/trace.go:171","msg":"trace[1703051831] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24567; }","duration":"172.967407ms","start":"2026-05-09T17:43:04.159957Z","end":"2026-05-09T17:43:04.332925Z","steps":["trace[1703051831] 'range keys from in-memory index tree' (duration: 172.750341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:04.332963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.879418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-09T17:43:04.333002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.944809ms","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-05-09T17:43:04.333042Z","caller":"traceutil/trace.go:171","msg":"trace[1867637386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24567; }","duration":"174.003561ms","start":"2026-05-09T17:43:04.159019Z","end":"2026-05-09T17:43:04.333022Z","steps":["trace[1867637386] 'range keys from in-memory index tree' (duration: 173.704984ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:04.33309Z","caller":"traceutil/trace.go:171","msg":"trace[1752359787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24567; }","duration":"174.081422ms","start":"2026-05-09T17:43:04.158989Z","end":"2026-05-09T17:43:04.333071Z","steps":["trace[1752359787] 'range keys from in-memory index tree' (duration: 173.717984ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:04.333092Z","caller":"traceutil/trace.go:171","msg":"trace[1284973189] linearizableReadLoop","detail":"{readStateIndex:25372; appliedIndex:25371; }","duration":"114.917691ms","start":"2026-05-09T17:43:04.218163Z","end":"2026-05-09T17:43:04.333081Z","steps":["trace[1284973189] 'read index received' (duration: 65.350339ms)","trace[1284973189] 'applied index is now lower than readState.Index' (duration: 49.565972ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:04.333212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.049464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:04.333252Z","caller":"traceutil/trace.go:171","msg":"trace[1391427928] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24568; }","duration":"115.118826ms","start":"2026-05-09T17:43:04.218123Z","end":"2026-05-09T17:43:04.333242Z","steps":["trace[1391427928] 'agreement among raft nodes before linearized reading' (duration: 114.994593ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:04.333329Z","caller":"traceutil/trace.go:171","msg":"trace[435686919] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"172.646099ms","start":"2026-05-09T17:43:04.160622Z","end":"2026-05-09T17:43:04.333268Z","steps":["trace[435686919] 'process raft request' (duration: 122.871581ms)","trace[435686919] 'compare' (duration: 49.048031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:04.586358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.132623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:43:04.586508Z","caller":"traceutil/trace.go:171","msg":"trace[743408932] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"248.842906ms","start":"2026-05-09T17:43:04.337642Z","end":"2026-05-09T17:43:04.586485Z","steps":["trace[743408932] 'process raft request' (duration: 126.465697ms)","trace[743408932] 'compare' (duration: 121.762915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:04.954808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.308707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:43:04.955007Z","caller":"traceutil/trace.go:171","msg":"trace[1720524808] linearizableReadLoop","detail":"{readStateIndex:25376; appliedIndex:25373; }","duration":"408.620338ms","start":"2026-05-09T17:43:04.546373Z","end":"2026-05-09T17:43:04.954994Z","steps":["trace[1720524808] 'read index received' (duration: 167.029535ms)","trace[1720524808] 'applied index is now lower than readState.Index' (duration: 241.589863ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:04.955063Z","caller":"traceutil/trace.go:171","msg":"trace[294213755] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"617.323937ms","start":"2026-05-09T17:43:04.337732Z","end":"2026-05-09T17:43:04.955056Z","steps":["trace[294213755] 'process raft request' (duration: 617.232055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:04.955107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:04.337698Z","time spent":"617.387949ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:43:04.955061Z","caller":"traceutil/trace.go:171","msg":"trace[1425470747] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"617.349349ms","start":"2026-05-09T17:43:04.337682Z","end":"2026-05-09T17:43:04.955032Z","steps":["trace[1425470747] 'process raft request' (duration: 617.214075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:04.955205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:04.337661Z","time spent":"617.527082ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:43:04.955306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.920925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e7b5436-6580-4bac-a0cf-ef4302056de8\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-05-09T17:43:04.955403Z","caller":"traceutil/trace.go:171","msg":"trace[773671322] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0e7b5436-6580-4bac-a0cf-ef4302056de8; range_end:; response_count:1; response_revision:24572; }","duration":"409.076079ms","start":"2026-05-09T17:43:04.546313Z","end":"2026-05-09T17:43:04.955389Z","steps":["trace[773671322] 'agreement among raft nodes before linearized reading' (duration: 408.743151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:04.95545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:04.546286Z","time spent":"409.15621ms","remote":"127.0.0.1:59402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3780,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e7b5436-6580-4bac-a0cf-ef4302056de8\" "} {"level":"info","ts":"2026-05-09T17:43:04.954992Z","caller":"traceutil/trace.go:171","msg":"trace[1624846609] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"617.303127ms","start":"2026-05-09T17:43:04.337663Z","end":"2026-05-09T17:43:04.954966Z","steps":["trace[1624846609] 'process raft request' (duration: 375.735774ms)","trace[1624846609] 'compare' (duration: 240.411255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:04.95581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:04.337646Z","time spent":"618.112046ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:43:05.132376Z","caller":"traceutil/trace.go:171","msg":"trace[163639008] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"274.040728ms","start":"2026-05-09T17:43:04.858293Z","end":"2026-05-09T17:43:05.132334Z","steps":["trace[163639008] 'process raft request' (duration: 242.731221ms)","trace[163639008] 'compare' (duration: 31.119212ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:05.133531Z","caller":"traceutil/trace.go:171","msg":"trace[1761827591] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"241.02822ms","start":"2026-05-09T17:43:04.892463Z","end":"2026-05-09T17:43:05.133491Z","steps":["trace[1761827591] 'process raft request' (duration: 240.721883ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:05.133589Z","caller":"traceutil/trace.go:171","msg":"trace[175736947] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"241.064132ms","start":"2026-05-09T17:43:04.892504Z","end":"2026-05-09T17:43:05.133568Z","steps":["trace[175736947] 'process raft request' (duration: 240.789144ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:05.133574Z","caller":"traceutil/trace.go:171","msg":"trace[1236798756] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"239.419441ms","start":"2026-05-09T17:43:04.89412Z","end":"2026-05-09T17:43:05.133539Z","steps":["trace[1236798756] 'process raft request' (duration: 239.291128ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:05.133488Z","caller":"traceutil/trace.go:171","msg":"trace[34939434] linearizableReadLoop","detail":"{readStateIndex:25381; appliedIndex:25376; }","duration":"178.434485ms","start":"2026-05-09T17:43:04.955029Z","end":"2026-05-09T17:43:05.133464Z","steps":["trace[34939434] 'read index received' (duration: 146.072354ms)","trace[34939434] 'applied index is now lower than readState.Index' (duration: 32.360731ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:05.133809Z","caller":"traceutil/trace.go:171","msg":"trace[1283793470] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"240.386014ms","start":"2026-05-09T17:43:04.893408Z","end":"2026-05-09T17:43:05.133794Z","steps":["trace[1283793470] 'process raft request' (duration: 239.944133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:05.133898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.49422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:43:05.133952Z","caller":"traceutil/trace.go:171","msg":"trace[1252711205] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24577; }","duration":"321.610812ms","start":"2026-05-09T17:43:04.812323Z","end":"2026-05-09T17:43:05.133934Z","steps":["trace[1252711205] 'agreement among raft nodes before linearized reading' (duration: 321.49638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:05.134007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:43:04.8123Z","time spent":"321.691075ms","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:43:06.459146Z","caller":"traceutil/trace.go:171","msg":"trace[562924962] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"120.664398ms","start":"2026-05-09T17:43:06.338443Z","end":"2026-05-09T17:43:06.459107Z","steps":["trace[562924962] 'process raft request' (duration: 120.416792ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:09.675727Z","caller":"traceutil/trace.go:171","msg":"trace[1219804205] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"100.301423ms","start":"2026-05-09T17:43:09.575388Z","end":"2026-05-09T17:43:09.675689Z","steps":["trace[1219804205] 'process raft request' (duration: 100.066178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:09.95313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.583884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274792"} {"level":"info","ts":"2026-05-09T17:43:09.953208Z","caller":"traceutil/trace.go:171","msg":"trace[1863733130] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:24627; }","duration":"109.719917ms","start":"2026-05-09T17:43:09.843472Z","end":"2026-05-09T17:43:09.953192Z","steps":["trace[1863733130] 'range keys from in-memory index tree' (duration: 109.110253ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:10.152513Z","caller":"traceutil/trace.go:171","msg":"trace[609312577] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"138.195257ms","start":"2026-05-09T17:43:10.01429Z","end":"2026-05-09T17:43:10.152485Z","steps":["trace[609312577] 'process raft request' (duration: 138.005022ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:35.199515Z","caller":"traceutil/trace.go:171","msg":"trace[1316134125] linearizableReadLoop","detail":"{readStateIndex:25649; appliedIndex:25647; }","duration":"103.685174ms","start":"2026-05-09T17:43:35.095807Z","end":"2026-05-09T17:43:35.199492Z","steps":["trace[1316134125] 'read index received' (duration: 25.490278ms)","trace[1316134125] 'applied index is now lower than readState.Index' (duration: 78.193986ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:35.199724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.908129ms","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-05-09T17:43:35.199759Z","caller":"traceutil/trace.go:171","msg":"trace[976022849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24837; }","duration":"104.018622ms","start":"2026-05-09T17:43:35.095728Z","end":"2026-05-09T17:43:35.199746Z","steps":["trace[976022849] 'agreement among raft nodes before linearized reading' (duration: 103.870628ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:35.199758Z","caller":"traceutil/trace.go:171","msg":"trace[1891218826] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"105.134158ms","start":"2026-05-09T17:43:35.094497Z","end":"2026-05-09T17:43:35.199631Z","steps":["trace[1891218826] 'process raft request' (duration: 104.812561ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:35.390167Z","caller":"traceutil/trace.go:171","msg":"trace[1678405165] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"185.830923ms","start":"2026-05-09T17:43:35.204306Z","end":"2026-05-09T17:43:35.390137Z","steps":["trace[1678405165] 'process raft request' (duration: 136.115416ms)","trace[1678405165] 'compare' (duration: 48.898698ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:35.431514Z","caller":"traceutil/trace.go:171","msg":"trace[1639610589] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"189.88987ms","start":"2026-05-09T17:43:35.241598Z","end":"2026-05-09T17:43:35.431488Z","steps":["trace[1639610589] 'process raft request' (duration: 189.804368ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:35.431757Z","caller":"traceutil/trace.go:171","msg":"trace[1964023794] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"185.70188ms","start":"2026-05-09T17:43:35.246034Z","end":"2026-05-09T17:43:35.431736Z","steps":["trace[1964023794] 'process raft request' (duration: 185.418663ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:35.431846Z","caller":"traceutil/trace.go:171","msg":"trace[128442286] linearizableReadLoop","detail":"{readStateIndex:25654; appliedIndex:25649; }","duration":"135.030081ms","start":"2026-05-09T17:43:35.296787Z","end":"2026-05-09T17:43:35.431817Z","steps":["trace[128442286] 'read index received' (duration: 43.658171ms)","trace[128442286] 'applied index is now lower than readState.Index' (duration: 91.3706ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:35.431914Z","caller":"traceutil/trace.go:171","msg":"trace[572537333] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"185.329321ms","start":"2026-05-09T17:43:35.24658Z","end":"2026-05-09T17:43:35.431909Z","steps":["trace[572537333] 'process raft request' (duration: 185.082305ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:35.432107Z","caller":"traceutil/trace.go:171","msg":"trace[296747772] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"192.087903ms","start":"2026-05-09T17:43:35.239436Z","end":"2026-05-09T17:43:35.431524Z","steps":["trace[296747772] 'process raft request' (duration: 191.797926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:35.432107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.282877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:35.43223Z","caller":"traceutil/trace.go:171","msg":"trace[43714446] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24842; }","duration":"135.448711ms","start":"2026-05-09T17:43:35.296742Z","end":"2026-05-09T17:43:35.432191Z","steps":["trace[43714446] 'agreement among raft nodes before linearized reading' (duration: 135.227196ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:35.935917Z","caller":"traceutil/trace.go:171","msg":"trace[49099602] linearizableReadLoop","detail":"{readStateIndex:25656; appliedIndex:25655; }","duration":"151.248148ms","start":"2026-05-09T17:43:35.784641Z","end":"2026-05-09T17:43:35.935889Z","steps":["trace[49099602] 'read index received' (duration: 151.034203ms)","trace[49099602] 'applied index is now lower than readState.Index' (duration: 212.525µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:35.935997Z","caller":"traceutil/trace.go:171","msg":"trace[1448454822] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"167.132307ms","start":"2026-05-09T17:43:35.768808Z","end":"2026-05-09T17:43:35.93594Z","steps":["trace[1448454822] 'process raft request' (duration: 166.900402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:35.936163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.497604ms","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-05-09T17:43:35.936246Z","caller":"traceutil/trace.go:171","msg":"trace[1292891182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24844; }","duration":"151.643917ms","start":"2026-05-09T17:43:35.784585Z","end":"2026-05-09T17:43:35.936229Z","steps":["trace[1292891182] 'agreement among raft nodes before linearized reading' (duration: 151.409222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:36.124731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.060152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:43:36.12494Z","caller":"traceutil/trace.go:171","msg":"trace[1824810256] transaction","detail":"{read_only:false; response_revision:24846; number_of_response:1; }","duration":"184.938532ms","start":"2026-05-09T17:43:35.939982Z","end":"2026-05-09T17:43:36.12492Z","steps":["trace[1824810256] 'process raft request' (duration: 184.86727ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:36.125033Z","caller":"traceutil/trace.go:171","msg":"trace[594986355] linearizableReadLoop","detail":"{readStateIndex:25657; appliedIndex:25656; }","duration":"189.017138ms","start":"2026-05-09T17:43:35.936Z","end":"2026-05-09T17:43:36.125017Z","steps":["trace[594986355] 'read index received' (duration: 32.604048ms)","trace[594986355] 'applied index is now lower than readState.Index' (duration: 156.41157ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:36.125166Z","caller":"traceutil/trace.go:171","msg":"trace[221118192] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"265.283878ms","start":"2026-05-09T17:43:35.859824Z","end":"2026-05-09T17:43:36.125108Z","steps":["trace[221118192] 'process raft request' (duration: 108.762265ms)","trace[221118192] 'compare' (duration: 155.892908ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:36.125354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.959763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:36.125469Z","caller":"traceutil/trace.go:171","msg":"trace[1281451123] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24846; }","duration":"247.195648ms","start":"2026-05-09T17:43:35.878247Z","end":"2026-05-09T17:43:36.125442Z","steps":["trace[1281451123] 'agreement among raft nodes before linearized reading' (duration: 246.867339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:36.859275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.9638ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T17:43:36.859418Z","caller":"traceutil/trace.go:171","msg":"trace[602704278] linearizableReadLoop","detail":"{readStateIndex:25662; appliedIndex:25661; }","duration":"116.570061ms","start":"2026-05-09T17:43:36.742825Z","end":"2026-05-09T17:43:36.859395Z","steps":["trace[602704278] 'read index received' (duration: 53.501µs)","trace[602704278] 'applied index is now lower than readState.Index' (duration: 116.51464ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:36.859638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.805197ms","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-05-09T17:43:36.859684Z","caller":"traceutil/trace.go:171","msg":"trace[1752289630] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24849; }","duration":"116.897868ms","start":"2026-05-09T17:43:36.742775Z","end":"2026-05-09T17:43:36.859673Z","steps":["trace[1752289630] 'agreement among raft nodes before linearized reading' (duration: 116.691113ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:37.559657Z","caller":"traceutil/trace.go:171","msg":"trace[2032121607] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"161.681897ms","start":"2026-05-09T17:43:37.397933Z","end":"2026-05-09T17:43:37.559615Z","steps":["trace[2032121607] 'process raft request' (duration: 102.7295ms)","trace[2032121607] 'compare' (duration: 58.759861ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:37.637083Z","caller":"traceutil/trace.go:171","msg":"trace[1064160997] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"200.451332ms","start":"2026-05-09T17:43:37.436604Z","end":"2026-05-09T17:43:37.637056Z","steps":["trace[1064160997] 'process raft request' (duration: 200.258437ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:37.637316Z","caller":"traceutil/trace.go:171","msg":"trace[1856280737] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"200.282998ms","start":"2026-05-09T17:43:37.437013Z","end":"2026-05-09T17:43:37.637296Z","steps":["trace[1856280737] 'process raft request' (duration: 199.997141ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:37.637622Z","caller":"traceutil/trace.go:171","msg":"trace[411514001] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"200.542734ms","start":"2026-05-09T17:43:37.437073Z","end":"2026-05-09T17:43:37.637615Z","steps":["trace[411514001] 'process raft request' (duration: 200.159125ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:37.637702Z","caller":"traceutil/trace.go:171","msg":"trace[974786677] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"174.706377ms","start":"2026-05-09T17:43:37.462967Z","end":"2026-05-09T17:43:37.637673Z","steps":["trace[974786677] 'process raft request' (duration: 174.647176ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:37.637742Z","caller":"traceutil/trace.go:171","msg":"trace[1548352734] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"199.486319ms","start":"2026-05-09T17:43:37.438236Z","end":"2026-05-09T17:43:37.637722Z","steps":["trace[1548352734] 'process raft request' (duration: 199.280694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:37.863021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.047376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:37.863109Z","caller":"traceutil/trace.go:171","msg":"trace[518856310] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24858; }","duration":"149.160228ms","start":"2026-05-09T17:43:37.713928Z","end":"2026-05-09T17:43:37.863088Z","steps":["trace[518856310] 'count revisions from in-memory index tree' (duration: 148.965743ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:38.187959Z","caller":"traceutil/trace.go:171","msg":"trace[160773048] linearizableReadLoop","detail":"{readStateIndex:25673; appliedIndex:25672; }","duration":"152.825306ms","start":"2026-05-09T17:43:38.035114Z","end":"2026-05-09T17:43:38.18794Z","steps":["trace[160773048] 'read index received' (duration: 152.639291ms)","trace[160773048] 'applied index is now lower than readState.Index' (duration: 185.115µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:38.188005Z","caller":"traceutil/trace.go:171","msg":"trace[1312047102] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"242.554307ms","start":"2026-05-09T17:43:37.94542Z","end":"2026-05-09T17:43:38.187974Z","steps":["trace[1312047102] 'process raft request' (duration: 242.384743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:38.18811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.98269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:38.188137Z","caller":"traceutil/trace.go:171","msg":"trace[409915894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24860; }","duration":"153.049701ms","start":"2026-05-09T17:43:38.035077Z","end":"2026-05-09T17:43:38.188126Z","steps":["trace[409915894] 'agreement among raft nodes before linearized reading' (duration: 152.968719ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:38.322077Z","caller":"traceutil/trace.go:171","msg":"trace[427012182] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"129.287785ms","start":"2026-05-09T17:43:38.192605Z","end":"2026-05-09T17:43:38.321893Z","steps":["trace[427012182] 'process raft request' (duration: 120.603269ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:39.094108Z","caller":"traceutil/trace.go:171","msg":"trace[1958480024] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"103.95137ms","start":"2026-05-09T17:43:38.990127Z","end":"2026-05-09T17:43:39.094078Z","steps":["trace[1958480024] 'process raft request' (duration: 103.730534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:39.333095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.789124ms","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-05-09T17:43:39.333171Z","caller":"traceutil/trace.go:171","msg":"trace[589445463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24864; }","duration":"110.902496ms","start":"2026-05-09T17:43:39.222251Z","end":"2026-05-09T17:43:39.333153Z","steps":["trace[589445463] 'range keys from in-memory index tree' (duration: 110.63528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:39.703123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.294693ms","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-05-09T17:43:39.703131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.31706ms","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":"info","ts":"2026-05-09T17:43:39.703189Z","caller":"traceutil/trace.go:171","msg":"trace[1631107093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24866; }","duration":"139.393185ms","start":"2026-05-09T17:43:39.56378Z","end":"2026-05-09T17:43:39.703173Z","steps":["trace[1631107093] 'range keys from in-memory index tree' (duration: 139.052358ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:39.703202Z","caller":"traceutil/trace.go:171","msg":"trace[882257179] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:24866; }","duration":"260.423483ms","start":"2026-05-09T17:43:39.44276Z","end":"2026-05-09T17:43:39.703183Z","steps":["trace[882257179] 'count revisions from in-memory index tree' (duration: 260.256288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:39.703733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.335685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-09T17:43:39.703811Z","caller":"traceutil/trace.go:171","msg":"trace[957615831] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24866; }","duration":"248.443137ms","start":"2026-05-09T17:43:39.45535Z","end":"2026-05-09T17:43:39.703793Z","steps":["trace[957615831] 'range keys from in-memory index tree' (duration: 248.228952ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:39.88955Z","caller":"traceutil/trace.go:171","msg":"trace[1047234852] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"245.476676ms","start":"2026-05-09T17:43:39.644052Z","end":"2026-05-09T17:43:39.889528Z","steps":["trace[1047234852] 'process raft request' (duration: 245.294201ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:39.889924Z","caller":"traceutil/trace.go:171","msg":"trace[457202905] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"245.785813ms","start":"2026-05-09T17:43:39.644108Z","end":"2026-05-09T17:43:39.889894Z","steps":["trace[457202905] 'process raft request' (duration: 245.380704ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:39.890018Z","caller":"traceutil/trace.go:171","msg":"trace[488590902] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"245.881545ms","start":"2026-05-09T17:43:39.64412Z","end":"2026-05-09T17:43:39.890001Z","steps":["trace[488590902] 'process raft request' (duration: 245.638549ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:39.890028Z","caller":"traceutil/trace.go:171","msg":"trace[1622569189] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"245.599388ms","start":"2026-05-09T17:43:39.644415Z","end":"2026-05-09T17:43:39.890014Z","steps":["trace[1622569189] 'process raft request' (duration: 245.489245ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:39.890026Z","caller":"traceutil/trace.go:171","msg":"trace[1970008661] linearizableReadLoop","detail":"{readStateIndex:25684; appliedIndex:25679; }","duration":"183.224771ms","start":"2026-05-09T17:43:39.706776Z","end":"2026-05-09T17:43:39.890001Z","steps":["trace[1970008661] 'read index received' (duration: 25.718994ms)","trace[1970008661] 'applied index is now lower than readState.Index' (duration: 157.504657ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:39.890146Z","caller":"traceutil/trace.go:171","msg":"trace[678879973] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"183.18869ms","start":"2026-05-09T17:43:39.706946Z","end":"2026-05-09T17:43:39.890135Z","steps":["trace[678879973] 'process raft request' (duration: 183.010646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:39.890218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.288132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:43:39.89023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.438066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0f77cc71-420f-44df-adbf-b74012cc4dc8\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-05-09T17:43:39.890265Z","caller":"traceutil/trace.go:171","msg":"trace[1800909470] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0f77cc71-420f-44df-adbf-b74012cc4dc8; range_end:; response_count:1; response_revision:24872; }","duration":"183.517009ms","start":"2026-05-09T17:43:39.706734Z","end":"2026-05-09T17:43:39.890251Z","steps":["trace[1800909470] 'agreement among raft nodes before linearized reading' (duration: 183.398125ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:39.890259Z","caller":"traceutil/trace.go:171","msg":"trace[2070063377] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24872; }","duration":"118.375284ms","start":"2026-05-09T17:43:39.771869Z","end":"2026-05-09T17:43:39.890244Z","steps":["trace[2070063377] 'agreement among raft nodes before linearized reading' (duration: 118.287361ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:40.332879Z","caller":"traceutil/trace.go:171","msg":"trace[2002078850] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"137.749646ms","start":"2026-05-09T17:43:40.195102Z","end":"2026-05-09T17:43:40.332852Z","steps":["trace[2002078850] 'process raft request' (duration: 137.49856ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:40.485852Z","caller":"traceutil/trace.go:171","msg":"trace[1007142667] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"148.768309ms","start":"2026-05-09T17:43:40.33706Z","end":"2026-05-09T17:43:40.485828Z","steps":["trace[1007142667] 'process raft request' (duration: 74.702932ms)","trace[1007142667] 'compare' (duration: 73.78269ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:40.486002Z","caller":"traceutil/trace.go:171","msg":"trace[456892242] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"148.257477ms","start":"2026-05-09T17:43:40.337717Z","end":"2026-05-09T17:43:40.485975Z","steps":["trace[456892242] 'process raft request' (duration: 148.038462ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:40.881929Z","caller":"traceutil/trace.go:171","msg":"trace[773043664] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"168.470039ms","start":"2026-05-09T17:43:40.713417Z","end":"2026-05-09T17:43:40.881887Z","steps":["trace[773043664] 'process raft request' (duration: 168.275604ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:40.88195Z","caller":"traceutil/trace.go:171","msg":"trace[438510756] linearizableReadLoop","detail":"{readStateIndex:25689; appliedIndex:25688; }","duration":"147.212361ms","start":"2026-05-09T17:43:40.734705Z","end":"2026-05-09T17:43:40.881917Z","steps":["trace[438510756] 'read index received' (duration: 147.122109ms)","trace[438510756] 'applied index is now lower than readState.Index' (duration: 86.862µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:40.88219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.468578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:40.88224Z","caller":"traceutil/trace.go:171","msg":"trace[1882707079] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24876; }","duration":"147.594092ms","start":"2026-05-09T17:43:40.73463Z","end":"2026-05-09T17:43:40.882224Z","steps":["trace[1882707079] 'agreement among raft nodes before linearized reading' (duration: 147.498359ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:41.998047Z","caller":"traceutil/trace.go:171","msg":"trace[698195608] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"100.434106ms","start":"2026-05-09T17:43:41.89759Z","end":"2026-05-09T17:43:41.998024Z","steps":["trace[698195608] 'process raft request' (duration: 100.258022ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:41.998355Z","caller":"traceutil/trace.go:171","msg":"trace[692989838] linearizableReadLoop","detail":"{readStateIndex:25698; appliedIndex:25697; }","duration":"100.610121ms","start":"2026-05-09T17:43:41.897723Z","end":"2026-05-09T17:43:41.998333Z","steps":["trace[692989838] 'read index received' (duration: 70.306328ms)","trace[692989838] 'applied index is now lower than readState.Index' (duration: 30.302542ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:41.99857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.831996ms","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-05-09T17:43:41.998609Z","caller":"traceutil/trace.go:171","msg":"trace[457783430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24886; }","duration":"100.911138ms","start":"2026-05-09T17:43:41.897687Z","end":"2026-05-09T17:43:41.998598Z","steps":["trace[457783430] 'agreement among raft nodes before linearized reading' (duration: 100.728783ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:45.63586Z","caller":"traceutil/trace.go:171","msg":"trace[1028080565] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"123.401753ms","start":"2026-05-09T17:43:45.512434Z","end":"2026-05-09T17:43:45.635835Z","steps":["trace[1028080565] 'process raft request' (duration: 122.8348ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:46.565225Z","caller":"traceutil/trace.go:171","msg":"trace[1124170540] linearizableReadLoop","detail":"{readStateIndex:25733; appliedIndex:25732; }","duration":"137.222714ms","start":"2026-05-09T17:43:46.427971Z","end":"2026-05-09T17:43:46.565194Z","steps":["trace[1124170540] 'read index received' (duration: 49.221885ms)","trace[1124170540] 'applied index is now lower than readState.Index' (duration: 87.999179ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:46.565315Z","caller":"traceutil/trace.go:171","msg":"trace[8587478] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"160.369545ms","start":"2026-05-09T17:43:46.404917Z","end":"2026-05-09T17:43:46.565287Z","steps":["trace[8587478] 'process raft request' (duration: 72.287484ms)","trace[8587478] 'compare' (duration: 87.674382ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:43:46.5654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.409648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:43:46.565446Z","caller":"traceutil/trace.go:171","msg":"trace[1523804214] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24919; }","duration":"137.505091ms","start":"2026-05-09T17:43:46.427928Z","end":"2026-05-09T17:43:46.565434Z","steps":["trace[1523804214] 'agreement among raft nodes before linearized reading' (duration: 137.408938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:43:46.63208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.11855ms","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-05-09T17:43:46.632155Z","caller":"traceutil/trace.go:171","msg":"trace[718661085] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24920; }","duration":"168.228773ms","start":"2026-05-09T17:43:46.463908Z","end":"2026-05-09T17:43:46.632137Z","steps":["trace[718661085] 'agreement among raft nodes before linearized reading' (duration: 167.993268ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:48.826203Z","caller":"traceutil/trace.go:171","msg":"trace[74981851] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"154.503975ms","start":"2026-05-09T17:43:48.671665Z","end":"2026-05-09T17:43:48.826169Z","steps":["trace[74981851] 'process raft request' (duration: 66.64419ms)","trace[74981851] 'compare' (duration: 87.18336ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:43:49.018701Z","caller":"traceutil/trace.go:171","msg":"trace[651588473] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"106.45ms","start":"2026-05-09T17:43:48.912211Z","end":"2026-05-09T17:43:49.018661Z","steps":["trace[651588473] 'process raft request' (duration: 60.119515ms)","trace[651588473] 'compare' (duration: 46.020707ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:58.392721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23339} {"level":"info","ts":"2026-05-09T17:45:58.537498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23339,"took":"139.957053ms","hash":3903280798,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":37019648,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-09T17:45:58.537553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3903280798,"revision":23339,"compact-revision":20055} {"level":"info","ts":"2026-05-09T17:46:04.650409Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069f3.wal"} {"level":"info","ts":"2026-05-09T17:50:58.399653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26227} {"level":"info","ts":"2026-05-09T17:50:58.540274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26227,"took":"137.410612ms","hash":3442883334,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":32956416,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T17:50:58.540376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3442883334,"revision":26227,"compact-revision":23339} {"level":"info","ts":"2026-05-09T17:51:37.238757Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:51:37.244622Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T17:51:37.244699Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T17:55:58.407277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28792} {"level":"info","ts":"2026-05-09T17:55:58.546168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28792,"took":"134.904781ms","hash":2963857892,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":30056448,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T17:55:58.546227Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2963857892,"revision":28792,"compact-revision":26227} {"level":"info","ts":"2026-05-09T18:00:58.413509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31009} {"level":"info","ts":"2026-05-09T18:00:58.530054Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31009,"took":"113.134091ms","hash":2157504290,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":32227328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T18:00:58.530108Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2157504290,"revision":31009,"compact-revision":28792} {"level":"info","ts":"2026-05-09T18:05:58.423593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33398} {"level":"info","ts":"2026-05-09T18:05:58.580444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33398,"took":"151.825954ms","hash":3366952557,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":33255424,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T18:05:58.580499Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3366952557,"revision":33398,"compact-revision":31009} {"level":"info","ts":"2026-05-09T18:10:58.428711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35668} {"level":"info","ts":"2026-05-09T18:10:58.56497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35668,"took":"132.794781ms","hash":4041043884,"current-db-size-bytes":59883520,"current-db-size":"60 MB","current-db-size-in-use-bytes":31555584,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T18:10:58.565037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4041043884,"revision":35668,"compact-revision":33398}