{"level":"warn","ts":"2026-05-25T14:25:17.940801Z","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-25T14:25:17.940878Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-25T14:25:17.940939Z","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-25T14:25:17.940947Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-05-25T14:25:17.940965Z","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-25T14:25:17.941617Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-05-25T14:25:17.94169Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-25T14:25:17.94578Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.909744ms"} {"level":"info","ts":"2026-05-25T14:25:17.951202Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-05-25T14:25:17.951278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T14:25:17.951377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-05-25T14:25:17.9514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T14:25:17.951409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-05-25T14:25:17.95145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-05-25T14:25:17.962814Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T14:25:17.964802Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T14:25:17.966053Z","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-25T14:25:17.969194Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T14:25:17.969325Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T14:25:17.969459Z","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-25T14:25:17.969542Z","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-25T14:25:17.969557Z","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-25T14:25:17.970384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-05-25T14:25:17.971Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-05-25T14:25:17.975586Z","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-25T14:25:17.975798Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-05-25T14:25:17.975859Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-05-25T14:25:17.975957Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T14:25:17.976076Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T14:25:18.952668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T14:25:18.952732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T14:25:18.952759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-05-25T14:25:18.952781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-05-25T14:25:18.952793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-05-25T14:25:18.952809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-05-25T14:25:18.952829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-05-25T14:25:18.954262Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:25:18.955067Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T14:25:18.955118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T14:25:18.955175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T14:25:18.955455Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T14:25:18.955519Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T14:25:18.955676Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:25:18.95577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:25:18.955801Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T14:25:18.958782Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T14:25:18.959005Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-05-25T14:25:20.562041Z","caller":"traceutil/trace.go:171","msg":"trace[1433216188] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"156.258347ms","start":"2026-05-25T14:25:20.405745Z","end":"2026-05-25T14:25:20.562003Z","steps":["trace[1433216188] 'process raft request' (duration: 156.191745ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:20.562091Z","caller":"traceutil/trace.go:171","msg":"trace[1424638111] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"157.022482ms","start":"2026-05-25T14:25:20.405035Z","end":"2026-05-25T14:25:20.562057Z","steps":["trace[1424638111] 'process raft request' (duration: 152.68882ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:56.631361Z","caller":"traceutil/trace.go:171","msg":"trace[1918528251] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"125.63932ms","start":"2026-05-25T14:25:56.505685Z","end":"2026-05-25T14:25:56.631324Z","steps":["trace[1918528251] 'process raft request' (duration: 125.368855ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:25:56.63131Z","caller":"traceutil/trace.go:171","msg":"trace[372505423] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"119.892851ms","start":"2026-05-25T14:25:56.511391Z","end":"2026-05-25T14:25:56.631283Z","steps":["trace[372505423] 'read index received' (duration: 119.663387ms)","trace[372505423] 'applied index is now lower than readState.Index' (duration: 228.354µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:25:56.631661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.235128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-25T14:25:56.631784Z","caller":"traceutil/trace.go:171","msg":"trace[274352609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"120.38473ms","start":"2026-05-25T14:25:56.511375Z","end":"2026-05-25T14:25:56.63176Z","steps":["trace[274352609] 'agreement among raft nodes before linearized reading' (duration: 120.080125ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:26:00.577529Z","caller":"traceutil/trace.go:171","msg":"trace[555852592] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"236.45736ms","start":"2026-05-25T14:26:00.341025Z","end":"2026-05-25T14:26:00.577483Z","steps":["trace[555852592] 'read index received' (duration: 222.958325ms)","trace[555852592] 'applied index is now lower than readState.Index' (duration: 13.497365ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:26:00.577599Z","caller":"traceutil/trace.go:171","msg":"trace[1970156203] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"415.432856ms","start":"2026-05-25T14:26:00.16211Z","end":"2026-05-25T14:26:00.577543Z","steps":["trace[1970156203] 'process raft request' (duration: 401.818789ms)","trace[1970156203] 'compare' (duration: 13.134308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:26:00.577732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.694304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:26:00.577829Z","caller":"traceutil/trace.go:171","msg":"trace[291123369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"236.833767ms","start":"2026-05-25T14:26:00.340954Z","end":"2026-05-25T14:26:00.577788Z","steps":["trace[291123369] 'agreement among raft nodes before linearized reading' (duration: 236.709805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:26:00.57837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:26:00.162074Z","time spent":"415.671041ms","remote":"127.0.0.1:40264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T14:26:15.109127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.917525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pvgxz\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-25T14:26:15.109216Z","caller":"traceutil/trace.go:171","msg":"trace[1833663048] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pvgxz; range_end:; response_count:1; response_revision:754; }","duration":"199.072418ms","start":"2026-05-25T14:26:14.910124Z","end":"2026-05-25T14:26:15.109196Z","steps":["trace[1833663048] 'range keys from in-memory index tree' (duration: 198.723412ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:26:46.209428Z","caller":"traceutil/trace.go:171","msg":"trace[664002101] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"138.715296ms","start":"2026-05-25T14:26:46.070684Z","end":"2026-05-25T14:26:46.209399Z","steps":["trace[664002101] 'process raft request' (duration: 121.112543ms)","trace[664002101] 'compare' (duration: 17.474141ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:27:30.510281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.192511ms","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":"warn","ts":"2026-05-25T14:27:30.510257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.935536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-25T14:27:30.510375Z","caller":"traceutil/trace.go:171","msg":"trace[592383945] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1816; }","duration":"243.359843ms","start":"2026-05-25T14:27:30.266998Z","end":"2026-05-25T14:27:30.510358Z","steps":["trace[592383945] 'range keys from in-memory index tree' (duration: 242.966506ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:30.5104Z","caller":"traceutil/trace.go:171","msg":"trace[845504370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1816; }","duration":"254.127989ms","start":"2026-05-25T14:27:30.256248Z","end":"2026-05-25T14:27:30.510376Z","steps":["trace[845504370] 'range keys from in-memory index tree' (duration: 253.729592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:30.510241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.03119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6dfca893-19fa-4673-93e4-eadc7fad5033\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-25T14:27:30.510512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.67408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6dfca893-19fa-4673-93e4-eadc7fad5033\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-25T14:27:30.510536Z","caller":"traceutil/trace.go:171","msg":"trace[1896599133] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6dfca893-19fa-4673-93e4-eadc7fad5033; range_end:; response_count:1; response_revision:1816; }","duration":"240.370267ms","start":"2026-05-25T14:27:30.270124Z","end":"2026-05-25T14:27:30.510494Z","steps":["trace[1896599133] 'range keys from in-memory index tree' (duration: 239.802276ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:30.510603Z","caller":"traceutil/trace.go:171","msg":"trace[1328879666] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6dfca893-19fa-4673-93e4-eadc7fad5033; range_end:; response_count:1; response_revision:1816; }","duration":"231.817663ms","start":"2026-05-25T14:27:30.278768Z","end":"2026-05-25T14:27:30.510585Z","steps":["trace[1328879666] 'range keys from in-memory index tree' (duration: 231.495267ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:30.535992Z","caller":"traceutil/trace.go:171","msg":"trace[1026438401] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"163.813985ms","start":"2026-05-25T14:27:30.372158Z","end":"2026-05-25T14:27:30.535972Z","steps":["trace[1026438401] 'process raft request' (duration: 163.683792ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:30.763785Z","caller":"traceutil/trace.go:171","msg":"trace[1802641715] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"149.812888ms","start":"2026-05-25T14:27:30.613949Z","end":"2026-05-25T14:27:30.763762Z","steps":["trace[1802641715] 'process raft request' (duration: 149.663145ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:30.923256Z","caller":"traceutil/trace.go:171","msg":"trace[1059369270] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"309.151938ms","start":"2026-05-25T14:27:30.614076Z","end":"2026-05-25T14:27:30.923228Z","steps":["trace[1059369270] 'process raft request' (duration: 262.039749ms)","trace[1059369270] 'compare' (duration: 46.936725ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:27:30.923595Z","caller":"traceutil/trace.go:171","msg":"trace[1262680877] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"308.705429ms","start":"2026-05-25T14:27:30.614831Z","end":"2026-05-25T14:27:30.923536Z","steps":["trace[1262680877] 'process raft request' (duration: 308.346083ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:30.923624Z","caller":"traceutil/trace.go:171","msg":"trace[47657749] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1891; }","duration":"199.366135ms","start":"2026-05-25T14:27:30.724229Z","end":"2026-05-25T14:27:30.923595Z","steps":["trace[47657749] 'read index received' (duration: 39.458533ms)","trace[47657749] 'applied index is now lower than readState.Index' (duration: 159.905181ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:27:30.92367Z","caller":"traceutil/trace.go:171","msg":"trace[374781968] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"308.340653ms","start":"2026-05-25T14:27:30.615302Z","end":"2026-05-25T14:27:30.923642Z","steps":["trace[374781968] 'process raft request' (duration: 308.122688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:30.923804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.555018ms","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:5"} {"level":"warn","ts":"2026-05-25T14:27:30.923822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:30.615251Z","time spent":"308.478556ms","remote":"127.0.0.1:39974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T14:27:30.923833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.561329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T14:27:30.923848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:30.614049Z","time spent":"309.300191ms","remote":"127.0.0.1:39974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:27:30.923877Z","caller":"traceutil/trace.go:171","msg":"trace[1823236888] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1824; }","duration":"199.67439ms","start":"2026-05-25T14:27:30.724186Z","end":"2026-05-25T14:27:30.92386Z","steps":["trace[1823236888] 'agreement among raft nodes before linearized reading' (duration: 199.540558ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:30.923884Z","caller":"traceutil/trace.go:171","msg":"trace[1738148148] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1824; }","duration":"199.65917ms","start":"2026-05-25T14:27:30.724214Z","end":"2026-05-25T14:27:30.923873Z","steps":["trace[1738148148] 'agreement among raft nodes before linearized reading' (duration: 199.561948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:30.92401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:27:30.61482Z","time spent":"308.855803ms","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:27:42.829379Z","caller":"traceutil/trace.go:171","msg":"trace[1543713006] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"228.081621ms","start":"2026-05-25T14:27:42.601267Z","end":"2026-05-25T14:27:42.829349Z","steps":["trace[1543713006] 'read index received' (duration: 227.798046ms)","trace[1543713006] 'applied index is now lower than readState.Index' (duration: 282.455µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:27:42.829538Z","caller":"traceutil/trace.go:171","msg":"trace[655520702] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"250.764715ms","start":"2026-05-25T14:27:42.578748Z","end":"2026-05-25T14:27:42.829513Z","steps":["trace[655520702] 'process raft request' (duration: 250.425388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:27:42.829656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.364557ms","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":"warn","ts":"2026-05-25T14:27:42.82969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.431719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T14:27:42.829703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.248165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-25T14:27:42.829762Z","caller":"traceutil/trace.go:171","msg":"trace[127178988] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1923; }","duration":"184.531481ms","start":"2026-05-25T14:27:42.645211Z","end":"2026-05-25T14:27:42.829743Z","steps":["trace[127178988] 'agreement among raft nodes before linearized reading' (duration: 184.418619ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:42.829785Z","caller":"traceutil/trace.go:171","msg":"trace[645062750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1923; }","duration":"228.351727ms","start":"2026-05-25T14:27:42.601416Z","end":"2026-05-25T14:27:42.829768Z","steps":["trace[645062750] 'agreement among raft nodes before linearized reading' (duration: 228.167893ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:27:42.82971Z","caller":"traceutil/trace.go:171","msg":"trace[1187959074] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1923; }","duration":"228.458899ms","start":"2026-05-25T14:27:42.601234Z","end":"2026-05-25T14:27:42.829693Z","steps":["trace[1187959074] 'agreement among raft nodes before linearized reading' (duration: 228.264036ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:28:21.251034Z","caller":"traceutil/trace.go:171","msg":"trace[160959200] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2226; }","duration":"149.685244ms","start":"2026-05-25T14:28:21.101321Z","end":"2026-05-25T14:28:21.251006Z","steps":["trace[160959200] 'read index received' (duration: 149.47887ms)","trace[160959200] 'applied index is now lower than readState.Index' (duration: 205.154µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:28:21.251102Z","caller":"traceutil/trace.go:171","msg":"trace[1228652830] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"156.203917ms","start":"2026-05-25T14:28:21.094868Z","end":"2026-05-25T14:28:21.251072Z","steps":["trace[1228652830] 'process raft request' (duration: 155.916792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:21.251283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.914089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:28:21.251385Z","caller":"traceutil/trace.go:171","msg":"trace[1560010025] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2146; }","duration":"150.076331ms","start":"2026-05-25T14:28:21.101287Z","end":"2026-05-25T14:28:21.251364Z","steps":["trace[1560010025] 'agreement among raft nodes before linearized reading' (duration: 149.878058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:28:21.252243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.880712ms","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-25T14:28:21.2523Z","caller":"traceutil/trace.go:171","msg":"trace[160086089] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2146; }","duration":"145.481024ms","start":"2026-05-25T14:28:21.106804Z","end":"2026-05-25T14:28:21.252285Z","steps":["trace[160086089] 'agreement among raft nodes before linearized reading' (duration: 144.524426ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:34.366248Z","caller":"traceutil/trace.go:171","msg":"trace[521021194] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"124.044449ms","start":"2026-05-25T14:33:34.242165Z","end":"2026-05-25T14:33:34.36621Z","steps":["trace[521021194] 'process raft request' (duration: 123.514569ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:34.570192Z","caller":"traceutil/trace.go:171","msg":"trace[2145148883] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"113.749203ms","start":"2026-05-25T14:33:34.456378Z","end":"2026-05-25T14:33:34.570127Z","steps":["trace[2145148883] 'read index received' (duration: 53.422001ms)","trace[2145148883] 'applied index is now lower than readState.Index' (duration: 60.326182ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:33:34.570201Z","caller":"traceutil/trace.go:171","msg":"trace[2002762398] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"122.601642ms","start":"2026-05-25T14:33:34.447568Z","end":"2026-05-25T14:33:34.57017Z","steps":["trace[2002762398] 'process raft request' (duration: 62.26725ms)","trace[2002762398] 'compare' (duration: 60.174549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:33:34.572338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.938355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728771"} {"level":"info","ts":"2026-05-25T14:33:34.572435Z","caller":"traceutil/trace.go:171","msg":"trace[1080818378] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4928; }","duration":"116.110758ms","start":"2026-05-25T14:33:34.456308Z","end":"2026-05-25T14:33:34.572419Z","steps":["trace[1080818378] 'agreement among raft nodes before linearized reading' (duration: 113.958396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:33:49.081673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.943382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-25T14:33:49.082004Z","caller":"traceutil/trace.go:171","msg":"trace[729190645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5205; }","duration":"200.342698ms","start":"2026-05-25T14:33:48.88163Z","end":"2026-05-25T14:33:49.081937Z","steps":["trace[729190645] 'range keys from in-memory index tree' (duration: 199.683066ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:49.315482Z","caller":"traceutil/trace.go:171","msg":"trace[1680258302] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"162.288014ms","start":"2026-05-25T14:33:49.153162Z","end":"2026-05-25T14:33:49.31545Z","steps":["trace[1680258302] 'process raft request' (duration: 123.27092ms)","trace[1680258302] 'compare' (duration: 38.876161ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:33:49.317976Z","caller":"traceutil/trace.go:171","msg":"trace[1095450039] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"163.515008ms","start":"2026-05-25T14:33:49.154436Z","end":"2026-05-25T14:33:49.317951Z","steps":["trace[1095450039] 'process raft request' (duration: 163.344314ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:49.318164Z","caller":"traceutil/trace.go:171","msg":"trace[1475349657] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"162.159472ms","start":"2026-05-25T14:33:49.155959Z","end":"2026-05-25T14:33:49.318118Z","steps":["trace[1475349657] 'process raft request' (duration: 161.955718ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:33:56.503171Z","caller":"traceutil/trace.go:171","msg":"trace[1517024642] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"100.561197ms","start":"2026-05-25T14:33:56.402582Z","end":"2026-05-25T14:33:56.503143Z","steps":["trace[1517024642] 'process raft request' (duration: 100.375492ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:35:18.984602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2981} {"level":"info","ts":"2026-05-25T14:35:19.056997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2981,"took":"70.569473ms","hash":3928794992,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":34103296,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T14:35:19.057069Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3928794992,"revision":2981,"compact-revision":-1} {"level":"info","ts":"2026-05-25T14:40:17.728374Z","caller":"traceutil/trace.go:171","msg":"trace[1156470944] linearizableReadLoop","detail":"{readStateIndex:8563; appliedIndex:8562; }","duration":"154.754881ms","start":"2026-05-25T14:40:17.573589Z","end":"2026-05-25T14:40:17.728344Z","steps":["trace[1156470944] 'read index received' (duration: 154.587828ms)","trace[1156470944] 'applied index is now lower than readState.Index' (duration: 165.983µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:40:17.728489Z","caller":"traceutil/trace.go:171","msg":"trace[220927493] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"195.609905ms","start":"2026-05-25T14:40:17.532845Z","end":"2026-05-25T14:40:17.728454Z","steps":["trace[220927493] 'process raft request' (duration: 195.319099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:40:17.728534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.929065ms","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-25T14:40:17.728576Z","caller":"traceutil/trace.go:171","msg":"trace[115798589] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8270; }","duration":"155.026986ms","start":"2026-05-25T14:40:17.57354Z","end":"2026-05-25T14:40:17.728567Z","steps":["trace[115798589] 'agreement among raft nodes before linearized reading' (duration: 154.925674ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:18.815948Z","caller":"traceutil/trace.go:171","msg":"trace[1693469949] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"178.47082ms","start":"2026-05-25T14:40:18.637443Z","end":"2026-05-25T14:40:18.815913Z","steps":["trace[1693469949] 'process raft request' (duration: 178.222766ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:19.012721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6113} {"level":"warn","ts":"2026-05-25T14:40:19.311855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.149917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:40:19.312146Z","caller":"traceutil/trace.go:171","msg":"trace[1539520324] transaction","detail":"{read_only:false; response_revision:8278; number_of_response:1; }","duration":"191.034759ms","start":"2026-05-25T14:40:19.121081Z","end":"2026-05-25T14:40:19.312116Z","steps":["trace[1539520324] 'process raft request' (duration: 14.345711ms)","trace[1539520324] 'compare' (duration: 115.752304ms)","trace[1539520324] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:473; } (duration: 60.056707ms)"],"step_count":3} {"level":"warn","ts":"2026-05-25T14:40:19.530892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.938565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:40:19.531014Z","caller":"traceutil/trace.go:171","msg":"trace[1386065689] linearizableReadLoop","detail":"{readStateIndex:8573; appliedIndex:8572; }","duration":"199.46001ms","start":"2026-05-25T14:40:19.331536Z","end":"2026-05-25T14:40:19.530996Z","steps":["trace[1386065689] 'read index received' (duration: 33.285991ms)","trace[1386065689] 'applied index is now lower than readState.Index' (duration: 166.169289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:40:19.531166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.45913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T14:40:19.531209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.671874ms","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-25T14:40:19.531169Z","caller":"traceutil/trace.go:171","msg":"trace[1812880462] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"205.775629ms","start":"2026-05-25T14:40:19.325337Z","end":"2026-05-25T14:40:19.531113Z","steps":["trace[1812880462] 'process raft request' (duration: 39.503998ms)","trace[1812880462] 'compare' (duration: 60.702791ms)","trace[1812880462] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 105.023989ms)"],"step_count":3} {"level":"info","ts":"2026-05-25T14:40:19.531242Z","caller":"traceutil/trace.go:171","msg":"trace[990442776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8279; }","duration":"199.738754ms","start":"2026-05-25T14:40:19.331491Z","end":"2026-05-25T14:40:19.53123Z","steps":["trace[990442776] 'agreement among raft nodes before linearized reading' (duration: 199.609792ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:19.531246Z","caller":"traceutil/trace.go:171","msg":"trace[1990634915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8279; }","duration":"189.564121ms","start":"2026-05-25T14:40:19.341661Z","end":"2026-05-25T14:40:19.531225Z","steps":["trace[1990634915] 'agreement among raft nodes before linearized reading' (duration: 189.435579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:40:19.531279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.014961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-25T14:40:19.531369Z","caller":"traceutil/trace.go:171","msg":"trace[1696338206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8279; }","duration":"188.141464ms","start":"2026-05-25T14:40:19.343206Z","end":"2026-05-25T14:40:19.531348Z","steps":["trace[1696338206] 'agreement among raft nodes before linearized reading' (duration: 187.9124ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:19.619077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6113,"took":"603.588034ms","hash":3759245393,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":23195648,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-25T14:40:19.61915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3759245393,"revision":6113,"compact-revision":2981} {"level":"info","ts":"2026-05-25T14:40:19.681789Z","caller":"traceutil/trace.go:171","msg":"trace[872316788] transaction","detail":"{read_only:false; response_revision:8281; number_of_response:1; }","duration":"146.047157ms","start":"2026-05-25T14:40:19.535709Z","end":"2026-05-25T14:40:19.681756Z","steps":["trace[872316788] 'process raft request' (duration: 123.880678ms)","trace[872316788] 'compare' (duration: 22.045607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:40:47.391639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.522331ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-25T14:40:47.39182Z","caller":"traceutil/trace.go:171","msg":"trace[226724050] linearizableReadLoop","detail":"{readStateIndex:8784; appliedIndex:8783; }","duration":"274.171344ms","start":"2026-05-25T14:40:47.117627Z","end":"2026-05-25T14:40:47.391799Z","steps":["trace[226724050] 'read index received' (duration: 123.5207ms)","trace[226724050] 'applied index is now lower than readState.Index' (duration: 150.648714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:40:47.392091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.430119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v6srl\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-25T14:40:47.392199Z","caller":"traceutil/trace.go:171","msg":"trace[643737941] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v6srl; range_end:; response_count:1; response_revision:8482; }","duration":"274.583642ms","start":"2026-05-25T14:40:47.117591Z","end":"2026-05-25T14:40:47.392174Z","steps":["trace[643737941] 'agreement among raft nodes before linearized reading' (duration: 274.284666ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:40:50.371255Z","caller":"traceutil/trace.go:171","msg":"trace[735261403] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"155.67545ms","start":"2026-05-25T14:40:50.215556Z","end":"2026-05-25T14:40:50.371232Z","steps":["trace[735261403] 'process raft request' (duration: 66.639702ms)","trace[735261403] 'compare' (duration: 88.685541ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:41:01.426879Z","caller":"traceutil/trace.go:171","msg":"trace[2013027644] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"260.22516ms","start":"2026-05-25T14:41:01.166614Z","end":"2026-05-25T14:41:01.426839Z","steps":["trace[2013027644] 'process raft request' (duration: 259.971465ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:41:01.496442Z","caller":"traceutil/trace.go:171","msg":"trace[1771023313] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"280.744738ms","start":"2026-05-25T14:41:01.21566Z","end":"2026-05-25T14:41:01.496404Z","steps":["trace[1771023313] 'process raft request' (duration: 280.541684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:41:01.496543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.398185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T14:41:01.496607Z","caller":"traceutil/trace.go:171","msg":"trace[747931964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8600; }","duration":"154.515307ms","start":"2026-05-25T14:41:01.342075Z","end":"2026-05-25T14:41:01.496591Z","steps":["trace[747931964] 'agreement among raft nodes before linearized reading' (duration: 154.397524ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:41:01.496413Z","caller":"traceutil/trace.go:171","msg":"trace[1710914430] linearizableReadLoop","detail":"{readStateIndex:8908; appliedIndex:8906; }","duration":"154.243922ms","start":"2026-05-25T14:41:01.342122Z","end":"2026-05-25T14:41:01.496366Z","steps":["trace[1710914430] 'read index received' (duration: 84.46891ms)","trace[1710914430] 'applied index is now lower than readState.Index' (duration: 69.773742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:41:01.497312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.783885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5b856f48dc-pgsrl.18b2d5e971de7a34\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-05-25T14:41:01.497862Z","caller":"traceutil/trace.go:171","msg":"trace[1631683877] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5b856f48dc-pgsrl.18b2d5e971de7a34; range_end:; response_count:1; response_revision:8600; }","duration":"115.370646ms","start":"2026-05-25T14:41:01.382454Z","end":"2026-05-25T14:41:01.497824Z","steps":["trace[1631683877] 'agreement among raft nodes before linearized reading' (duration: 114.746194ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:41:39.460535Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243b.wal"} {"level":"info","ts":"2026-05-25T14:43:00.895805Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T14:43:00.902106Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-25T14:43:00.902188Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-25T14:45:19.020286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8277} {"level":"info","ts":"2026-05-25T14:45:19.099153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8277,"took":"76.347946ms","hash":2802640621,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":25370624,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-25T14:45:19.099211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2802640621,"revision":8277,"compact-revision":6113} {"level":"info","ts":"2026-05-25T14:46:01.245684Z","caller":"traceutil/trace.go:171","msg":"trace[751652084] transaction","detail":"{read_only:false; response_revision:11195; number_of_response:1; }","duration":"110.767466ms","start":"2026-05-25T14:46:01.134893Z","end":"2026-05-25T14:46:01.24566Z","steps":["trace[751652084] 'process raft request' (duration: 110.608033ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:46:01.649793Z","caller":"traceutil/trace.go:171","msg":"trace[1102559123] linearizableReadLoop","detail":"{readStateIndex:11583; appliedIndex:11582; }","duration":"136.682997ms","start":"2026-05-25T14:46:01.513087Z","end":"2026-05-25T14:46:01.64977Z","steps":["trace[1102559123] 'read index received' (duration: 105.483836ms)","trace[1102559123] 'applied index is now lower than readState.Index' (duration: 31.198231ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:46:01.649949Z","caller":"traceutil/trace.go:171","msg":"trace[221642683] transaction","detail":"{read_only:false; response_revision:11201; number_of_response:1; }","duration":"120.483021ms","start":"2026-05-25T14:46:01.529457Z","end":"2026-05-25T14:46:01.64994Z","steps":["trace[221642683] 'process raft request' (duration: 120.279487ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:46:01.649885Z","caller":"traceutil/trace.go:171","msg":"trace[1456152379] transaction","detail":"{read_only:false; response_revision:11200; number_of_response:1; }","duration":"155.721929ms","start":"2026-05-25T14:46:01.494107Z","end":"2026-05-25T14:46:01.649829Z","steps":["trace[1456152379] 'process raft request' (duration: 124.449006ms)","trace[1456152379] 'compare' (duration: 31.052498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:46:01.650039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.931653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v6srl\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-25T14:46:01.650124Z","caller":"traceutil/trace.go:171","msg":"trace[1494330135] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v6srl; range_end:; response_count:1; response_revision:11201; }","duration":"137.043625ms","start":"2026-05-25T14:46:01.51306Z","end":"2026-05-25T14:46:01.650103Z","steps":["trace[1494330135] 'agreement among raft nodes before linearized reading' (duration: 136.795181ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:46:01.650356Z","caller":"traceutil/trace.go:171","msg":"trace[1888405231] transaction","detail":"{read_only:false; response_revision:11202; number_of_response:1; }","duration":"120.38285ms","start":"2026-05-25T14:46:01.529955Z","end":"2026-05-25T14:46:01.650338Z","steps":["trace[1888405231] 'process raft request' (duration: 119.924191ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:46:01.650482Z","caller":"traceutil/trace.go:171","msg":"trace[512285224] transaction","detail":"{read_only:false; response_revision:11203; number_of_response:1; }","duration":"111.556922ms","start":"2026-05-25T14:46:01.538917Z","end":"2026-05-25T14:46:01.650474Z","steps":["trace[512285224] 'process raft request' (duration: 111.132164ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:50:19.027202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10774} {"level":"info","ts":"2026-05-25T14:50:19.106694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10774,"took":"76.903489ms","hash":1004379725,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":27697152,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-25T14:50:19.106755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1004379725,"revision":10774,"compact-revision":8277} {"level":"warn","ts":"2026-05-25T14:52:24.833594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.997682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T14:52:24.833595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.677401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T14:52:24.833697Z","caller":"traceutil/trace.go:171","msg":"trace[507869039] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:13765; }","duration":"107.165774ms","start":"2026-05-25T14:52:24.726512Z","end":"2026-05-25T14:52:24.833678Z","steps":["trace[507869039] 'count revisions from in-memory index tree' (duration: 106.895989ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:52:24.833716Z","caller":"traceutil/trace.go:171","msg":"trace[1653720040] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:13765; }","duration":"101.852944ms","start":"2026-05-25T14:52:24.731839Z","end":"2026-05-25T14:52:24.833692Z","steps":["trace[1653720040] 'count revisions from in-memory index tree' (duration: 101.585879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:52:33.075158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.353545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T14:52:33.075267Z","caller":"traceutil/trace.go:171","msg":"trace[853356552] linearizableReadLoop","detail":"{readStateIndex:14295; appliedIndex:14294; }","duration":"173.110067ms","start":"2026-05-25T14:52:32.902138Z","end":"2026-05-25T14:52:33.075248Z","steps":["trace[853356552] 'read index received' (duration: 63.421µs)","trace[853356552] 'applied index is now lower than readState.Index' (duration: 173.045476ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T14:52:33.075362Z","caller":"traceutil/trace.go:171","msg":"trace[1808245095] transaction","detail":"{read_only:false; response_revision:13820; number_of_response:1; }","duration":"412.030205ms","start":"2026-05-25T14:52:32.663299Z","end":"2026-05-25T14:52:33.075329Z","steps":["trace[1808245095] 'process raft request' (duration: 13.409125ms)","trace[1808245095] 'compare' (duration: 398.036159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:52:33.075478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.346621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v6srl\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-25T14:52:33.075511Z","caller":"traceutil/trace.go:171","msg":"trace[1227299327] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v6srl; range_end:; response_count:1; response_revision:13820; }","duration":"173.407743ms","start":"2026-05-25T14:52:32.902094Z","end":"2026-05-25T14:52:33.075501Z","steps":["trace[1227299327] 'agreement among raft nodes before linearized reading' (duration: 173.24932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:52:33.075516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:52:32.663088Z","time spent":"412.351581ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T14:55:19.033567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12954} {"level":"info","ts":"2026-05-25T14:55:19.107615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12954,"took":"71.99109ms","hash":1525960584,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":30711808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-25T14:55:19.107694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1525960584,"revision":12954,"compact-revision":10774} {"level":"info","ts":"2026-05-25T14:59:58.745055Z","caller":"traceutil/trace.go:171","msg":"trace[132829008] transaction","detail":"{read_only:false; response_revision:17475; number_of_response:1; }","duration":"115.762405ms","start":"2026-05-25T14:59:58.629271Z","end":"2026-05-25T14:59:58.745034Z","steps":["trace[132829008] 'process raft request' (duration: 115.634253ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:59:59.316649Z","caller":"traceutil/trace.go:171","msg":"trace[578641579] linearizableReadLoop","detail":"{readStateIndex:18070; appliedIndex:18069; }","duration":"426.26033ms","start":"2026-05-25T14:59:58.890364Z","end":"2026-05-25T14:59:59.316624Z","steps":["trace[578641579] 'read index received' (duration: 426.119827ms)","trace[578641579] 'applied index is now lower than readState.Index' (duration: 139.453µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T14:59:59.316842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.441623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T14:59:59.316764Z","caller":"traceutil/trace.go:171","msg":"trace[465540447] transaction","detail":"{read_only:false; response_revision:17478; number_of_response:1; }","duration":"437.660776ms","start":"2026-05-25T14:59:58.87903Z","end":"2026-05-25T14:59:59.316691Z","steps":["trace[465540447] 'process raft request' (duration: 437.439122ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T14:59:59.316889Z","caller":"traceutil/trace.go:171","msg":"trace[1989713892] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:17478; }","duration":"426.563025ms","start":"2026-05-25T14:59:58.890313Z","end":"2026-05-25T14:59:59.316876Z","steps":["trace[1989713892] 'agreement among raft nodes before linearized reading' (duration: 426.450943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T14:59:59.316933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:58.890292Z","time spent":"426.630586ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":4,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-05-25T14:59:59.317233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T14:59:58.879Z","time spent":"437.90427ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20895,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:00:19.039529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15322} {"level":"info","ts":"2026-05-25T15:00:19.13828Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15322,"took":"95.969078ms","hash":3035466386,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":32481280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T15:00:19.138336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3035466386,"revision":15322,"compact-revision":12954} {"level":"info","ts":"2026-05-25T15:03:40.625693Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T15:03:40.630634Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-25T15:03:40.630895Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-25T15:05:19.046012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17716} {"level":"info","ts":"2026-05-25T15:05:19.142566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17716,"took":"93.060947ms","hash":3048974306,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":34381824,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T15:05:19.142622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3048974306,"revision":17716,"compact-revision":15322} {"level":"info","ts":"2026-05-25T15:05:47.453897Z","caller":"traceutil/trace.go:171","msg":"trace[931205980] transaction","detail":"{read_only:false; response_revision:20265; number_of_response:1; }","duration":"161.532348ms","start":"2026-05-25T15:05:47.292335Z","end":"2026-05-25T15:05:47.453867Z","steps":["trace[931205980] 'process raft request' (duration: 161.348604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:05:47.70756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.661028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:05:47.707729Z","caller":"traceutil/trace.go:171","msg":"trace[1283756700] transaction","detail":"{read_only:false; response_revision:20266; number_of_response:1; }","duration":"414.262616ms","start":"2026-05-25T15:05:47.293449Z","end":"2026-05-25T15:05:47.707711Z","steps":["trace[1283756700] 'process raft request' (duration: 279.374444ms)","trace[1283756700] 'compare' (duration: 134.29459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:05:47.707821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:05:47.293434Z","time spent":"414.335958ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:05:47.708065Z","caller":"traceutil/trace.go:171","msg":"trace[1012430148] linearizableReadLoop","detail":"{readStateIndex:20952; appliedIndex:20948; }","duration":"329.157771ms","start":"2026-05-25T15:05:47.378887Z","end":"2026-05-25T15:05:47.708045Z","steps":["trace[1012430148] 'read index received' (duration: 74.782251ms)","trace[1012430148] 'applied index is now lower than readState.Index' (duration: 254.37429ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:05:47.70807Z","caller":"traceutil/trace.go:171","msg":"trace[28735529] transaction","detail":"{read_only:false; response_revision:20267; number_of_response:1; }","duration":"414.542031ms","start":"2026-05-25T15:05:47.293505Z","end":"2026-05-25T15:05:47.708047Z","steps":["trace[28735529] 'process raft request' (duration: 414.146074ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:47.708117Z","caller":"traceutil/trace.go:171","msg":"trace[1091004483] transaction","detail":"{read_only:false; response_revision:20268; number_of_response:1; }","duration":"414.228006ms","start":"2026-05-25T15:05:47.293842Z","end":"2026-05-25T15:05:47.70807Z","steps":["trace[1091004483] 'process raft request' (duration: 414.079513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:05:47.708212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:05:47.293486Z","time spent":"414.681685ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:05:47.708333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:05:47.293823Z","time spent":"414.396699ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:05:47.780085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.183505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-25T15:05:47.78011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.217769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:05:47.780201Z","caller":"traceutil/trace.go:171","msg":"trace[1215375694] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20268; }","duration":"401.334821ms","start":"2026-05-25T15:05:47.378838Z","end":"2026-05-25T15:05:47.780173Z","steps":["trace[1215375694] 'agreement among raft nodes before linearized reading' (duration: 329.301974ms)","trace[1215375694] 'range keys from in-memory index tree' (duration: 71.819643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:05:47.780237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.3269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-cfn-789f6b9f5c-dmx2r.18b2d733180690aa\" ","response":"range_response_count:1 size:907"} {"level":"warn","ts":"2026-05-25T15:05:47.780255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:05:47.37882Z","time spent":"401.421894ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T15:05:47.780268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.43674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:05:47.780298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.440041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-25T15:05:47.780298Z","caller":"traceutil/trace.go:171","msg":"trace[1511669595] range","detail":"{range_begin:/registry/events/openstack/heat-cfn-789f6b9f5c-dmx2r.18b2d733180690aa; range_end:; response_count:1; response_revision:20268; }","duration":"322.421833ms","start":"2026-05-25T15:05:47.45786Z","end":"2026-05-25T15:05:47.780282Z","steps":["trace[1511669595] 'agreement among raft nodes before linearized reading' (duration: 322.32182ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:47.780321Z","caller":"traceutil/trace.go:171","msg":"trace[896847258] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:20268; }","duration":"266.497941ms","start":"2026-05-25T15:05:47.513816Z","end":"2026-05-25T15:05:47.780314Z","steps":["trace[896847258] 'agreement among raft nodes before linearized reading' (duration: 266.351727ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:47.780331Z","caller":"traceutil/trace.go:171","msg":"trace[359362371] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:20268; }","duration":"355.543952ms","start":"2026-05-25T15:05:47.424769Z","end":"2026-05-25T15:05:47.780312Z","steps":["trace[359362371] 'agreement among raft nodes before linearized reading' (duration: 355.436849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:05:47.78035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:05:47.457841Z","time spent":"322.497075ms","remote":"127.0.0.1:39890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":929,"request content":"key:\"/registry/events/openstack/heat-cfn-789f6b9f5c-dmx2r.18b2d733180690aa\" "} {"level":"warn","ts":"2026-05-25T15:05:47.78037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:05:47.424753Z","time spent":"355.606013ms","remote":"127.0.0.1:45900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-05-25T15:05:47.780104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.755582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:05:47.780457Z","caller":"traceutil/trace.go:171","msg":"trace[902941215] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:20268; }","duration":"122.15063ms","start":"2026-05-25T15:05:47.658262Z","end":"2026-05-25T15:05:47.780412Z","steps":["trace[902941215] 'agreement among raft nodes before linearized reading' (duration: 121.733381ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:47.780142Z","caller":"traceutil/trace.go:171","msg":"trace[1986755958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20268; }","duration":"374.295387ms","start":"2026-05-25T15:05:47.405835Z","end":"2026-05-25T15:05:47.78013Z","steps":["trace[1986755958] 'agreement among raft nodes before linearized reading' (duration: 374.125914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:05:47.780616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:05:47.405814Z","time spent":"374.795407ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T15:05:48.06172Z","caller":"traceutil/trace.go:171","msg":"trace[618916034] linearizableReadLoop","detail":"{readStateIndex:20954; appliedIndex:20953; }","duration":"277.599921ms","start":"2026-05-25T15:05:47.784103Z","end":"2026-05-25T15:05:48.061703Z","steps":["trace[618916034] 'read index received' (duration: 277.54249ms)","trace[618916034] 'applied index is now lower than readState.Index' (duration: 56.761µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:05:48.061859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.741454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:05:48.061879Z","caller":"traceutil/trace.go:171","msg":"trace[1039127206] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20269; }","duration":"277.815575ms","start":"2026-05-25T15:05:47.784058Z","end":"2026-05-25T15:05:48.061874Z","steps":["trace[1039127206] 'agreement among raft nodes before linearized reading' (duration: 277.702573ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:48.061869Z","caller":"traceutil/trace.go:171","msg":"trace[1384437512] transaction","detail":"{read_only:false; response_revision:20269; number_of_response:1; }","duration":"278.63063ms","start":"2026-05-25T15:05:47.7832Z","end":"2026-05-25T15:05:48.06183Z","steps":["trace[1384437512] 'process raft request' (duration: 278.353135ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:48.10463Z","caller":"traceutil/trace.go:171","msg":"trace[1363915929] transaction","detail":"{read_only:false; response_revision:20270; number_of_response:1; }","duration":"320.099168ms","start":"2026-05-25T15:05:47.784487Z","end":"2026-05-25T15:05:48.104587Z","steps":["trace[1363915929] 'process raft request' (duration: 319.961975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:05:48.104793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:05:47.78447Z","time spent":"320.239381ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:05:48.263266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.095689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:05:48.263483Z","caller":"traceutil/trace.go:171","msg":"trace[409468887] transaction","detail":"{read_only:false; response_revision:20272; number_of_response:1; }","duration":"128.547801ms","start":"2026-05-25T15:05:48.134914Z","end":"2026-05-25T15:05:48.263462Z","steps":["trace[409468887] 'process raft request' (duration: 128.46504ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:48.263485Z","caller":"traceutil/trace.go:171","msg":"trace[584906053] transaction","detail":"{read_only:false; response_revision:20271; number_of_response:1; }","duration":"155.37423ms","start":"2026-05-25T15:05:48.108086Z","end":"2026-05-25T15:05:48.26346Z","steps":["trace[584906053] 'compare' (duration: 150.960147ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:48.399467Z","caller":"traceutil/trace.go:171","msg":"trace[1223521136] transaction","detail":"{read_only:false; response_revision:20273; number_of_response:1; }","duration":"263.77954ms","start":"2026-05-25T15:05:48.135652Z","end":"2026-05-25T15:05:48.399431Z","steps":["trace[1223521136] 'process raft request' (duration: 252.167798ms)","trace[1223521136] 'compare' (duration: 11.308375ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:05:48.399765Z","caller":"traceutil/trace.go:171","msg":"trace[1337153780] transaction","detail":"{read_only:false; response_revision:20274; number_of_response:1; }","duration":"263.976763ms","start":"2026-05-25T15:05:48.135762Z","end":"2026-05-25T15:05:48.399739Z","steps":["trace[1337153780] 'process raft request' (duration: 263.492313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:05:48.399939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.789837ms","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-25T15:05:48.400026Z","caller":"traceutil/trace.go:171","msg":"trace[1898702414] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20274; }","duration":"236.916939ms","start":"2026-05-25T15:05:48.163086Z","end":"2026-05-25T15:05:48.400003Z","steps":["trace[1898702414] 'agreement among raft nodes before linearized reading' (duration: 236.742696ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:05:48.399976Z","caller":"traceutil/trace.go:171","msg":"trace[1644209917] linearizableReadLoop","detail":"{readStateIndex:20959; appliedIndex:20955; }","duration":"236.557843ms","start":"2026-05-25T15:05:48.163114Z","end":"2026-05-25T15:05:48.399672Z","steps":["trace[1644209917] 'read index received' (duration: 84.007326ms)","trace[1644209917] 'applied index is now lower than readState.Index' (duration: 152.549237ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:09:39.410473Z","caller":"traceutil/trace.go:171","msg":"trace[250158915] transaction","detail":"{read_only:false; response_revision:22650; number_of_response:1; }","duration":"258.372225ms","start":"2026-05-25T15:09:39.152063Z","end":"2026-05-25T15:09:39.410436Z","steps":["trace[250158915] 'process raft request' (duration: 256.47328ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:09:39.501327Z","caller":"traceutil/trace.go:171","msg":"trace[1391220062] transaction","detail":"{read_only:false; response_revision:22651; number_of_response:1; }","duration":"280.394944ms","start":"2026-05-25T15:09:39.220914Z","end":"2026-05-25T15:09:39.501309Z","steps":["trace[1391220062] 'process raft request' (duration: 280.258132ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:09:39.501586Z","caller":"traceutil/trace.go:171","msg":"trace[214464786] linearizableReadLoop","detail":"{readStateIndex:23410; appliedIndex:23407; }","duration":"233.81563ms","start":"2026-05-25T15:09:39.267702Z","end":"2026-05-25T15:09:39.501518Z","steps":["trace[214464786] 'read index received' (duration: 140.861835ms)","trace[214464786] 'applied index is now lower than readState.Index' (duration: 92.949025ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:09:39.501668Z","caller":"traceutil/trace.go:171","msg":"trace[1893902589] transaction","detail":"{read_only:false; response_revision:22652; number_of_response:1; }","duration":"278.943197ms","start":"2026-05-25T15:09:39.222712Z","end":"2026-05-25T15:09:39.501655Z","steps":["trace[1893902589] 'process raft request' (duration: 278.56473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:09:39.501891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.184567ms","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-25T15:09:39.501956Z","caller":"traceutil/trace.go:171","msg":"trace[1355735911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22652; }","duration":"234.298069ms","start":"2026-05-25T15:09:39.26764Z","end":"2026-05-25T15:09:39.501938Z","steps":["trace[1355735911] 'agreement among raft nodes before linearized reading' (duration: 234.068475ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:09:39.568117Z","caller":"traceutil/trace.go:171","msg":"trace[2075201186] transaction","detail":"{read_only:false; response_revision:22653; number_of_response:1; }","duration":"149.714553ms","start":"2026-05-25T15:09:39.418366Z","end":"2026-05-25T15:09:39.568081Z","steps":["trace[2075201186] 'process raft request' (duration: 149.310685ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:09:39.569507Z","caller":"traceutil/trace.go:171","msg":"trace[642226727] transaction","detail":"{read_only:false; response_revision:22654; number_of_response:1; }","duration":"132.891533ms","start":"2026-05-25T15:09:39.436528Z","end":"2026-05-25T15:09:39.56942Z","steps":["trace[642226727] 'process raft request' (duration: 131.450657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:09:39.570134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.957588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:09:39.570369Z","caller":"traceutil/trace.go:171","msg":"trace[286970326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22654; }","duration":"228.190832ms","start":"2026-05-25T15:09:39.342116Z","end":"2026-05-25T15:09:39.570306Z","steps":["trace[286970326] 'agreement among raft nodes before linearized reading' (duration: 227.368897ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:10:19.051878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20119} {"level":"info","ts":"2026-05-25T15:10:19.164538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20119,"took":"107.104524ms","hash":1219842429,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":41287680,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-25T15:10:19.164695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1219842429,"revision":20119,"compact-revision":17716} {"level":"warn","ts":"2026-05-25T15:12:59.468293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.334721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:12:59.468425Z","caller":"traceutil/trace.go:171","msg":"trace[1568198435] linearizableReadLoop","detail":"{readStateIndex:25537; appliedIndex:25536; }","duration":"196.428808ms","start":"2026-05-25T15:12:59.271977Z","end":"2026-05-25T15:12:59.468406Z","steps":["trace[1568198435] 'read index received' (duration: 47.38µs)","trace[1568198435] 'applied index is now lower than readState.Index' (duration: 196.379968ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:59.468468Z","caller":"traceutil/trace.go:171","msg":"trace[1055188638] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"298.409665ms","start":"2026-05-25T15:12:59.170022Z","end":"2026-05-25T15:12:59.468431Z","steps":["trace[1055188638] 'process raft request' (duration: 76.837719ms)","trace[1055188638] 'compare' (duration: 221.000385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:12:59.468564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.581172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:12:59.468623Z","caller":"traceutil/trace.go:171","msg":"trace[276913179] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24724; }","duration":"196.677193ms","start":"2026-05-25T15:12:59.27193Z","end":"2026-05-25T15:12:59.468607Z","steps":["trace[276913179] 'agreement among raft nodes before linearized reading' (duration: 196.581091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:59.46869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.011333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T15:12:59.468782Z","caller":"traceutil/trace.go:171","msg":"trace[431497640] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24724; }","duration":"175.144735ms","start":"2026-05-25T15:12:59.293614Z","end":"2026-05-25T15:12:59.468759Z","steps":["trace[431497640] 'agreement among raft nodes before linearized reading' (duration: 174.978722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:12:59.468848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.656265ms","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-25T15:12:59.468883Z","caller":"traceutil/trace.go:171","msg":"trace[1689801572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24724; }","duration":"174.739276ms","start":"2026-05-25T15:12:59.294135Z","end":"2026-05-25T15:12:59.468874Z","steps":["trace[1689801572] 'agreement among raft nodes before linearized reading' (duration: 174.617994ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:12:59.691232Z","caller":"traceutil/trace.go:171","msg":"trace[2046893147] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"169.67942ms","start":"2026-05-25T15:12:59.521515Z","end":"2026-05-25T15:12:59.691195Z","steps":["trace[2046893147] 'process raft request' (duration: 104.034204ms)","trace[2046893147] 'compare' (duration: 65.399502ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:12:59.784032Z","caller":"traceutil/trace.go:171","msg":"trace[186958234] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"191.232171ms","start":"2026-05-25T15:12:59.592777Z","end":"2026-05-25T15:12:59.784009Z","steps":["trace[186958234] 'process raft request' (duration: 191.099227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.375459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.722445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:00.375638Z","caller":"traceutil/trace.go:171","msg":"trace[2037381179] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"394.061311ms","start":"2026-05-25T15:12:59.981561Z","end":"2026-05-25T15:13:00.375622Z","steps":["trace[2037381179] 'process raft request' (duration: 393.99004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.375704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:59.981536Z","time spent":"394.139732ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:00.376031Z","caller":"traceutil/trace.go:171","msg":"trace[1611948691] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"567.36877ms","start":"2026-05-25T15:12:59.80863Z","end":"2026-05-25T15:13:00.375999Z","steps":["trace[1611948691] 'process raft request' (duration: 320.885681ms)","trace[1611948691] 'compare' (duration: 245.323027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:00.376187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.523228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:13:00.376215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:59.808609Z","time spent":"567.508803ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:00.376271Z","caller":"traceutil/trace.go:171","msg":"trace[1472382469] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24729; }","duration":"543.66384ms","start":"2026-05-25T15:12:59.832587Z","end":"2026-05-25T15:13:00.376251Z","steps":["trace[1472382469] 'agreement among raft nodes before linearized reading' (duration: 543.482777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.37631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:59.832565Z","time spent":"543.737242ms","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:13:00.376396Z","caller":"traceutil/trace.go:171","msg":"trace[42076330] linearizableReadLoop","detail":"{readStateIndex:25541; appliedIndex:25540; }","duration":"543.003958ms","start":"2026-05-25T15:12:59.832638Z","end":"2026-05-25T15:13:00.375642Z","steps":["trace[42076330] 'read index received' (duration: 296.890075ms)","trace[42076330] 'applied index is now lower than readState.Index' (duration: 246.110052ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:00.434275Z","caller":"traceutil/trace.go:171","msg":"trace[2043316643] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"221.369142ms","start":"2026-05-25T15:13:00.212881Z","end":"2026-05-25T15:13:00.43425Z","steps":["trace[2043316643] 'process raft request' (duration: 221.191879ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:00.469186Z","caller":"traceutil/trace.go:171","msg":"trace[1247936577] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"102.574077ms","start":"2026-05-25T15:13:00.366589Z","end":"2026-05-25T15:13:00.469163Z","steps":["trace[1247936577] 'process raft request' (duration: 102.139119ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:00.469568Z","caller":"traceutil/trace.go:171","msg":"trace[403123886] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"102.544235ms","start":"2026-05-25T15:13:00.367008Z","end":"2026-05-25T15:13:00.469553Z","steps":["trace[403123886] 'process raft request' (duration: 101.872733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.469924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.064611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:00.47Z","caller":"traceutil/trace.go:171","msg":"trace[895351145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24732; }","duration":"127.188753ms","start":"2026-05-25T15:13:00.342798Z","end":"2026-05-25T15:13:00.469987Z","steps":["trace[895351145] 'agreement among raft nodes before linearized reading' (duration: 127.067301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.470355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.250965ms","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-25T15:13:00.470441Z","caller":"traceutil/trace.go:171","msg":"trace[1310502882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24732; }","duration":"561.365136ms","start":"2026-05-25T15:12:59.909053Z","end":"2026-05-25T15:13:00.470419Z","steps":["trace[1310502882] 'agreement among raft nodes before linearized reading' (duration: 561.117601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.47049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:59.90904Z","time spent":"561.441998ms","remote":"127.0.0.1:40040","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-25T15:13:00.4706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.43648ms","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-25T15:13:00.47063Z","caller":"traceutil/trace.go:171","msg":"trace[64321386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24732; }","duration":"589.46733ms","start":"2026-05-25T15:12:59.881156Z","end":"2026-05-25T15:13:00.470623Z","steps":["trace[64321386] 'agreement among raft nodes before linearized reading' (duration: 589.368108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.470652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:59.881117Z","time spent":"589.528781ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T15:13:00.470817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.217153ms","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-25T15:13:00.470847Z","caller":"traceutil/trace.go:171","msg":"trace[1361911191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24732; }","duration":"562.282743ms","start":"2026-05-25T15:12:59.908554Z","end":"2026-05-25T15:13:00.470837Z","steps":["trace[1361911191] 'agreement among raft nodes before linearized reading' (duration: 562.188802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:00.47087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:12:59.908535Z","time spent":"562.330085ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-25T15:13:04.835841Z","caller":"traceutil/trace.go:171","msg":"trace[1061124675] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"130.07645ms","start":"2026-05-25T15:13:04.705741Z","end":"2026-05-25T15:13:04.835817Z","steps":["trace[1061124675] 'process raft request' (duration: 129.891896ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:05.119779Z","caller":"traceutil/trace.go:171","msg":"trace[1833391155] linearizableReadLoop","detail":"{readStateIndex:25579; appliedIndex:25577; }","duration":"328.610957ms","start":"2026-05-25T15:13:04.791134Z","end":"2026-05-25T15:13:05.119745Z","steps":["trace[1833391155] 'read index received' (duration: 44.508164ms)","trace[1833391155] 'applied index is now lower than readState.Index' (duration: 284.101083ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:05.120119Z","caller":"traceutil/trace.go:171","msg":"trace[1642388088] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"378.316902ms","start":"2026-05-25T15:13:04.741781Z","end":"2026-05-25T15:13:05.120098Z","steps":["trace[1642388088] 'process raft request' (duration: 343.655164ms)","trace[1642388088] 'compare' (duration: 34.097597ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:05.12026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.064055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:05.120374Z","caller":"traceutil/trace.go:171","msg":"trace[755168014] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24765; }","duration":"329.260039ms","start":"2026-05-25T15:13:04.791091Z","end":"2026-05-25T15:13:05.120351Z","steps":["trace[755168014] 'agreement among raft nodes before linearized reading' (duration: 329.017155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:05.120296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:04.741752Z","time spent":"378.424903ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:05.120441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:04.791074Z","time spent":"329.356611ms","remote":"127.0.0.1:57300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-25T15:13:05.12944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.45397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-25T15:13:05.129508Z","caller":"traceutil/trace.go:171","msg":"trace[1370616546] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24766; }","duration":"186.569172ms","start":"2026-05-25T15:13:04.942922Z","end":"2026-05-25T15:13:05.129491Z","steps":["trace[1370616546] 'agreement among raft nodes before linearized reading' (duration: 186.384258ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:05.473772Z","caller":"traceutil/trace.go:171","msg":"trace[463159764] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"157.496571ms","start":"2026-05-25T15:13:05.316251Z","end":"2026-05-25T15:13:05.473747Z","steps":["trace[463159764] 'process raft request' (duration: 157.342248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:05.829705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.265036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:05.829815Z","caller":"traceutil/trace.go:171","msg":"trace[731845575] linearizableReadLoop","detail":"{readStateIndex:25582; appliedIndex:25581; }","duration":"307.528348ms","start":"2026-05-25T15:13:05.522269Z","end":"2026-05-25T15:13:05.829798Z","steps":["trace[731845575] 'read index received' (duration: 130.113439ms)","trace[731845575] 'applied index is now lower than readState.Index' (duration: 177.413668ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:05.829887Z","caller":"traceutil/trace.go:171","msg":"trace[982245053] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"338.608438ms","start":"2026-05-25T15:13:05.491253Z","end":"2026-05-25T15:13:05.829862Z","steps":["trace[982245053] 'process raft request' (duration: 161.098887ms)","trace[982245053] 'compare' (duration: 177.140694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:05.829979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.742232ms","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-25T15:13:05.830024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:05.491231Z","time spent":"338.72412ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:05.830028Z","caller":"traceutil/trace.go:171","msg":"trace[322371263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24768; }","duration":"307.823734ms","start":"2026-05-25T15:13:05.52219Z","end":"2026-05-25T15:13:05.830014Z","steps":["trace[322371263] 'agreement among raft nodes before linearized reading' (duration: 307.680691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:05.830082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:05.522129Z","time spent":"307.938616ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-25T15:13:06.252445Z","caller":"traceutil/trace.go:171","msg":"trace[1363239522] linearizableReadLoop","detail":"{readStateIndex:25584; appliedIndex:25582; }","duration":"422.571832ms","start":"2026-05-25T15:13:05.829841Z","end":"2026-05-25T15:13:06.252413Z","steps":["trace[1363239522] 'read index received' (duration: 11.299095ms)","trace[1363239522] 'applied index is now lower than readState.Index' (duration: 411.271907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:06.252665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.175572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:06.252739Z","caller":"traceutil/trace.go:171","msg":"trace[586090189] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:24771; }","duration":"503.294885ms","start":"2026-05-25T15:13:05.749424Z","end":"2026-05-25T15:13:06.252719Z","steps":["trace[586090189] 'agreement among raft nodes before linearized reading' (duration: 503.164892ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:06.25276Z","caller":"traceutil/trace.go:171","msg":"trace[311984475] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"452.006251ms","start":"2026-05-25T15:13:05.800735Z","end":"2026-05-25T15:13:06.252741Z","steps":["trace[311984475] 'process raft request' (duration: 440.88027ms)","trace[311984475] 'compare' (duration: 10.355446ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:06.252756Z","caller":"traceutil/trace.go:171","msg":"trace[1744697085] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"418.357672ms","start":"2026-05-25T15:13:05.834349Z","end":"2026-05-25T15:13:06.252707Z","steps":["trace[1744697085] 'process raft request' (duration: 417.959355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:06.252786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:05.749398Z","time spent":"503.377436ms","remote":"127.0.0.1:56880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-05-25T15:13:06.252874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:05.800713Z","time spent":"452.082822ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:06.252867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.844116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:13:06.252885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.198163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-25T15:13:06.252908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:05.834316Z","time spent":"418.511475ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:06.252867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.710332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-25T15:13:06.252948Z","caller":"traceutil/trace.go:171","msg":"trace[775727550] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24771; }","duration":"109.312655ms","start":"2026-05-25T15:13:06.143623Z","end":"2026-05-25T15:13:06.252935Z","steps":["trace[775727550] 'agreement among raft nodes before linearized reading' (duration: 109.07571ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:06.252953Z","caller":"traceutil/trace.go:171","msg":"trace[2011097580] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:24771; }","duration":"472.977088ms","start":"2026-05-25T15:13:05.779952Z","end":"2026-05-25T15:13:06.25293Z","steps":["trace[2011097580] 'agreement among raft nodes before linearized reading' (duration: 472.721734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:06.252994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:05.779933Z","time spent":"473.049729ms","remote":"127.0.0.1:60194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:13:06.252987Z","caller":"traceutil/trace.go:171","msg":"trace[1537764476] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24771; }","duration":"198.829534ms","start":"2026-05-25T15:13:06.054125Z","end":"2026-05-25T15:13:06.252955Z","steps":["trace[1537764476] 'agreement among raft nodes before linearized reading' (duration: 198.567849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:06.833909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.94947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:06.834076Z","caller":"traceutil/trace.go:171","msg":"trace[555327775] linearizableReadLoop","detail":"{readStateIndex:25590; appliedIndex:25588; }","duration":"332.314497ms","start":"2026-05-25T15:13:06.501746Z","end":"2026-05-25T15:13:06.834061Z","steps":["trace[555327775] 'read index received' (duration: 196.182544ms)","trace[555327775] 'applied index is now lower than readState.Index' (duration: 136.131033ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:06.834193Z","caller":"traceutil/trace.go:171","msg":"trace[50872567] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"340.416032ms","start":"2026-05-25T15:13:06.493766Z","end":"2026-05-25T15:13:06.834182Z","steps":["trace[50872567] 'process raft request' (duration: 204.116535ms)","trace[50872567] 'compare' (duration: 135.837488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:06.834252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.493733Z","time spent":"340.485812ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:06.834321Z","caller":"traceutil/trace.go:171","msg":"trace[660289856] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"339.854641ms","start":"2026-05-25T15:13:06.494437Z","end":"2026-05-25T15:13:06.834291Z","steps":["trace[660289856] 'process raft request' (duration: 339.569765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:06.834367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.624313ms","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-25T15:13:06.834413Z","caller":"traceutil/trace.go:171","msg":"trace[1816347144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24776; }","duration":"332.710045ms","start":"2026-05-25T15:13:06.501688Z","end":"2026-05-25T15:13:06.834399Z","steps":["trace[1816347144] 'agreement among raft nodes before linearized reading' (duration: 332.533111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:06.834446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.50167Z","time spent":"332.769406ms","remote":"127.0.0.1:40040","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-25T15:13:06.834454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.890386ms","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-25T15:13:06.834478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.494428Z","time spent":"339.965723ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:06.834522Z","caller":"traceutil/trace.go:171","msg":"trace[122313961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24776; }","duration":"325.979388ms","start":"2026-05-25T15:13:06.508525Z","end":"2026-05-25T15:13:06.834504Z","steps":["trace[122313961] 'agreement among raft nodes before linearized reading' (duration: 325.744973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:06.834502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.051793ms","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-25T15:13:06.834557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.508487Z","time spent":"326.063509ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-25T15:13:06.834598Z","caller":"traceutil/trace.go:171","msg":"trace[1601666369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24776; }","duration":"332.201915ms","start":"2026-05-25T15:13:06.502367Z","end":"2026-05-25T15:13:06.834569Z","steps":["trace[1601666369] 'agreement among raft nodes before linearized reading' (duration: 331.886879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:06.834648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.502358Z","time spent":"332.279006ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T15:13:07.166332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.135984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:07.166435Z","caller":"traceutil/trace.go:171","msg":"trace[677499629] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"328.106768ms","start":"2026-05-25T15:13:06.838309Z","end":"2026-05-25T15:13:07.166416Z","steps":["trace[677499629] 'process raft request' (duration: 201.809501ms)","trace[677499629] 'compare' (duration: 125.349659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:07.166497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.838252Z","time spent":"328.21736ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:07.230847Z","caller":"traceutil/trace.go:171","msg":"trace[962119537] linearizableReadLoop","detail":"{readStateIndex:25592; appliedIndex:25590; }","duration":"391.528613ms","start":"2026-05-25T15:13:06.839292Z","end":"2026-05-25T15:13:07.23082Z","steps":["trace[962119537] 'read index received' (duration: 200.843823ms)","trace[962119537] 'applied index is now lower than readState.Index' (duration: 190.68358ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:07.231203Z","caller":"traceutil/trace.go:171","msg":"trace[1485516651] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"391.275408ms","start":"2026-05-25T15:13:06.839892Z","end":"2026-05-25T15:13:07.231168Z","steps":["trace[1485516651] 'process raft request' (duration: 390.878981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.231318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.005731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-25T15:13:07.231355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.839874Z","time spent":"391.391ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:07.231366Z","caller":"traceutil/trace.go:171","msg":"trace[447263369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24779; }","duration":"392.084053ms","start":"2026-05-25T15:13:06.83927Z","end":"2026-05-25T15:13:07.231354Z","steps":["trace[447263369] 'agreement among raft nodes before linearized reading' (duration: 391.92881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.231415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.839264Z","time spent":"392.140244ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T15:13:07.231537Z","caller":"traceutil/trace.go:171","msg":"trace[209275430] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"392.341358ms","start":"2026-05-25T15:13:06.839185Z","end":"2026-05-25T15:13:07.231526Z","steps":["trace[209275430] 'process raft request' (duration: 391.426711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.231646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:06.83917Z","time spent":"392.398519ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:07.617664Z","caller":"traceutil/trace.go:171","msg":"trace[1214844725] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"483.119842ms","start":"2026-05-25T15:13:07.134523Z","end":"2026-05-25T15:13:07.617642Z","steps":["trace[1214844725] 'process raft request' (duration: 483.069261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.617804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:07.134495Z","time spent":"483.235763ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:07.618094Z","caller":"traceutil/trace.go:171","msg":"trace[879373775] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"491.886808ms","start":"2026-05-25T15:13:07.1262Z","end":"2026-05-25T15:13:07.618087Z","steps":["trace[879373775] 'process raft request' (duration: 491.263536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.618131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:07.126172Z","time spent":"491.941109ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:07.61823Z","caller":"traceutil/trace.go:171","msg":"trace[1025424842] linearizableReadLoop","detail":"{readStateIndex:25595; appliedIndex:25595; }","duration":"387.290341ms","start":"2026-05-25T15:13:07.230923Z","end":"2026-05-25T15:13:07.618213Z","steps":["trace[1025424842] 'read index received' (duration: 387.284171ms)","trace[1025424842] 'applied index is now lower than readState.Index' (duration: 5.09µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:07.618267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.883533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:07.618293Z","caller":"traceutil/trace.go:171","msg":"trace[1086309907] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24781; }","duration":"436.948025ms","start":"2026-05-25T15:13:07.181337Z","end":"2026-05-25T15:13:07.618285Z","steps":["trace[1086309907] 'agreement among raft nodes before linearized reading' (duration: 436.894704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.618322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:07.181318Z","time spent":"436.995295ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-25T15:13:07.618354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.029629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:13:07.618395Z","caller":"traceutil/trace.go:171","msg":"trace[487294503] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24781; }","duration":"572.093799ms","start":"2026-05-25T15:13:07.046278Z","end":"2026-05-25T15:13:07.618371Z","steps":["trace[487294503] 'agreement among raft nodes before linearized reading' (duration: 571.987267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.61842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:07.046256Z","time spent":"572.157461ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-25T15:13:07.635478Z","caller":"traceutil/trace.go:171","msg":"trace[1593486114] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"399.251629ms","start":"2026-05-25T15:13:07.236167Z","end":"2026-05-25T15:13:07.635418Z","steps":["trace[1593486114] 'process raft request' (duration: 398.986894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.635621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:07.236147Z","time spent":"399.400941ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:07.814633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.287044ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T15:13:07.815081Z","caller":"traceutil/trace.go:171","msg":"trace[1187542503] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"235.010371ms","start":"2026-05-25T15:13:07.580044Z","end":"2026-05-25T15:13:07.815055Z","steps":["trace[1187542503] 'process raft request' (duration: 234.794297ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:07.815077Z","caller":"traceutil/trace.go:171","msg":"trace[120966892] linearizableReadLoop","detail":"{readStateIndex:25598; appliedIndex:25595; }","duration":"193.071026ms","start":"2026-05-25T15:13:07.621975Z","end":"2026-05-25T15:13:07.815046Z","steps":["trace[120966892] 'read index received' (duration: 13.036188ms)","trace[120966892] 'applied index is now lower than readState.Index' (duration: 180.032018ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:07.972794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.66636ms","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-25T15:13:07.972884Z","caller":"traceutil/trace.go:171","msg":"trace[106112484] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24783; }","duration":"210.800152ms","start":"2026-05-25T15:13:07.762058Z","end":"2026-05-25T15:13:07.972859Z","steps":["trace[106112484] 'agreement among raft nodes before linearized reading' (duration: 53.257341ms)","trace[106112484] 'count revisions from in-memory index tree' (duration: 157.419889ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:07.973045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.693885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-25T15:13:07.973117Z","caller":"traceutil/trace.go:171","msg":"trace[1461495482] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24783; }","duration":"185.808018ms","start":"2026-05-25T15:13:07.78729Z","end":"2026-05-25T15:13:07.973098Z","steps":["trace[1461495482] 'agreement among raft nodes before linearized reading' (duration: 28.046302ms)","trace[1461495482] 'count revisions from in-memory index tree' (duration: 157.657293ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:07.973182Z","caller":"traceutil/trace.go:171","msg":"trace[801088735] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"138.148563ms","start":"2026-05-25T15:13:07.834979Z","end":"2026-05-25T15:13:07.973128Z","steps":["trace[801088735] 'process raft request' (duration: 137.618354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:07.973724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.732318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:13:07.973781Z","caller":"traceutil/trace.go:171","msg":"trace[768973504] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24783; }","duration":"351.825009ms","start":"2026-05-25T15:13:07.62194Z","end":"2026-05-25T15:13:07.973765Z","steps":["trace[768973504] 'agreement among raft nodes before linearized reading' (duration: 193.207288ms)","trace[768973504] 'range keys from in-memory index tree' (duration: 157.871608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:07.973827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:07.621922Z","time spent":"351.887051ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T15:13:08.298911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.358019ms","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-25T15:13:08.299004Z","caller":"traceutil/trace.go:171","msg":"trace[820317845] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24784; }","duration":"224.488252ms","start":"2026-05-25T15:13:08.074495Z","end":"2026-05-25T15:13:08.298984Z","steps":["trace[820317845] 'range keys from in-memory index tree' (duration: 224.307418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.299489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.497346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:08.29955Z","caller":"traceutil/trace.go:171","msg":"trace[1458644741] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:24784; }","duration":"200.576397ms","start":"2026-05-25T15:13:08.09896Z","end":"2026-05-25T15:13:08.299536Z","steps":["trace[1458644741] 'count revisions from in-memory index tree' (duration: 200.428564ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:08.619734Z","caller":"traceutil/trace.go:171","msg":"trace[1994054323] linearizableReadLoop","detail":"{readStateIndex:25601; appliedIndex:25600; }","duration":"315.486069ms","start":"2026-05-25T15:13:08.304215Z","end":"2026-05-25T15:13:08.619701Z","steps":["trace[1994054323] 'read index received' (duration: 37.740996ms)","trace[1994054323] 'applied index is now lower than readState.Index' (duration: 277.744003ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:08.619769Z","caller":"traceutil/trace.go:171","msg":"trace[1632499161] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"316.277034ms","start":"2026-05-25T15:13:08.303466Z","end":"2026-05-25T15:13:08.619743Z","steps":["trace[1632499161] 'process raft request' (duration: 316.0718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.619882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:08.303446Z","time spent":"316.378706ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:08.619996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.753594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:13:08.620057Z","caller":"traceutil/trace.go:171","msg":"trace[1088317892] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24786; }","duration":"315.852566ms","start":"2026-05-25T15:13:08.304187Z","end":"2026-05-25T15:13:08.62004Z","steps":["trace[1088317892] 'agreement among raft nodes before linearized reading' (duration: 315.650092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.6201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:08.304179Z","time spent":"315.911177ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T15:13:08.959515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.923045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:08.95977Z","caller":"traceutil/trace.go:171","msg":"trace[1896063697] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"119.954537ms","start":"2026-05-25T15:13:08.839792Z","end":"2026-05-25T15:13:08.959746Z","steps":["trace[1896063697] 'process raft request' (duration: 119.869285ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:08.960027Z","caller":"traceutil/trace.go:171","msg":"trace[1878072568] linearizableReadLoop","detail":"{readStateIndex:25603; appliedIndex:25601; }","duration":"340.195168ms","start":"2026-05-25T15:13:08.61981Z","end":"2026-05-25T15:13:08.960005Z","steps":["trace[1878072568] 'read index received' (duration: 99.624281ms)","trace[1878072568] 'applied index is now lower than readState.Index' (duration: 240.568237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:08.9601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.003605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:08.960139Z","caller":"traceutil/trace.go:171","msg":"trace[1989745145] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24789; }","duration":"555.055976ms","start":"2026-05-25T15:13:08.405073Z","end":"2026-05-25T15:13:08.960129Z","steps":["trace[1989745145] 'agreement among raft nodes before linearized reading' (duration: 554.978185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.960156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.004565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:13:08.960166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:08.405014Z","time spent":"555.144968ms","remote":"127.0.0.1:60072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:13:08.960216Z","caller":"traceutil/trace.go:171","msg":"trace[430762989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24789; }","duration":"614.098907ms","start":"2026-05-25T15:13:08.346103Z","end":"2026-05-25T15:13:08.960202Z","steps":["trace[430762989] 'agreement among raft nodes before linearized reading' (duration: 614.002986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.960273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:08.346081Z","time spent":"614.179819ms","remote":"127.0.0.1:39806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T15:13:08.959782Z","caller":"traceutil/trace.go:171","msg":"trace[82020898] transaction","detail":"{read_only:false; response_revision:24787; number_of_response:1; }","duration":"501.377797ms","start":"2026-05-25T15:13:08.45838Z","end":"2026-05-25T15:13:08.959758Z","steps":["trace[82020898] 'process raft request' (duration: 261.136746ms)","trace[82020898] 'compare' (duration: 239.815283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:08.96042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:08.458345Z","time spent":"502.008699ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:08.960482Z","caller":"traceutil/trace.go:171","msg":"trace[950537665] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"487.740669ms","start":"2026-05-25T15:13:08.472697Z","end":"2026-05-25T15:13:08.960438Z","steps":["trace[950537665] 'process raft request' (duration: 486.902133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.960585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:08.472681Z","time spent":"487.854411ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:08.987121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.712659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:08.987208Z","caller":"traceutil/trace.go:171","msg":"trace[438413156] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24790; }","duration":"260.850791ms","start":"2026-05-25T15:13:08.726334Z","end":"2026-05-25T15:13:08.987185Z","steps":["trace[438413156] 'agreement among raft nodes before linearized reading' (duration: 260.690188ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:08.987233Z","caller":"traceutil/trace.go:171","msg":"trace[1660181654] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"146.95314ms","start":"2026-05-25T15:13:08.840254Z","end":"2026-05-25T15:13:08.987207Z","steps":["trace[1660181654] 'process raft request' (duration: 146.590633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.987566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.52705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-05-25T15:13:08.987666Z","caller":"traceutil/trace.go:171","msg":"trace[2096925301] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24790; }","duration":"365.752634ms","start":"2026-05-25T15:13:08.62189Z","end":"2026-05-25T15:13:08.987643Z","steps":["trace[2096925301] 'agreement among raft nodes before linearized reading' (duration: 365.446098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.987681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.141721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-25T15:13:08.987727Z","caller":"traceutil/trace.go:171","msg":"trace[1176440130] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:24790; }","duration":"267.242803ms","start":"2026-05-25T15:13:08.720471Z","end":"2026-05-25T15:13:08.987714Z","steps":["trace[1176440130] 'agreement among raft nodes before linearized reading' (duration: 267.05914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:08.987727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:08.621875Z","time spent":"365.840075ms","remote":"127.0.0.1:39978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18564,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-25T15:13:09.292615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.678173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:09.292705Z","caller":"traceutil/trace.go:171","msg":"trace[1864638845] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:24790; }","duration":"157.828906ms","start":"2026-05-25T15:13:09.134857Z","end":"2026-05-25T15:13:09.292686Z","steps":["trace[1864638845] 'count revisions from in-memory index tree' (duration: 157.590941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:09.292881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.277311ms","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-25T15:13:09.292969Z","caller":"traceutil/trace.go:171","msg":"trace[294817828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24790; }","duration":"122.409984ms","start":"2026-05-25T15:13:09.170541Z","end":"2026-05-25T15:13:09.292951Z","steps":["trace[294817828] 'range keys from in-memory index tree' (duration: 122.065657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:09.700786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.627777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:09.701027Z","caller":"traceutil/trace.go:171","msg":"trace[366378287] transaction","detail":"{read_only:false; response_revision:24793; number_of_response:1; }","duration":"402.933519ms","start":"2026-05-25T15:13:09.298072Z","end":"2026-05-25T15:13:09.701006Z","steps":["trace[366378287] 'process raft request' (duration: 402.848787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:09.701125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:09.298053Z","time spent":"403.024561ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:09.701113Z","caller":"traceutil/trace.go:171","msg":"trace[1504624703] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"403.031031ms","start":"2026-05-25T15:13:09.298054Z","end":"2026-05-25T15:13:09.701085Z","steps":["trace[1504624703] 'process raft request' (duration: 161.002006ms)","trace[1504624703] 'compare' (duration: 241.488184ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:09.701716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:09.298032Z","time spent":"403.55114ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:09.752006Z","caller":"traceutil/trace.go:171","msg":"trace[1893836866] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"128.137023ms","start":"2026-05-25T15:13:09.623838Z","end":"2026-05-25T15:13:09.751975Z","steps":["trace[1893836866] 'process raft request' (duration: 127.904919ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:09.987191Z","caller":"traceutil/trace.go:171","msg":"trace[645452517] linearizableReadLoop","detail":"{readStateIndex:25610; appliedIndex:25608; }","duration":"347.263882ms","start":"2026-05-25T15:13:09.63989Z","end":"2026-05-25T15:13:09.987154Z","steps":["trace[645452517] 'read index received' (duration: 111.858104ms)","trace[645452517] 'applied index is now lower than readState.Index' (duration: 235.404448ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:09.987369Z","caller":"traceutil/trace.go:171","msg":"trace[1052942345] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"363.432729ms","start":"2026-05-25T15:13:09.6239Z","end":"2026-05-25T15:13:09.987332Z","steps":["trace[1052942345] 'process raft request' (duration: 277.371945ms)","trace[1052942345] 'compare' (duration: 85.446522ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:09.987421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.515607ms","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-25T15:13:09.987479Z","caller":"traceutil/trace.go:171","msg":"trace[1952937055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24795; }","duration":"347.612408ms","start":"2026-05-25T15:13:09.63985Z","end":"2026-05-25T15:13:09.987462Z","steps":["trace[1952937055] 'agreement among raft nodes before linearized reading' (duration: 347.417015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:09.987533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:09.639835Z","time spent":"347.68622ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T15:13:09.987531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:09.623869Z","time spent":"363.572882ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:10.393976Z","caller":"traceutil/trace.go:171","msg":"trace[236331162] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"414.811624ms","start":"2026-05-25T15:13:09.979143Z","end":"2026-05-25T15:13:10.393954Z","steps":["trace[236331162] 'process raft request' (duration: 406.940145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:10.394117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:09.979118Z","time spent":"414.928646ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:10.394242Z","caller":"traceutil/trace.go:171","msg":"trace[1775527112] transaction","detail":"{read_only:false; response_revision:24798; number_of_response:1; }","duration":"402.120254ms","start":"2026-05-25T15:13:09.992016Z","end":"2026-05-25T15:13:10.394136Z","steps":["trace[1775527112] 'process raft request' (duration: 401.878499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:10.394701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:09.991984Z","time spent":"402.346418ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:10.741924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.192786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:10.742032Z","caller":"traceutil/trace.go:171","msg":"trace[354193076] linearizableReadLoop","detail":"{readStateIndex:25614; appliedIndex:25613; }","duration":"347.947346ms","start":"2026-05-25T15:13:10.394066Z","end":"2026-05-25T15:13:10.742013Z","steps":["trace[354193076] 'read index received' (duration: 142.480255ms)","trace[354193076] 'applied index is now lower than readState.Index' (duration: 205.465951ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:10.742056Z","caller":"traceutil/trace.go:171","msg":"trace[1004060522] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"391.289957ms","start":"2026-05-25T15:13:10.350741Z","end":"2026-05-25T15:13:10.742031Z","steps":["trace[1004060522] 'process raft request' (duration: 185.905508ms)","trace[1004060522] 'compare' (duration: 205.079624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:10.742255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.834903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"warn","ts":"2026-05-25T15:13:10.742238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.172672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:10.742291Z","caller":"traceutil/trace.go:171","msg":"trace[158966918] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24799; }","duration":"376.900105ms","start":"2026-05-25T15:13:10.365377Z","end":"2026-05-25T15:13:10.742278Z","steps":["trace[158966918] 'agreement among raft nodes before linearized reading' (duration: 376.724712ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:10.742314Z","caller":"traceutil/trace.go:171","msg":"trace[305451364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24799; }","duration":"346.290194ms","start":"2026-05-25T15:13:10.396005Z","end":"2026-05-25T15:13:10.742295Z","steps":["trace[305451364] 'agreement among raft nodes before linearized reading' (duration: 346.156132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:10.742333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.36536Z","time spent":"376.962966ms","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-25T15:13:10.742358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.395979Z","time spent":"346.370075ms","remote":"127.0.0.1:39808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T15:13:10.742445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.350725Z","time spent":"391.540453ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:10.742308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.775036ms","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-25T15:13:10.742687Z","caller":"traceutil/trace.go:171","msg":"trace[859669151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24799; }","duration":"118.172824ms","start":"2026-05-25T15:13:10.62448Z","end":"2026-05-25T15:13:10.742653Z","steps":["trace[859669151] 'agreement among raft nodes before linearized reading' (duration: 117.688155ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:11.254094Z","caller":"traceutil/trace.go:171","msg":"trace[1933030931] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"504.14424ms","start":"2026-05-25T15:13:10.749925Z","end":"2026-05-25T15:13:11.254069Z","steps":["trace[1933030931] 'process raft request' (duration: 503.931846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:11.254254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.749903Z","time spent":"504.278002ms","remote":"127.0.0.1:40066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:11.254433Z","caller":"traceutil/trace.go:171","msg":"trace[1148935449] transaction","detail":"{read_only:false; response_revision:24803; number_of_response:1; }","duration":"482.108122ms","start":"2026-05-25T15:13:10.772295Z","end":"2026-05-25T15:13:11.254403Z","steps":["trace[1148935449] 'process raft request' (duration: 481.981659ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:11.254449Z","caller":"traceutil/trace.go:171","msg":"trace[784764462] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"504.153861ms","start":"2026-05-25T15:13:10.750263Z","end":"2026-05-25T15:13:11.254417Z","steps":["trace[784764462] 'process raft request' (duration: 503.760263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:11.254573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.772272Z","time spent":"482.223773ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:11.254628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.75025Z","time spent":"504.278563ms","remote":"127.0.0.1:40066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:11.288532Z","caller":"traceutil/trace.go:171","msg":"trace[2130267492] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"323.549002ms","start":"2026-05-25T15:13:10.964959Z","end":"2026-05-25T15:13:11.288508Z","steps":["trace[2130267492] 'process raft request' (duration: 322.752647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:11.288711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.964934Z","time spent":"323.661773ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:11.288866Z","caller":"traceutil/trace.go:171","msg":"trace[234121379] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"323.186424ms","start":"2026-05-25T15:13:10.965668Z","end":"2026-05-25T15:13:11.288854Z","steps":["trace[234121379] 'process raft request' (duration: 322.169045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:11.28893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.965638Z","time spent":"323.261676ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:11.289222Z","caller":"traceutil/trace.go:171","msg":"trace[1746225607] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"297.436828ms","start":"2026-05-25T15:13:10.991769Z","end":"2026-05-25T15:13:11.289206Z","steps":["trace[1746225607] 'process raft request' (duration: 296.144383ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:11.289339Z","caller":"traceutil/trace.go:171","msg":"trace[1008400888] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"321.35309ms","start":"2026-05-25T15:13:10.967695Z","end":"2026-05-25T15:13:11.289048Z","steps":["trace[1008400888] 'process raft request' (duration: 320.187298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:11.28942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:10.967668Z","time spent":"321.717607ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:21.637055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.393211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-25T15:13:21.637123Z","caller":"traceutil/trace.go:171","msg":"trace[1230361710] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24883; }","duration":"163.502594ms","start":"2026-05-25T15:13:21.473601Z","end":"2026-05-25T15:13:21.637104Z","steps":["trace[1230361710] 'range keys from in-memory index tree' (duration: 163.254879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:23.340131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521226404861414451,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-25T15:13:23.495625Z","caller":"traceutil/trace.go:171","msg":"trace[1924655368] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"707.885838ms","start":"2026-05-25T15:13:22.787702Z","end":"2026-05-25T15:13:23.495588Z","steps":["trace[1924655368] 'process raft request' (duration: 707.639194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:23.495869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:22.787677Z","time spent":"708.054021ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:24.304596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.519024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:24.304766Z","caller":"traceutil/trace.go:171","msg":"trace[1690530505] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"1.470537575s","start":"2026-05-25T15:13:22.834198Z","end":"2026-05-25T15:13:24.304736Z","steps":["trace[1690530505] 'process raft request' (duration: 1.099802817s)","trace[1690530505] 'compare' (duration: 370.365531ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:24.30482Z","caller":"traceutil/trace.go:171","msg":"trace[677931573] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"920.306611ms","start":"2026-05-25T15:13:23.3845Z","end":"2026-05-25T15:13:24.304806Z","steps":["trace[677931573] 'process raft request' (duration: 920.245939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.304869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.384456Z","time spent":"920.391602ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:24.304885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:22.834126Z","time spent":"1.470692628s","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:24.304964Z","caller":"traceutil/trace.go:171","msg":"trace[1348989646] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"946.162ms","start":"2026-05-25T15:13:23.358795Z","end":"2026-05-25T15:13:24.304957Z","steps":["trace[1348989646] 'process raft request' (duration: 945.881385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.305002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.358774Z","time spent":"946.210622ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:24.305135Z","caller":"traceutil/trace.go:171","msg":"trace[1243097673] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"489.374ms","start":"2026-05-25T15:13:23.815753Z","end":"2026-05-25T15:13:24.305127Z","steps":["trace[1243097673] 'process raft request' (duration: 489.345179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.305183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.815731Z","time spent":"489.429801ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:24.305246Z","caller":"traceutil/trace.go:171","msg":"trace[308309159] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"919.010345ms","start":"2026-05-25T15:13:23.386206Z","end":"2026-05-25T15:13:24.305217Z","steps":["trace[308309159] 'process raft request' (duration: 918.811791ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:24.305346Z","caller":"traceutil/trace.go:171","msg":"trace[675750633] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"919.594406ms","start":"2026-05-25T15:13:23.385742Z","end":"2026-05-25T15:13:24.305336Z","steps":["trace[675750633] 'process raft request' (duration: 919.027736ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:24.305384Z","caller":"traceutil/trace.go:171","msg":"trace[854575797] linearizableReadLoop","detail":"{readStateIndex:25712; appliedIndex:25710; }","duration":"1.465410577s","start":"2026-05-25T15:13:22.839966Z","end":"2026-05-25T15:13:24.305376Z","steps":["trace[854575797] 'read index received' (duration: 655.419851ms)","trace[854575797] 'applied index is now lower than readState.Index' (duration: 809.989846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:24.305389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.385711Z","time spent":"919.657607ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:24.305433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.386197Z","time spent":"919.142247ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:24.305548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46558299s","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-25T15:13:24.305575Z","caller":"traceutil/trace.go:171","msg":"trace[2033410354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24899; }","duration":"1.465638152s","start":"2026-05-25T15:13:22.83993Z","end":"2026-05-25T15:13:24.305568Z","steps":["trace[2033410354] 'agreement among raft nodes before linearized reading' (duration: 1.46555905s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.305593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:22.839918Z","time spent":"1.465670842s","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T15:13:24.804338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.955455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:24.804442Z","caller":"traceutil/trace.go:171","msg":"trace[1260222174] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25717; }","duration":"499.019162ms","start":"2026-05-25T15:13:24.305408Z","end":"2026-05-25T15:13:24.804427Z","steps":["trace[1260222174] 'read index received' (duration: 230.062587ms)","trace[1260222174] 'applied index is now lower than readState.Index' (duration: 268.955385ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:24.804523Z","caller":"traceutil/trace.go:171","msg":"trace[1560402395] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"766.948669ms","start":"2026-05-25T15:13:24.037562Z","end":"2026-05-25T15:13:24.804511Z","steps":["trace[1560402395] 'process raft request' (duration: 497.744259ms)","trace[1560402395] 'compare' (duration: 268.723241ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:24.804585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:24.037536Z","time spent":"767.0181ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:24.804672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069613264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:13:24.804801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367278154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:24.804842Z","caller":"traceutil/trace.go:171","msg":"trace[1826645077] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24900; }","duration":"1.367324085s","start":"2026-05-25T15:13:23.437508Z","end":"2026-05-25T15:13:24.804832Z","steps":["trace[1826645077] 'agreement among raft nodes before linearized reading' (duration: 1.367249944s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.804882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.437437Z","time spent":"1.367434348s","remote":"127.0.0.1:35320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-05-25T15:13:24.804966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.206613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-25T15:13:24.805037Z","caller":"traceutil/trace.go:171","msg":"trace[573952294] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24900; }","duration":"571.300994ms","start":"2026-05-25T15:13:24.233717Z","end":"2026-05-25T15:13:24.805018Z","steps":["trace[573952294] 'agreement among raft nodes before linearized reading' (duration: 571.110771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.805099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:24.2337Z","time spent":"571.380817ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T15:13:24.805159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401432413s","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-25T15:13:24.805209Z","caller":"traceutil/trace.go:171","msg":"trace[160139575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24900; }","duration":"1.401520085s","start":"2026-05-25T15:13:23.403678Z","end":"2026-05-25T15:13:24.805198Z","steps":["trace[160139575] 'agreement among raft nodes before linearized reading' (duration: 1.401382412s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.805246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.403662Z","time spent":"1.401575565s","remote":"127.0.0.1:40040","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-25T15:13:24.805419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588015274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T15:13:24.805469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032031261s","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-25T15:13:24.805536Z","caller":"traceutil/trace.go:171","msg":"trace[1822184827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24900; }","duration":"1.032117052s","start":"2026-05-25T15:13:23.7734Z","end":"2026-05-25T15:13:24.805517Z","steps":["trace[1822184827] 'agreement among raft nodes before linearized reading' (duration: 1.031965429s)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:24.805459Z","caller":"traceutil/trace.go:171","msg":"trace[1639655959] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:24900; }","duration":"1.588093805s","start":"2026-05-25T15:13:23.217354Z","end":"2026-05-25T15:13:24.805448Z","steps":["trace[1639655959] 'agreement among raft nodes before linearized reading' (duration: 1.588030154s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.80559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.77339Z","time spent":"1.032186684s","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T15:13:24.805647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958760752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:24.805687Z","caller":"traceutil/trace.go:171","msg":"trace[960027411] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24900; }","duration":"1.958800853s","start":"2026-05-25T15:13:22.846876Z","end":"2026-05-25T15:13:24.805677Z","steps":["trace[960027411] 'agreement among raft nodes before linearized reading' (duration: 1.958738932s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.80572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:22.846828Z","time spent":"1.958885405s","remote":"127.0.0.1:35530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-05-25T15:13:24.804792Z","caller":"traceutil/trace.go:171","msg":"trace[263365182] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24900; }","duration":"1.069776388s","start":"2026-05-25T15:13:23.734984Z","end":"2026-05-25T15:13:24.80476Z","steps":["trace[263365182] 'agreement among raft nodes before linearized reading' (duration: 1.069601754s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.805929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038784279s","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-25T15:13:24.805955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.734962Z","time spent":"1.070979141s","remote":"127.0.0.1:35400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:13:24.805987Z","caller":"traceutil/trace.go:171","msg":"trace[721242377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24900; }","duration":"1.03886598s","start":"2026-05-25T15:13:23.767107Z","end":"2026-05-25T15:13:24.805973Z","steps":["trace[721242377] 'agreement among raft nodes before linearized reading' (duration: 1.038684357s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.806036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.767096Z","time spent":"1.038929752s","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T15:13:24.805596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:23.217335Z","time spent":"1.588246188s","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"info","ts":"2026-05-25T15:13:24.806508Z","caller":"traceutil/trace.go:171","msg":"trace[85231108] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"492.473669ms","start":"2026-05-25T15:13:24.314011Z","end":"2026-05-25T15:13:24.806484Z","steps":["trace[85231108] 'process raft request' (duration: 492.337676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.806526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.128314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T15:13:24.806577Z","caller":"traceutil/trace.go:171","msg":"trace[1717084055] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:24902; }","duration":"490.219935ms","start":"2026-05-25T15:13:24.316345Z","end":"2026-05-25T15:13:24.806565Z","steps":["trace[1717084055] 'agreement among raft nodes before linearized reading' (duration: 490.117543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.806611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:24.316327Z","time spent":"490.276017ms","remote":"127.0.0.1:40054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-25T15:13:24.806624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:24.313993Z","time spent":"492.56732ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:13:24.806621Z","caller":"traceutil/trace.go:171","msg":"trace[684864042] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"370.078795ms","start":"2026-05-25T15:13:24.436517Z","end":"2026-05-25T15:13:24.806596Z","steps":["trace[684864042] 'process raft request' (duration: 369.902312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.806677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.425484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:24.806721Z","caller":"traceutil/trace.go:171","msg":"trace[1456505559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24902; }","duration":"466.508916ms","start":"2026-05-25T15:13:24.340201Z","end":"2026-05-25T15:13:24.80671Z","steps":["trace[1456505559] 'agreement among raft nodes before linearized reading' (duration: 466.425094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.806711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.662028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-25T15:13:24.806743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:24.340167Z","time spent":"466.571828ms","remote":"127.0.0.1:39806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T15:13:24.806755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.944991ms","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-25T15:13:24.80678Z","caller":"traceutil/trace.go:171","msg":"trace[575241394] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24902; }","duration":"136.009422ms","start":"2026-05-25T15:13:24.670763Z","end":"2026-05-25T15:13:24.806772Z","steps":["trace[575241394] 'agreement among raft nodes before linearized reading' (duration: 135.951681ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:24.806774Z","caller":"traceutil/trace.go:171","msg":"trace[978885067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24902; }","duration":"349.755179ms","start":"2026-05-25T15:13:24.457002Z","end":"2026-05-25T15:13:24.806757Z","steps":["trace[978885067] 'agreement among raft nodes before linearized reading' (duration: 349.595286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:24.806737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:24.436496Z","time spent":"370.178856ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T15:13:24.806817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:24.456984Z","time spent":"349.822221ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T15:13:25.382016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.407691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:25.382079Z","caller":"traceutil/trace.go:171","msg":"trace[767772182] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:24906; }","duration":"115.550124ms","start":"2026-05-25T15:13:25.266514Z","end":"2026-05-25T15:13:25.382064Z","steps":["trace[767772182] 'count revisions from in-memory index tree' (duration: 115.309398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:25.382198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.957255ms","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-25T15:13:25.382295Z","caller":"traceutil/trace.go:171","msg":"trace[1048087666] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24906; }","duration":"476.116087ms","start":"2026-05-25T15:13:24.906154Z","end":"2026-05-25T15:13:25.38227Z","steps":["trace[1048087666] 'range keys from in-memory index tree' (duration: 475.857673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:25.382361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:13:24.906127Z","time spent":"476.22043ms","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-25T15:13:25.727123Z","caller":"traceutil/trace.go:171","msg":"trace[1055584882] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"225.763515ms","start":"2026-05-25T15:13:25.50133Z","end":"2026-05-25T15:13:25.727093Z","steps":["trace[1055584882] 'process raft request' (duration: 225.597752ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:31.522853Z","caller":"traceutil/trace.go:171","msg":"trace[1465748043] linearizableReadLoop","detail":"{readStateIndex:25826; appliedIndex:25825; }","duration":"262.104955ms","start":"2026-05-25T15:13:31.260725Z","end":"2026-05-25T15:13:31.52283Z","steps":["trace[1465748043] 'read index received' (duration: 261.86556ms)","trace[1465748043] 'applied index is now lower than readState.Index' (duration: 238.585µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:31.523153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.036826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:31.523175Z","caller":"traceutil/trace.go:171","msg":"trace[699864734] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"263.322689ms","start":"2026-05-25T15:13:31.259838Z","end":"2026-05-25T15:13:31.523161Z","steps":["trace[699864734] 'process raft request' (duration: 262.789469ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:31.52321Z","caller":"traceutil/trace.go:171","msg":"trace[68691384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25007; }","duration":"181.135167ms","start":"2026-05-25T15:13:31.34206Z","end":"2026-05-25T15:13:31.523196Z","steps":["trace[68691384] 'agreement among raft nodes before linearized reading' (duration: 181.001395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:31.523419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.700226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9712c717-1468-4f18-a585-e25097f393af\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-25T15:13:31.523463Z","caller":"traceutil/trace.go:171","msg":"trace[1520435588] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9712c717-1468-4f18-a585-e25097f393af; range_end:; response_count:1; response_revision:25007; }","duration":"262.780578ms","start":"2026-05-25T15:13:31.26067Z","end":"2026-05-25T15:13:31.523451Z","steps":["trace[1520435588] 'agreement among raft nodes before linearized reading' (duration: 262.715947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:31.523737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.601363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T15:13:31.523788Z","caller":"traceutil/trace.go:171","msg":"trace[792333018] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:25007; }","duration":"146.655814ms","start":"2026-05-25T15:13:31.377118Z","end":"2026-05-25T15:13:31.523774Z","steps":["trace[792333018] 'agreement among raft nodes before linearized reading' (duration: 146.511282ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:32.092012Z","caller":"traceutil/trace.go:171","msg":"trace[1797278065] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"249.288412ms","start":"2026-05-25T15:13:31.842703Z","end":"2026-05-25T15:13:32.091991Z","steps":["trace[1797278065] 'process raft request' (duration: 249.124609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:32.364336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.803469ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-25T15:13:32.364753Z","caller":"traceutil/trace.go:171","msg":"trace[296717000] linearizableReadLoop","detail":"{readStateIndex:25829; appliedIndex:25828; }","duration":"209.979026ms","start":"2026-05-25T15:13:32.154711Z","end":"2026-05-25T15:13:32.36469Z","steps":["trace[296717000] 'read index received' (duration: 73.659788ms)","trace[296717000] 'applied index is now lower than readState.Index' (duration: 136.317028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:13:32.364991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.255001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-426j8.18b2d7150b4675d9\" ","response":"range_response_count:1 size:957"} {"level":"info","ts":"2026-05-25T15:13:32.365088Z","caller":"traceutil/trace.go:171","msg":"trace[563382141] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-426j8.18b2d7150b4675d9; range_end:; response_count:1; response_revision:25009; }","duration":"210.400204ms","start":"2026-05-25T15:13:32.154666Z","end":"2026-05-25T15:13:32.365066Z","steps":["trace[563382141] 'agreement among raft nodes before linearized reading' (duration: 210.107909ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:32.484898Z","caller":"traceutil/trace.go:171","msg":"trace[482839548] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"142.275241ms","start":"2026-05-25T15:13:32.342596Z","end":"2026-05-25T15:13:32.484871Z","steps":["trace[482839548] 'process raft request' (duration: 142.043166ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:32.484979Z","caller":"traceutil/trace.go:171","msg":"trace[679686729] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"142.266341ms","start":"2026-05-25T15:13:32.342673Z","end":"2026-05-25T15:13:32.484939Z","steps":["trace[679686729] 'process raft request' (duration: 142.100888ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:32.4859Z","caller":"traceutil/trace.go:171","msg":"trace[2040305783] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"143.036705ms","start":"2026-05-25T15:13:32.342836Z","end":"2026-05-25T15:13:32.485872Z","steps":["trace[2040305783] 'process raft request' (duration: 141.981806ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:32.486165Z","caller":"traceutil/trace.go:171","msg":"trace[1434787631] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"142.678079ms","start":"2026-05-25T15:13:32.343428Z","end":"2026-05-25T15:13:32.486106Z","steps":["trace[1434787631] 'process raft request' (duration: 142.382983ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:32.487019Z","caller":"traceutil/trace.go:171","msg":"trace[1548463484] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"119.453616ms","start":"2026-05-25T15:13:32.36755Z","end":"2026-05-25T15:13:32.487004Z","steps":["trace[1548463484] 'process raft request' (duration: 119.355995ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:32.487091Z","caller":"traceutil/trace.go:171","msg":"trace[881780394] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"121.330713ms","start":"2026-05-25T15:13:32.365733Z","end":"2026-05-25T15:13:32.487064Z","steps":["trace[881780394] 'process raft request' (duration: 120.302513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:34.632555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.725711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:13:34.632658Z","caller":"traceutil/trace.go:171","msg":"trace[748628783] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"203.663626ms","start":"2026-05-25T15:13:34.428978Z","end":"2026-05-25T15:13:34.632641Z","steps":["trace[748628783] 'process raft request' (duration: 101.772372ms)","trace[748628783] 'compare' (duration: 101.215661ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:34.708457Z","caller":"traceutil/trace.go:171","msg":"trace[219955469] linearizableReadLoop","detail":"{readStateIndex:25852; appliedIndex:25848; }","duration":"157.248445ms","start":"2026-05-25T15:13:34.551161Z","end":"2026-05-25T15:13:34.70841Z","steps":["trace[219955469] 'read index received' (duration: 156.732755ms)","trace[219955469] 'applied index is now lower than readState.Index' (duration: 514.93µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:34.708797Z","caller":"traceutil/trace.go:171","msg":"trace[136328862] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"216.840187ms","start":"2026-05-25T15:13:34.491934Z","end":"2026-05-25T15:13:34.708774Z","steps":["trace[136328862] 'process raft request' (duration: 216.436588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:34.709998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.793695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9712c717-1468-4f18-a585-e25097f393af\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-05-25T15:13:34.710098Z","caller":"traceutil/trace.go:171","msg":"trace[1097495110] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9712c717-1468-4f18-a585-e25097f393af; range_end:; response_count:1; response_revision:25032; }","duration":"158.961418ms","start":"2026-05-25T15:13:34.551117Z","end":"2026-05-25T15:13:34.710079Z","steps":["trace[1097495110] 'agreement among raft nodes before linearized reading' (duration: 158.647372ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:34.71102Z","caller":"traceutil/trace.go:171","msg":"trace[704363554] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"219.843213ms","start":"2026-05-25T15:13:34.491141Z","end":"2026-05-25T15:13:34.710984Z","steps":["trace[704363554] 'process raft request' (duration: 217.009309ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:34.711089Z","caller":"traceutil/trace.go:171","msg":"trace[175961921] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"219.586878ms","start":"2026-05-25T15:13:34.491463Z","end":"2026-05-25T15:13:34.71105Z","steps":["trace[175961921] 'process raft request' (duration: 216.828866ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:34.711109Z","caller":"traceutil/trace.go:171","msg":"trace[1369763897] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"220.990075ms","start":"2026-05-25T15:13:34.490085Z","end":"2026-05-25T15:13:34.711075Z","steps":["trace[1369763897] 'process raft request' (duration: 217.899986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:34.711128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.170187ms","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-25T15:13:34.711583Z","caller":"traceutil/trace.go:171","msg":"trace[1589176110] 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:25032; }","duration":"125.91872ms","start":"2026-05-25T15:13:34.585449Z","end":"2026-05-25T15:13:34.711368Z","steps":["trace[1589176110] 'agreement among raft nodes before linearized reading' (duration: 123.82989ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:39.448476Z","caller":"traceutil/trace.go:171","msg":"trace[1288867671] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"122.670059ms","start":"2026-05-25T15:13:39.325783Z","end":"2026-05-25T15:13:39.448454Z","steps":["trace[1288867671] 'process raft request' (duration: 122.485095ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:39.6053Z","caller":"traceutil/trace.go:171","msg":"trace[1943311418] linearizableReadLoop","detail":"{readStateIndex:25914; appliedIndex:25913; }","duration":"156.060512ms","start":"2026-05-25T15:13:39.449215Z","end":"2026-05-25T15:13:39.605276Z","steps":["trace[1943311418] 'read index received' (duration: 155.558072ms)","trace[1943311418] 'applied index is now lower than readState.Index' (duration: 501.58µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:39.605328Z","caller":"traceutil/trace.go:171","msg":"trace[139172470] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"181.866903ms","start":"2026-05-25T15:13:39.423434Z","end":"2026-05-25T15:13:39.605301Z","steps":["trace[139172470] 'process raft request' (duration: 181.527676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:39.605517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.276796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-25T15:13:39.605588Z","caller":"traceutil/trace.go:171","msg":"trace[516466661] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25093; }","duration":"156.385199ms","start":"2026-05-25T15:13:39.449185Z","end":"2026-05-25T15:13:39.60557Z","steps":["trace[516466661] 'agreement among raft nodes before linearized reading' (duration: 156.188495ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:49.164535Z","caller":"traceutil/trace.go:171","msg":"trace[1272208421] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"169.985738ms","start":"2026-05-25T15:13:48.994519Z","end":"2026-05-25T15:13:49.164504Z","steps":["trace[1272208421] 'process raft request' (duration: 169.774574ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:49.173803Z","caller":"traceutil/trace.go:171","msg":"trace[207246242] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"179.142911ms","start":"2026-05-25T15:13:48.994631Z","end":"2026-05-25T15:13:49.173774Z","steps":["trace[207246242] 'process raft request' (duration: 178.919367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:13:49.173911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.709342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:13:49.17396Z","caller":"traceutil/trace.go:171","msg":"trace[23136529] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25171; }","duration":"168.795564ms","start":"2026-05-25T15:13:49.005151Z","end":"2026-05-25T15:13:49.173947Z","steps":["trace[23136529] 'agreement among raft nodes before linearized reading' (duration: 168.700452ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:49.173806Z","caller":"traceutil/trace.go:171","msg":"trace[1676336364] linearizableReadLoop","detail":"{readStateIndex:25994; appliedIndex:25989; }","duration":"168.60931ms","start":"2026-05-25T15:13:49.005172Z","end":"2026-05-25T15:13:49.173781Z","steps":["trace[1676336364] 'read index received' (duration: 159.119181ms)","trace[1676336364] 'applied index is now lower than readState.Index' (duration: 9.489369ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:13:49.173966Z","caller":"traceutil/trace.go:171","msg":"trace[606202616] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"178.256303ms","start":"2026-05-25T15:13:48.995668Z","end":"2026-05-25T15:13:49.173924Z","steps":["trace[606202616] 'process raft request' (duration: 178.059369ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:49.17403Z","caller":"traceutil/trace.go:171","msg":"trace[1942154736] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"178.684562ms","start":"2026-05-25T15:13:48.995333Z","end":"2026-05-25T15:13:49.174018Z","steps":["trace[1942154736] 'process raft request' (duration: 178.365476ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:13:49.17409Z","caller":"traceutil/trace.go:171","msg":"trace[281981506] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"178.358125ms","start":"2026-05-25T15:13:48.995694Z","end":"2026-05-25T15:13:49.174053Z","steps":["trace[281981506] 'process raft request' (duration: 178.05769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:02.915334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.253555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:273003"} {"level":"info","ts":"2026-05-25T15:14:02.915409Z","caller":"traceutil/trace.go:171","msg":"trace[2143981011] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25277; }","duration":"225.337717ms","start":"2026-05-25T15:14:02.690053Z","end":"2026-05-25T15:14:02.915391Z","steps":["trace[2143981011] 'range keys from in-memory index tree' (duration: 224.897719ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:02.91589Z","caller":"traceutil/trace.go:171","msg":"trace[1314569093] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"113.438734ms","start":"2026-05-25T15:14:02.802433Z","end":"2026-05-25T15:14:02.915872Z","steps":["trace[1314569093] 'process raft request' (duration: 113.302821ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:02.994761Z","caller":"traceutil/trace.go:171","msg":"trace[1889201066] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"172.269281ms","start":"2026-05-25T15:14:02.822466Z","end":"2026-05-25T15:14:02.994735Z","steps":["trace[1889201066] 'process raft request' (duration: 172.083797ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:02.99498Z","caller":"traceutil/trace.go:171","msg":"trace[575829583] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"172.061007ms","start":"2026-05-25T15:14:02.82291Z","end":"2026-05-25T15:14:02.994971Z","steps":["trace[575829583] 'process raft request' (duration: 171.773482ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:02.995228Z","caller":"traceutil/trace.go:171","msg":"trace[1156643093] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"169.788783ms","start":"2026-05-25T15:14:02.82543Z","end":"2026-05-25T15:14:02.995218Z","steps":["trace[1156643093] 'process raft request' (duration: 169.508118ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:02.995307Z","caller":"traceutil/trace.go:171","msg":"trace[2122315230] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"169.447666ms","start":"2026-05-25T15:14:02.825833Z","end":"2026-05-25T15:14:02.995281Z","steps":["trace[2122315230] 'process raft request' (duration: 169.322554ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:03.17212Z","caller":"traceutil/trace.go:171","msg":"trace[309838667] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"135.053153ms","start":"2026-05-25T15:14:03.037038Z","end":"2026-05-25T15:14:03.172091Z","steps":["trace[309838667] 'process raft request' (duration: 134.89601ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:03.172114Z","caller":"traceutil/trace.go:171","msg":"trace[1655887867] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"149.906365ms","start":"2026-05-25T15:14:03.022164Z","end":"2026-05-25T15:14:03.17207Z","steps":["trace[1655887867] 'process raft request' (duration: 104.721837ms)","trace[1655887867] 'compare' (duration: 44.682539ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:14:03.348796Z","caller":"traceutil/trace.go:171","msg":"trace[284988384] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"121.619648ms","start":"2026-05-25T15:14:03.227144Z","end":"2026-05-25T15:14:03.348763Z","steps":["trace[284988384] 'process raft request' (duration: 121.379533ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:03.348682Z","caller":"traceutil/trace.go:171","msg":"trace[1083656906] linearizableReadLoop","detail":"{readStateIndex:26111; appliedIndex:26110; }","duration":"118.738973ms","start":"2026-05-25T15:14:03.229924Z","end":"2026-05-25T15:14:03.348664Z","steps":["trace[1083656906] 'read index received' (duration: 118.539969ms)","trace[1083656906] 'applied index is now lower than readState.Index' (duration: 197.564µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:14:03.349072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.103131ms","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-25T15:14:03.349157Z","caller":"traceutil/trace.go:171","msg":"trace[1685165659] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25285; }","duration":"119.263523ms","start":"2026-05-25T15:14:03.229872Z","end":"2026-05-25T15:14:03.349136Z","steps":["trace[1685165659] 'agreement among raft nodes before linearized reading' (duration: 118.965417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:03.349314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.99272ms","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-25T15:14:03.34936Z","caller":"traceutil/trace.go:171","msg":"trace[325479758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25285; }","duration":"118.067751ms","start":"2026-05-25T15:14:03.23128Z","end":"2026-05-25T15:14:03.349347Z","steps":["trace[325479758] 'agreement among raft nodes before linearized reading' (duration: 117.950059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:03.349368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.08057ms","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-25T15:14:03.349426Z","caller":"traceutil/trace.go:171","msg":"trace[1860974412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25285; }","duration":"110.164971ms","start":"2026-05-25T15:14:03.23925Z","end":"2026-05-25T15:14:03.349414Z","steps":["trace[1860974412] 'agreement among raft nodes before linearized reading' (duration: 109.979868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:03.349514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.954047ms","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-25T15:14:03.350889Z","caller":"traceutil/trace.go:171","msg":"trace[1134923554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25285; }","duration":"120.382285ms","start":"2026-05-25T15:14:03.230497Z","end":"2026-05-25T15:14:03.350879Z","steps":["trace[1134923554] 'agreement among raft nodes before linearized reading' (duration: 118.467089ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:03.550217Z","caller":"traceutil/trace.go:171","msg":"trace[809585357] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"196.307297ms","start":"2026-05-25T15:14:03.353884Z","end":"2026-05-25T15:14:03.550191Z","steps":["trace[809585357] 'process raft request' (duration: 104.8581ms)","trace[809585357] 'compare' (duration: 90.933327ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:14:03.550513Z","caller":"traceutil/trace.go:171","msg":"trace[792996481] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"195.250356ms","start":"2026-05-25T15:14:03.355238Z","end":"2026-05-25T15:14:03.550489Z","steps":["trace[792996481] 'process raft request' (duration: 194.829978ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:03.550925Z","caller":"traceutil/trace.go:171","msg":"trace[1970626556] linearizableReadLoop","detail":"{readStateIndex:26115; appliedIndex:26112; }","duration":"117.964599ms","start":"2026-05-25T15:14:03.432936Z","end":"2026-05-25T15:14:03.5509Z","steps":["trace[1970626556] 'read index received' (duration: 25.749698ms)","trace[1970626556] 'applied index is now lower than readState.Index' (duration: 92.212371ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:14:03.550959Z","caller":"traceutil/trace.go:171","msg":"trace[2078652356] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"195.402728ms","start":"2026-05-25T15:14:03.355528Z","end":"2026-05-25T15:14:03.550931Z","steps":["trace[2078652356] 'process raft request' (duration: 194.876498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:03.551068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.110201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:14:03.551117Z","caller":"traceutil/trace.go:171","msg":"trace[915323307] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25289; }","duration":"118.201794ms","start":"2026-05-25T15:14:03.432901Z","end":"2026-05-25T15:14:03.551103Z","steps":["trace[915323307] 'agreement among raft nodes before linearized reading' (duration: 118.102682ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:04.058206Z","caller":"traceutil/trace.go:171","msg":"trace[430810124] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"142.656558ms","start":"2026-05-25T15:14:03.915526Z","end":"2026-05-25T15:14:04.058182Z","steps":["trace[430810124] 'process raft request' (duration: 142.469194ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:04.678443Z","caller":"traceutil/trace.go:171","msg":"trace[405509540] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"117.036502ms","start":"2026-05-25T15:14:04.56138Z","end":"2026-05-25T15:14:04.678416Z","steps":["trace[405509540] 'process raft request' (duration: 116.874679ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:05.147768Z","caller":"traceutil/trace.go:171","msg":"trace[1558749613] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"147.216203ms","start":"2026-05-25T15:14:05.000524Z","end":"2026-05-25T15:14:05.14774Z","steps":["trace[1558749613] 'process raft request' (duration: 146.683353ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:05.147812Z","caller":"traceutil/trace.go:171","msg":"trace[1315865281] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"147.235894ms","start":"2026-05-25T15:14:05.000544Z","end":"2026-05-25T15:14:05.14778Z","steps":["trace[1315865281] 'process raft request' (duration: 147.108742ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:05.14787Z","caller":"traceutil/trace.go:171","msg":"trace[847474925] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"147.264355ms","start":"2026-05-25T15:14:05.000545Z","end":"2026-05-25T15:14:05.147809Z","steps":["trace[847474925] 'process raft request' (duration: 147.168593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:05.411846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.155615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:14:05.412092Z","caller":"traceutil/trace.go:171","msg":"trace[1661512699] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"234.40288ms","start":"2026-05-25T15:14:05.177659Z","end":"2026-05-25T15:14:05.412062Z","steps":["trace[1661512699] 'process raft request' (duration: 234.296238ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:05.412094Z","caller":"traceutil/trace.go:171","msg":"trace[274453029] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"235.063192ms","start":"2026-05-25T15:14:05.176977Z","end":"2026-05-25T15:14:05.41204Z","steps":["trace[274453029] 'process raft request' (duration: 108.621902ms)","trace[274453029] 'compare' (duration: 125.764957ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:14:05.565965Z","caller":"traceutil/trace.go:171","msg":"trace[1032283309] linearizableReadLoop","detail":"{readStateIndex:26126; appliedIndex:26125; }","duration":"108.292646ms","start":"2026-05-25T15:14:05.457652Z","end":"2026-05-25T15:14:05.565944Z","steps":["trace[1032283309] 'read index received' (duration: 104.825649ms)","trace[1032283309] 'applied index is now lower than readState.Index' (duration: 3.466187ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:14:05.566061Z","caller":"traceutil/trace.go:171","msg":"trace[423143207] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"148.331946ms","start":"2026-05-25T15:14:05.417721Z","end":"2026-05-25T15:14:05.566053Z","steps":["trace[423143207] 'process raft request' (duration: 144.746117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:05.566337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.642673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:14:05.566434Z","caller":"traceutil/trace.go:171","msg":"trace[162612813] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25300; }","duration":"108.796876ms","start":"2026-05-25T15:14:05.457617Z","end":"2026-05-25T15:14:05.566414Z","steps":["trace[162612813] 'agreement among raft nodes before linearized reading' (duration: 108.583252ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:05.742382Z","caller":"traceutil/trace.go:171","msg":"trace[1126472618] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"171.983105ms","start":"2026-05-25T15:14:05.570376Z","end":"2026-05-25T15:14:05.74236Z","steps":["trace[1126472618] 'process raft request' (duration: 171.849562ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:05.837469Z","caller":"traceutil/trace.go:171","msg":"trace[1787223228] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"267.004509ms","start":"2026-05-25T15:14:05.570447Z","end":"2026-05-25T15:14:05.837452Z","steps":["trace[1787223228] 'process raft request' (duration: 266.846796ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:05.837615Z","caller":"traceutil/trace.go:171","msg":"trace[2141650674] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"267.133532ms","start":"2026-05-25T15:14:05.570475Z","end":"2026-05-25T15:14:05.837608Z","steps":["trace[2141650674] 'process raft request' (duration: 266.933888ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:05.837691Z","caller":"traceutil/trace.go:171","msg":"trace[1275424483] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"267.118681ms","start":"2026-05-25T15:14:05.570552Z","end":"2026-05-25T15:14:05.837671Z","steps":["trace[1275424483] 'process raft request' (duration: 267.029049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:17.730354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.757053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T15:14:17.73073Z","caller":"traceutil/trace.go:171","msg":"trace[360780663] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"221.841341ms","start":"2026-05-25T15:14:17.508844Z","end":"2026-05-25T15:14:17.730685Z","steps":["trace[360780663] 'process raft request' (duration: 101.635949ms)","trace[360780663] 'compare' (duration: 119.58378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:14:17.980776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.274691ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T15:14:17.980885Z","caller":"traceutil/trace.go:171","msg":"trace[936021101] linearizableReadLoop","detail":"{readStateIndex:26215; appliedIndex:26213; }","duration":"352.596603ms","start":"2026-05-25T15:14:17.628271Z","end":"2026-05-25T15:14:17.980867Z","steps":["trace[936021101] 'read index received' (duration: 28.012112ms)","trace[936021101] 'applied index is now lower than readState.Index' (duration: 324.583141ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:14:17.981369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.093963ms","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-25T15:14:17.981422Z","caller":"traceutil/trace.go:171","msg":"trace[1506611577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25386; }","duration":"353.189685ms","start":"2026-05-25T15:14:17.628219Z","end":"2026-05-25T15:14:17.981409Z","steps":["trace[1506611577] 'agreement among raft nodes before linearized reading' (duration: 353.014422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:17.981459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:14:17.628201Z","time spent":"353.248406ms","remote":"127.0.0.1:40040","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-25T15:14:17.981747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.806344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-05-25T15:14:17.981826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.468518ms","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-25T15:14:17.981861Z","caller":"traceutil/trace.go:171","msg":"trace[1082468101] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25386; }","duration":"189.934566ms","start":"2026-05-25T15:14:17.791897Z","end":"2026-05-25T15:14:17.981832Z","steps":["trace[1082468101] 'agreement among raft nodes before linearized reading' (duration: 189.60799ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:17.98192Z","caller":"traceutil/trace.go:171","msg":"trace[797636516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25386; }","duration":"188.614821ms","start":"2026-05-25T15:14:17.79328Z","end":"2026-05-25T15:14:17.981895Z","steps":["trace[797636516] 'agreement among raft nodes before linearized reading' (duration: 188.317956ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:18.098617Z","caller":"traceutil/trace.go:171","msg":"trace[381564108] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"111.216351ms","start":"2026-05-25T15:14:17.987125Z","end":"2026-05-25T15:14:18.098341Z","steps":["trace[381564108] 'process raft request' (duration: 110.982337ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:18.13432Z","caller":"traceutil/trace.go:171","msg":"trace[1266757757] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"146.573392ms","start":"2026-05-25T15:14:17.987705Z","end":"2026-05-25T15:14:18.134278Z","steps":["trace[1266757757] 'process raft request' (duration: 146.495741ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:18.134461Z","caller":"traceutil/trace.go:171","msg":"trace[1108181438] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"146.705995ms","start":"2026-05-25T15:14:17.9877Z","end":"2026-05-25T15:14:18.134406Z","steps":["trace[1108181438] 'process raft request' (duration: 146.369578ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:18.134612Z","caller":"traceutil/trace.go:171","msg":"trace[624025930] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"146.611603ms","start":"2026-05-25T15:14:17.987608Z","end":"2026-05-25T15:14:18.134219Z","steps":["trace[624025930] 'process raft request' (duration: 146.197395ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:18.242838Z","caller":"traceutil/trace.go:171","msg":"trace[1826895824] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"141.762812ms","start":"2026-05-25T15:14:18.101038Z","end":"2026-05-25T15:14:18.242801Z","steps":["trace[1826895824] 'process raft request' (duration: 108.073542ms)","trace[1826895824] 'compare' (duration: 33.550597ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:14:28.301768Z","caller":"traceutil/trace.go:171","msg":"trace[1393955578] linearizableReadLoop","detail":"{readStateIndex:26303; appliedIndex:26302; }","duration":"119.422657ms","start":"2026-05-25T15:14:28.182325Z","end":"2026-05-25T15:14:28.301748Z","steps":["trace[1393955578] 'read index received' (duration: 119.332296ms)","trace[1393955578] 'applied index is now lower than readState.Index' (duration: 89.511µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:14:28.301824Z","caller":"traceutil/trace.go:171","msg":"trace[1385292731] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"145.365009ms","start":"2026-05-25T15:14:28.156435Z","end":"2026-05-25T15:14:28.3018Z","steps":["trace[1385292731] 'process raft request' (duration: 145.177126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:28.301934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.5987ms","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-25T15:14:28.301976Z","caller":"traceutil/trace.go:171","msg":"trace[1809009047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25471; }","duration":"119.686582ms","start":"2026-05-25T15:14:28.18228Z","end":"2026-05-25T15:14:28.301966Z","steps":["trace[1809009047] 'agreement among raft nodes before linearized reading' (duration: 119.53959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:28.369637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.239507ms","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-25T15:14:28.369753Z","caller":"traceutil/trace.go:171","msg":"trace[1042653465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25472; }","duration":"184.38588ms","start":"2026-05-25T15:14:28.185342Z","end":"2026-05-25T15:14:28.369728Z","steps":["trace[1042653465] 'agreement among raft nodes before linearized reading' (duration: 184.071545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:28.369751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.871499ms","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-25T15:14:28.369836Z","caller":"traceutil/trace.go:171","msg":"trace[860525622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25472; }","duration":"185.005952ms","start":"2026-05-25T15:14:28.184809Z","end":"2026-05-25T15:14:28.369815Z","steps":["trace[860525622] 'agreement among raft nodes before linearized reading' (duration: 184.753617ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:28.529748Z","caller":"traceutil/trace.go:171","msg":"trace[1895838176] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"155.470931ms","start":"2026-05-25T15:14:28.374188Z","end":"2026-05-25T15:14:28.529659Z","steps":["trace[1895838176] 'process raft request' (duration: 152.295691ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:28.529889Z","caller":"traceutil/trace.go:171","msg":"trace[972200460] transaction","detail":"{read_only:false; response_revision:25475; number_of_response:1; }","duration":"155.296298ms","start":"2026-05-25T15:14:28.374541Z","end":"2026-05-25T15:14:28.529838Z","steps":["trace[972200460] 'process raft request' (duration: 155.074404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T15:14:28.530051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.346917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T15:14:28.530188Z","caller":"traceutil/trace.go:171","msg":"trace[2109316617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25475; }","duration":"127.542541ms","start":"2026-05-25T15:14:28.402615Z","end":"2026-05-25T15:14:28.530158Z","steps":["trace[2109316617] 'agreement among raft nodes before linearized reading' (duration: 127.309686ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:28.529789Z","caller":"traceutil/trace.go:171","msg":"trace[1406049002] linearizableReadLoop","detail":"{readStateIndex:26307; appliedIndex:26305; }","duration":"127.072822ms","start":"2026-05-25T15:14:28.402685Z","end":"2026-05-25T15:14:28.529757Z","steps":["trace[1406049002] 'read index received' (duration: 123.7603ms)","trace[1406049002] 'applied index is now lower than readState.Index' (duration: 3.310822ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:14:29.288289Z","caller":"traceutil/trace.go:171","msg":"trace[462758322] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"172.710359ms","start":"2026-05-25T15:14:29.115552Z","end":"2026-05-25T15:14:29.288263Z","steps":["trace[462758322] 'process raft request' (duration: 172.548256ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:29.465698Z","caller":"traceutil/trace.go:171","msg":"trace[1000903571] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"316.05857ms","start":"2026-05-25T15:14:29.149611Z","end":"2026-05-25T15:14:29.46567Z","steps":["trace[1000903571] 'process raft request' (duration: 240.379993ms)","trace[1000903571] 'compare' (duration: 75.508654ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T15:14:29.465846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T15:14:29.149595Z","time spent":"316.171693ms","remote":"127.0.0.1:40040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T15:14:29.466068Z","caller":"traceutil/trace.go:171","msg":"trace[2117212038] transaction","detail":"{read_only:false; response_revision:25479; number_of_response:1; }","duration":"180.997876ms","start":"2026-05-25T15:14:29.285042Z","end":"2026-05-25T15:14:29.46604Z","steps":["trace[2117212038] 'process raft request' (duration: 180.583048ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:29.466111Z","caller":"traceutil/trace.go:171","msg":"trace[187389434] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"172.397593ms","start":"2026-05-25T15:14:29.293689Z","end":"2026-05-25T15:14:29.466086Z","steps":["trace[187389434] 'process raft request' (duration: 172.344622ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:29.466228Z","caller":"traceutil/trace.go:171","msg":"trace[177902514] transaction","detail":"{read_only:false; response_revision:25480; number_of_response:1; }","duration":"172.81354ms","start":"2026-05-25T15:14:29.293391Z","end":"2026-05-25T15:14:29.466205Z","steps":["trace[177902514] 'process raft request' (duration: 172.482714ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:14:29.631935Z","caller":"traceutil/trace.go:171","msg":"trace[1680634739] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"120.009908ms","start":"2026-05-25T15:14:29.511891Z","end":"2026-05-25T15:14:29.631901Z","steps":["trace[1680634739] 'process raft request' (duration: 70.161652ms)","trace[1680634739] 'compare' (duration: 49.695603ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T15:15:19.057998Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23133} {"level":"info","ts":"2026-05-25T15:15:19.193074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23133,"took":"130.672999ms","hash":3531475851,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":35639296,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-25T15:15:19.193188Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3531475851,"revision":23133,"compact-revision":20119} {"level":"info","ts":"2026-05-25T15:15:39.42262Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069d7.wal"} {"level":"info","ts":"2026-05-25T15:16:09.020359Z","caller":"traceutil/trace.go:171","msg":"trace[349595940] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"105.590595ms","start":"2026-05-25T15:16:08.914747Z","end":"2026-05-25T15:16:09.020338Z","steps":["trace[349595940] 'process raft request' (duration: 105.426041ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T15:20:19.063767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26052} {"level":"info","ts":"2026-05-25T15:20:19.201136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26052,"took":"133.98039ms","hash":2320499579,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":34476032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T15:20:19.201195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2320499579,"revision":26052,"compact-revision":23133} {"level":"info","ts":"2026-05-25T15:21:01.056539Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T15:21:01.061491Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-25T15:21:01.061708Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-25T15:25:19.070382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28767} {"level":"info","ts":"2026-05-25T15:25:19.220711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28767,"took":"145.58391ms","hash":1735311191,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":30371840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T15:25:19.220778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1735311191,"revision":28767,"compact-revision":26052} {"level":"info","ts":"2026-05-25T15:30:19.076553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30987} {"level":"info","ts":"2026-05-25T15:30:19.189691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30987,"took":"109.618849ms","hash":3381533441,"current-db-size-bytes":58880000,"current-db-size":"59 MB","current-db-size-in-use-bytes":31264768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-25T15:30:19.189826Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3381533441,"revision":30987,"compact-revision":28767} {"level":"info","ts":"2026-05-25T15:35:19.083633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33257} {"level":"info","ts":"2026-05-25T15:35:19.232183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33257,"took":"143.092275ms","hash":169242551,"current-db-size-bytes":59920384,"current-db-size":"60 MB","current-db-size-in-use-bytes":31797248,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T15:35:19.232246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":169242551,"revision":33257,"compact-revision":30987} {"level":"info","ts":"2026-05-25T15:40:19.090088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35552} {"level":"info","ts":"2026-05-25T15:40:19.228911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35552,"took":"134.075703ms","hash":480412960,"current-db-size-bytes":60395520,"current-db-size":"60 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T15:40:19.229002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":480412960,"revision":35552,"compact-revision":33257} {"level":"info","ts":"2026-05-25T15:42:29.795376Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T15:42:29.79985Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-25T15:42:29.799978Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}