{"level":"warn","ts":"2026-05-11T00:58:57.683078Z","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-11T00:58:57.683247Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-11T00:58:57.683331Z","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-11T00:58:57.683339Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-11T00:58:57.683357Z","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-11T00:58:57.68402Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-05-11T00:58:57.684104Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-11T00:58:57.687995Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.669348ms"} {"level":"info","ts":"2026-05-11T00:58:57.701162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-05-11T00:58:57.701281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:58:57.701312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-05-11T00:58:57.701329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:58:57.701349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-05-11T00:58:57.701434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-05-11T00:58:57.709151Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:58:57.714058Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:58:57.715706Z","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-11T00:58:57.718315Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:58:57.71929Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:58:57.719353Z","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-11T00:58:57.719472Z","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-11T00:58:57.719509Z","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-11T00:58:57.720354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-05-11T00:58:57.720573Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-11T00:58:57.721461Z","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-11T00:58:57.721607Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-11T00:58:57.721677Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-11T00:58:57.72181Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:58:57.721863Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:58:58.304506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:58:58.304594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:58:58.30464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-05-11T00:58:58.304664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:58:58.304683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-11T00:58:58.304714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-05-11T00:58:58.304736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-11T00:58:58.306542Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:58:58.306908Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:58:58.307257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:58:58.307379Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:58:58.307572Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:58:58.307625Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:58:58.310906Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-05-11T00:58:58.311579Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:58:58.321013Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:58:58.32113Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:58:58.321169Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:59:34.217467Z","caller":"traceutil/trace.go:171","msg":"trace[1976435321] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"101.084513ms","start":"2026-05-11T00:59:34.116297Z","end":"2026-05-11T00:59:34.217381Z","steps":["trace[1976435321] 'read index received' (duration: 92.669225ms)","trace[1976435321] 'applied index is now lower than readState.Index' (duration: 8.414128ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:34.217554Z","caller":"traceutil/trace.go:171","msg":"trace[422391322] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"195.429256ms","start":"2026-05-11T00:59:34.022076Z","end":"2026-05-11T00:59:34.217505Z","steps":["trace[422391322] 'process raft request' (duration: 193.431059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:34.217859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.530062ms","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-11T00:59:34.218157Z","caller":"traceutil/trace.go:171","msg":"trace[2122284415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"101.711186ms","start":"2026-05-11T00:59:34.116246Z","end":"2026-05-11T00:59:34.217957Z","steps":["trace[2122284415] 'agreement among raft nodes before linearized reading' (duration: 101.400459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:34.606422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.277149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:59:34.606637Z","caller":"traceutil/trace.go:171","msg":"trace[951921866] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"302.158121ms","start":"2026-05-11T00:59:34.304462Z","end":"2026-05-11T00:59:34.606621Z","steps":["trace[951921866] 'read index received' (duration: 223.628732ms)","trace[951921866] 'applied index is now lower than readState.Index' (duration: 78.528219ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:34.606852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.378836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T00:59:34.606788Z","caller":"traceutil/trace.go:171","msg":"trace[1843819326] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"313.395773ms","start":"2026-05-11T00:59:34.293298Z","end":"2026-05-11T00:59:34.606694Z","steps":["trace[1843819326] 'process raft request' (duration: 313.255991ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:34.606895Z","caller":"traceutil/trace.go:171","msg":"trace[1432018287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"302.498878ms","start":"2026-05-11T00:59:34.304386Z","end":"2026-05-11T00:59:34.606884Z","steps":["trace[1432018287] 'agreement among raft nodes before linearized reading' (duration: 302.286644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:34.606963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:34.304355Z","time spent":"302.598739ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T00:59:34.607087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:34.293265Z","time spent":"313.700748ms","remote":"127.0.0.1:46652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:34.607353Z","caller":"traceutil/trace.go:171","msg":"trace[1002848056] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"357.090427ms","start":"2026-05-11T00:59:34.249624Z","end":"2026-05-11T00:59:34.606714Z","steps":["trace[1002848056] 'process raft request' (duration: 49.884951ms)","trace[1002848056] 'compare' (duration: 306.106965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:34.607618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:34.249579Z","time spent":"357.923172ms","remote":"127.0.0.1:46500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:00:38.704575Z","caller":"traceutil/trace.go:171","msg":"trace[1528763357] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"136.618833ms","start":"2026-05-11T01:00:38.567927Z","end":"2026-05-11T01:00:38.704546Z","steps":["trace[1528763357] 'process raft request' (duration: 123.179ms)","trace[1528763357] 'compare' (duration: 13.305261ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:01:43.743434Z","caller":"traceutil/trace.go:171","msg":"trace[1943592407] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"102.968898ms","start":"2026-05-11T01:01:43.640428Z","end":"2026-05-11T01:01:43.743397Z","steps":["trace[1943592407] 'process raft request' (duration: 102.746884ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:43.907602Z","caller":"traceutil/trace.go:171","msg":"trace[1228657449] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"159.134705ms","start":"2026-05-11T01:01:43.748417Z","end":"2026-05-11T01:01:43.907551Z","steps":["trace[1228657449] 'process raft request' (duration: 127.619552ms)","trace[1228657449] 'compare' (duration: 31.072275ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:02:23.484025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.500151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-11T01:02:23.484214Z","caller":"traceutil/trace.go:171","msg":"trace[658127610] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2438; }","duration":"143.767677ms","start":"2026-05-11T01:02:23.340414Z","end":"2026-05-11T01:02:23.484181Z","steps":["trace[658127610] 'range keys from in-memory index tree' (duration: 143.165084ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:55.999636Z","caller":"traceutil/trace.go:171","msg":"trace[1672758714] transaction","detail":"{read_only:false; response_revision:2665; number_of_response:1; }","duration":"106.743035ms","start":"2026-05-11T01:02:55.892861Z","end":"2026-05-11T01:02:55.999604Z","steps":["trace[1672758714] 'process raft request' (duration: 106.454718ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:03:01.275615Z","caller":"traceutil/trace.go:171","msg":"trace[1768411453] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"131.773797ms","start":"2026-05-11T01:03:01.143805Z","end":"2026-05-11T01:03:01.275578Z","steps":["trace[1768411453] 'process raft request' (duration: 123.031602ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:19.266946Z","caller":"traceutil/trace.go:171","msg":"trace[520212493] transaction","detail":"{read_only:false; response_revision:4441; number_of_response:1; }","duration":"154.65188ms","start":"2026-05-11T01:05:19.112247Z","end":"2026-05-11T01:05:19.266898Z","steps":["trace[520212493] 'process raft request' (duration: 60.812819ms)","trace[520212493] 'compare' (duration: 93.654307ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:19.29754Z","caller":"traceutil/trace.go:171","msg":"trace[1047547123] transaction","detail":"{read_only:false; response_revision:4442; number_of_response:1; }","duration":"124.637033ms","start":"2026-05-11T01:05:19.172882Z","end":"2026-05-11T01:05:19.297519Z","steps":["trace[1047547123] 'process raft request' (duration: 124.537061ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:29.800557Z","caller":"traceutil/trace.go:171","msg":"trace[876059378] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"120.341502ms","start":"2026-05-11T01:05:29.680188Z","end":"2026-05-11T01:05:29.800529Z","steps":["trace[876059378] 'process raft request' (duration: 120.138158ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:34.347031Z","caller":"traceutil/trace.go:171","msg":"trace[171857175] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"171.626688ms","start":"2026-05-11T01:05:34.175349Z","end":"2026-05-11T01:05:34.346976Z","steps":["trace[171857175] 'process raft request' (duration: 142.483728ms)","trace[171857175] 'compare' (duration: 28.922536ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:34.347229Z","caller":"traceutil/trace.go:171","msg":"trace[594744864] linearizableReadLoop","detail":"{readStateIndex:4963; appliedIndex:4962; }","duration":"103.145216ms","start":"2026-05-11T01:05:34.244061Z","end":"2026-05-11T01:05:34.347206Z","steps":["trace[594744864] 'read index received' (duration: 73.734061ms)","trace[594744864] 'applied index is now lower than readState.Index' (duration: 29.409655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:05:34.347476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.391361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-8bxbc\" ","response":"range_response_count:1 size:7812"} {"level":"info","ts":"2026-05-11T01:05:34.34752Z","caller":"traceutil/trace.go:171","msg":"trace[829005369] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-8bxbc; range_end:; response_count:1; response_revision:4765; }","duration":"103.450312ms","start":"2026-05-11T01:05:34.244053Z","end":"2026-05-11T01:05:34.347503Z","steps":["trace[829005369] 'agreement among raft nodes before linearized reading' (duration: 103.282458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:41.443032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.47866ms","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:5"} {"level":"info","ts":"2026-05-11T01:05:41.443243Z","caller":"traceutil/trace.go:171","msg":"trace[1670584446] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:4931; }","duration":"149.763846ms","start":"2026-05-11T01:05:41.293458Z","end":"2026-05-11T01:05:41.443222Z","steps":["trace[1670584446] 'count revisions from in-memory index tree' (duration: 149.367718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:41.443253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.434003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-046cead1-6e39-4914-8fb3-b01085ad7ac9\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-11T01:05:41.443366Z","caller":"traceutil/trace.go:171","msg":"trace[1906570962] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-046cead1-6e39-4914-8fb3-b01085ad7ac9; range_end:; response_count:1; response_revision:4931; }","duration":"101.574237ms","start":"2026-05-11T01:05:41.341765Z","end":"2026-05-11T01:05:41.443339Z","steps":["trace[1906570962] 'range keys from in-memory index tree' (duration: 101.284341ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:41.554005Z","caller":"traceutil/trace.go:171","msg":"trace[244457239] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"101.870533ms","start":"2026-05-11T01:05:41.452052Z","end":"2026-05-11T01:05:41.553922Z","steps":["trace[244457239] 'process raft request' (duration: 101.622927ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:41.708318Z","caller":"traceutil/trace.go:171","msg":"trace[2008848641] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"116.468228ms","start":"2026-05-11T01:05:41.591811Z","end":"2026-05-11T01:05:41.708279Z","steps":["trace[2008848641] 'process raft request' (duration: 116.207983ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:41.708332Z","caller":"traceutil/trace.go:171","msg":"trace[2087696061] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"116.411877ms","start":"2026-05-11T01:05:41.591894Z","end":"2026-05-11T01:05:41.708306Z","steps":["trace[2087696061] 'process raft request' (duration: 116.265185ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:41.708627Z","caller":"traceutil/trace.go:171","msg":"trace[562661032] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"116.404487ms","start":"2026-05-11T01:05:41.592204Z","end":"2026-05-11T01:05:41.708609Z","steps":["trace[562661032] 'process raft request' (duration: 116.04936ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:41.708891Z","caller":"traceutil/trace.go:171","msg":"trace[1273016095] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"115.98648ms","start":"2026-05-11T01:05:41.592874Z","end":"2026-05-11T01:05:41.708861Z","steps":["trace[1273016095] 'process raft request' (duration: 115.633183ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:06:29.870764Z","caller":"traceutil/trace.go:171","msg":"trace[1853140926] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"108.691214ms","start":"2026-05-11T01:06:29.762034Z","end":"2026-05-11T01:06:29.870725Z","steps":["trace[1853140926] 'process raft request' (duration: 108.411789ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:08:58.346002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3256} {"level":"info","ts":"2026-05-11T01:08:58.439243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3256,"took":"90.849413ms","hash":2208236911,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":33898496,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T01:08:58.439324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2208236911,"revision":3256,"compact-revision":-1} {"level":"info","ts":"2026-05-11T01:11:34.131339Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002122.wal"} {"level":"info","ts":"2026-05-11T01:11:38.416381Z","caller":"traceutil/trace.go:171","msg":"trace[1160579298] linearizableReadLoop","detail":"{readStateIndex:8511; appliedIndex:8510; }","duration":"201.806599ms","start":"2026-05-11T01:11:38.214548Z","end":"2026-05-11T01:11:38.416355Z","steps":["trace[1160579298] 'read index received' (duration: 201.618716ms)","trace[1160579298] 'applied index is now lower than readState.Index' (duration: 186.353µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:11:38.416498Z","caller":"traceutil/trace.go:171","msg":"trace[206565783] transaction","detail":"{read_only:false; response_revision:8153; number_of_response:1; }","duration":"227.250716ms","start":"2026-05-11T01:11:38.189201Z","end":"2026-05-11T01:11:38.416452Z","steps":["trace[206565783] 'process raft request' (duration: 226.965651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:38.416794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.211356ms","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-11T01:11:38.417283Z","caller":"traceutil/trace.go:171","msg":"trace[1064481565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8153; }","duration":"202.744496ms","start":"2026-05-11T01:11:38.214518Z","end":"2026-05-11T01:11:38.417263Z","steps":["trace[1064481565] 'agreement among raft nodes before linearized reading' (duration: 202.027343ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:49.47992Z","caller":"traceutil/trace.go:171","msg":"trace[261266746] transaction","detail":"{read_only:false; response_revision:8230; number_of_response:1; }","duration":"109.699635ms","start":"2026-05-11T01:11:49.370192Z","end":"2026-05-11T01:11:49.479892Z","steps":["trace[261266746] 'process raft request' (duration: 102.111162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:49.983687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.028114ms","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-11T01:11:49.983981Z","caller":"traceutil/trace.go:171","msg":"trace[1769256820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8230; }","duration":"304.377851ms","start":"2026-05-11T01:11:49.679585Z","end":"2026-05-11T01:11:49.983963Z","steps":["trace[1769256820] 'range keys from in-memory index tree' (duration: 303.753518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:49.984036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:11:49.679565Z","time spent":"304.450292ms","remote":"127.0.0.1:46594","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-11T01:11:49.983716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.374597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T01:11:49.984231Z","caller":"traceutil/trace.go:171","msg":"trace[1420588036] linearizableReadLoop","detail":"{readStateIndex:8594; appliedIndex:8593; }","duration":"128.719631ms","start":"2026-05-11T01:11:49.855503Z","end":"2026-05-11T01:11:49.984222Z","steps":["trace[1420588036] 'read index received' (duration: 37.92µs)","trace[1420588036] 'applied index is now lower than readState.Index' (duration: 128.680771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:11:49.984332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.824042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T01:11:49.984352Z","caller":"traceutil/trace.go:171","msg":"trace[558528163] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8231; }","duration":"128.885134ms","start":"2026-05-11T01:11:49.855459Z","end":"2026-05-11T01:11:49.984344Z","steps":["trace[558528163] 'agreement among raft nodes before linearized reading' (duration: 128.831713ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:49.984344Z","caller":"traceutil/trace.go:171","msg":"trace[1729060223] transaction","detail":"{read_only:false; response_revision:8231; number_of_response:1; }","duration":"248.545984ms","start":"2026-05-11T01:11:49.735765Z","end":"2026-05-11T01:11:49.984311Z","steps":["trace[1729060223] 'process raft request' (duration: 58.502765ms)","trace[1729060223] 'compare' (duration: 189.268544ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:11:50.764756Z","caller":"traceutil/trace.go:171","msg":"trace[1103637672] linearizableReadLoop","detail":"{readStateIndex:8601; appliedIndex:8600; }","duration":"190.765662ms","start":"2026-05-11T01:11:50.57395Z","end":"2026-05-11T01:11:50.764716Z","steps":["trace[1103637672] 'read index received' (duration: 188.364938ms)","trace[1103637672] 'applied index is now lower than readState.Index' (duration: 2.399254ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:11:50.764833Z","caller":"traceutil/trace.go:171","msg":"trace[954215550] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"226.982301ms","start":"2026-05-11T01:11:50.537786Z","end":"2026-05-11T01:11:50.764768Z","steps":["trace[954215550] 'process raft request' (duration: 224.449384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:50.765039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.071069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-11T01:11:50.765127Z","caller":"traceutil/trace.go:171","msg":"trace[662599519] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8238; }","duration":"191.198451ms","start":"2026-05-11T01:11:50.573904Z","end":"2026-05-11T01:11:50.765102Z","steps":["trace[662599519] 'agreement among raft nodes before linearized reading' (duration: 190.950276ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:51.477605Z","caller":"traceutil/trace.go:171","msg":"trace[656288116] transaction","detail":"{read_only:false; response_revision:8242; number_of_response:1; }","duration":"143.767133ms","start":"2026-05-11T01:11:51.33381Z","end":"2026-05-11T01:11:51.477577Z","steps":["trace[656288116] 'process raft request' (duration: 143.596798ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:18.505934Z","caller":"traceutil/trace.go:171","msg":"trace[1816776035] linearizableReadLoop","detail":"{readStateIndex:8848; appliedIndex:8847; }","duration":"348.858974ms","start":"2026-05-11T01:12:18.15704Z","end":"2026-05-11T01:12:18.505899Z","steps":["trace[1816776035] 'read index received' (duration: 348.614779ms)","trace[1816776035] 'applied index is now lower than readState.Index' (duration: 242.565µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:12:18.506Z","caller":"traceutil/trace.go:171","msg":"trace[1129591176] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"361.247416ms","start":"2026-05-11T01:12:18.144703Z","end":"2026-05-11T01:12:18.50595Z","steps":["trace[1129591176] 'process raft request' (duration: 360.936689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:18.506213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:12:18.144676Z","time spent":"361.424378ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:12:18.506325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.269592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-11T01:12:18.506386Z","caller":"traceutil/trace.go:171","msg":"trace[1137641063] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8472; }","duration":"349.370503ms","start":"2026-05-11T01:12:18.156992Z","end":"2026-05-11T01:12:18.506363Z","steps":["trace[1137641063] 'agreement among raft nodes before linearized reading' (duration: 349.116399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:18.506436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:12:18.156912Z","time spent":"349.511256ms","remote":"127.0.0.1:46472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"info","ts":"2026-05-11T01:12:31.225132Z","caller":"traceutil/trace.go:171","msg":"trace[1027857767] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"158.717652ms","start":"2026-05-11T01:12:31.066381Z","end":"2026-05-11T01:12:31.225098Z","steps":["trace[1027857767] 'process raft request' (duration: 158.490538ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:31.31887Z","caller":"traceutil/trace.go:171","msg":"trace[1091139326] linearizableReadLoop","detail":"{readStateIndex:8987; appliedIndex:8985; }","duration":"232.486744ms","start":"2026-05-11T01:12:31.086354Z","end":"2026-05-11T01:12:31.318841Z","steps":["trace[1091139326] 'read index received' (duration: 138.485003ms)","trace[1091139326] 'applied index is now lower than readState.Index' (duration: 94.000311ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:12:31.319004Z","caller":"traceutil/trace.go:171","msg":"trace[311183675] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"251.113532ms","start":"2026-05-11T01:12:31.067854Z","end":"2026-05-11T01:12:31.318967Z","steps":["trace[311183675] 'process raft request' (duration: 214.761161ms)","trace[311183675] 'compare' (duration: 36.085426ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:12:31.319173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.795469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mpdmh\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-11T01:12:31.319213Z","caller":"traceutil/trace.go:171","msg":"trace[1657964244] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mpdmh; range_end:; response_count:1; response_revision:8603; }","duration":"232.88272ms","start":"2026-05-11T01:12:31.086319Z","end":"2026-05-11T01:12:31.319202Z","steps":["trace[1657964244] 'agreement among raft nodes before linearized reading' (duration: 232.686547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:31.319348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.463633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-11T01:12:31.319425Z","caller":"traceutil/trace.go:171","msg":"trace[2002992208] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8603; }","duration":"232.580705ms","start":"2026-05-11T01:12:31.086827Z","end":"2026-05-11T01:12:31.319408Z","steps":["trace[2002992208] 'agreement among raft nodes before linearized reading' (duration: 232.29978ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:32.806837Z","caller":"traceutil/trace.go:171","msg":"trace[1224099243] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"143.02607ms","start":"2026-05-11T01:12:32.663781Z","end":"2026-05-11T01:12:32.806808Z","steps":["trace[1224099243] 'process raft request' (duration: 142.777724ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:13:41.35378Z","caller":"traceutil/trace.go:171","msg":"trace[1430337218] transaction","detail":"{read_only:false; response_revision:9344; number_of_response:1; }","duration":"118.779245ms","start":"2026-05-11T01:13:41.23497Z","end":"2026-05-11T01:13:41.353749Z","steps":["trace[1430337218] 'process raft request' (duration: 118.613972ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:13:58.364689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6627} {"level":"info","ts":"2026-05-11T01:13:58.469874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6627,"took":"102.856997ms","hash":4136350650,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":26918912,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-11T01:13:58.469979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4136350650,"revision":6627,"compact-revision":3256} {"level":"info","ts":"2026-05-11T01:14:07.717547Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:14:07.72398Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T01:14:07.724318Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T01:17:20.231323Z","caller":"traceutil/trace.go:171","msg":"trace[1228965129] transaction","detail":"{read_only:false; response_revision:11529; number_of_response:1; }","duration":"106.298419ms","start":"2026-05-11T01:17:20.124957Z","end":"2026-05-11T01:17:20.231255Z","steps":["trace[1228965129] 'process raft request' (duration: 105.965723ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:18:58.375922Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9518} {"level":"info","ts":"2026-05-11T01:18:58.460906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9518,"took":"81.643341ms","hash":4135942073,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":28454912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T01:18:58.461066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4135942073,"revision":9518,"compact-revision":6627} {"level":"info","ts":"2026-05-11T01:23:32.192998Z","caller":"traceutil/trace.go:171","msg":"trace[887347413] transaction","detail":"{read_only:false; response_revision:14546; number_of_response:1; }","duration":"123.43419ms","start":"2026-05-11T01:23:32.069521Z","end":"2026-05-11T01:23:32.192955Z","steps":["trace[887347413] 'process raft request' (duration: 123.097364ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:23:41.679552Z","caller":"traceutil/trace.go:171","msg":"trace[1497496446] linearizableReadLoop","detail":"{readStateIndex:15308; appliedIndex:15307; }","duration":"144.583235ms","start":"2026-05-11T01:23:41.534944Z","end":"2026-05-11T01:23:41.679527Z","steps":["trace[1497496446] 'read index received' (duration: 144.252039ms)","trace[1497496446] 'applied index is now lower than readState.Index' (duration: 330.056µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:23:41.679574Z","caller":"traceutil/trace.go:171","msg":"trace[1321666460] transaction","detail":"{read_only:false; response_revision:14627; number_of_response:1; }","duration":"147.352287ms","start":"2026-05-11T01:23:41.532196Z","end":"2026-05-11T01:23:41.679548Z","steps":["trace[1321666460] 'process raft request' (duration: 147.068082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:23:41.679785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.80999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-05-11T01:23:41.679862Z","caller":"traceutil/trace.go:171","msg":"trace[1048822332] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:14627; }","duration":"144.926372ms","start":"2026-05-11T01:23:41.534917Z","end":"2026-05-11T01:23:41.679843Z","steps":["trace[1048822332] 'agreement among raft nodes before linearized reading' (duration: 144.719268ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:23:47.561259Z","caller":"traceutil/trace.go:171","msg":"trace[848762292] transaction","detail":"{read_only:false; response_revision:14670; number_of_response:1; }","duration":"109.792354ms","start":"2026-05-11T01:23:47.451439Z","end":"2026-05-11T01:23:47.561231Z","steps":["trace[848762292] 'process raft request' (duration: 109.58952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:23:48.332066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.537697ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T01:23:48.332194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.413114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:23:48.33228Z","caller":"traceutil/trace.go:171","msg":"trace[452024929] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:14672; }","duration":"368.575936ms","start":"2026-05-11T01:23:47.96368Z","end":"2026-05-11T01:23:48.332256Z","steps":["trace[452024929] 'count revisions from in-memory index tree' (duration: 368.194019ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:23:48.33228Z","caller":"traceutil/trace.go:171","msg":"trace[1786324083] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14672; }","duration":"422.788761ms","start":"2026-05-11T01:23:47.909461Z","end":"2026-05-11T01:23:48.33225Z","steps":["trace[1786324083] 'range keys from in-memory index tree' (duration: 422.507315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:23:48.332346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:23:47.963651Z","time spent":"368.677838ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:23:48.332336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.334945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:23:48.332471Z","caller":"traceutil/trace.go:171","msg":"trace[1074929402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14672; }","duration":"389.531949ms","start":"2026-05-11T01:23:47.942895Z","end":"2026-05-11T01:23:48.332427Z","steps":["trace[1074929402] 'range keys from in-memory index tree' (duration: 389.181162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:23:48.332564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:23:47.942866Z","time spent":"389.677751ms","remote":"127.0.0.1:46302","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-11T01:23:58.381783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12348} {"level":"info","ts":"2026-05-11T01:23:58.475639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12348,"took":"91.161147ms","hash":885217227,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":25587712,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T01:23:58.475728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":885217227,"revision":12348,"compact-revision":9518} {"level":"warn","ts":"2026-05-11T01:25:33.143648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.135025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:22 size:187825"} {"level":"info","ts":"2026-05-11T01:25:33.143776Z","caller":"traceutil/trace.go:171","msg":"trace[1640757852] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:22; response_revision:15912; }","duration":"201.302048ms","start":"2026-05-11T01:25:32.942452Z","end":"2026-05-11T01:25:33.143754Z","steps":["trace[1640757852] 'range keys from in-memory index tree' (duration: 200.779009ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:25:33.261299Z","caller":"traceutil/trace.go:171","msg":"trace[877242208] transaction","detail":"{read_only:false; response_revision:15913; number_of_response:1; }","duration":"113.38688ms","start":"2026-05-11T01:25:33.147886Z","end":"2026-05-11T01:25:33.261273Z","steps":["trace[877242208] 'process raft request' (duration: 113.200067ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:25:33.262488Z","caller":"traceutil/trace.go:171","msg":"trace[751158709] transaction","detail":"{read_only:false; response_revision:15914; number_of_response:1; }","duration":"113.614114ms","start":"2026-05-11T01:25:33.14884Z","end":"2026-05-11T01:25:33.262454Z","steps":["trace[751158709] 'process raft request' (duration: 113.423941ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:28:58.387992Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14835} {"level":"info","ts":"2026-05-11T01:28:58.48183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14835,"took":"89.598476ms","hash":2651948787,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":31694848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T01:28:58.481909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2651948787,"revision":14835,"compact-revision":12348} {"level":"info","ts":"2026-05-11T01:30:55.951511Z","caller":"traceutil/trace.go:171","msg":"trace[1434745822] linearizableReadLoop","detail":"{readStateIndex:19704; appliedIndex:19703; }","duration":"184.090421ms","start":"2026-05-11T01:30:55.767384Z","end":"2026-05-11T01:30:55.951474Z","steps":["trace[1434745822] 'read index received' (duration: 183.741024ms)","trace[1434745822] 'applied index is now lower than readState.Index' (duration: 347.267µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:30:55.951594Z","caller":"traceutil/trace.go:171","msg":"trace[1935421281] transaction","detail":"{read_only:false; response_revision:18821; number_of_response:1; }","duration":"189.467671ms","start":"2026-05-11T01:30:55.76209Z","end":"2026-05-11T01:30:55.951557Z","steps":["trace[1935421281] 'process raft request' (duration: 189.123295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:30:55.951816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.413787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-gh8s5\" ","response":"range_response_count:1 size:20845"} {"level":"info","ts":"2026-05-11T01:30:55.95191Z","caller":"traceutil/trace.go:171","msg":"trace[2096792836] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-gh8s5; range_end:; response_count:1; response_revision:18821; }","duration":"184.57383ms","start":"2026-05-11T01:30:55.767317Z","end":"2026-05-11T01:30:55.951891Z","steps":["trace[2096792836] 'agreement among raft nodes before linearized reading' (duration: 184.331865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:30:56.185622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.059006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:93 size:918018"} {"level":"info","ts":"2026-05-11T01:30:56.185741Z","caller":"traceutil/trace.go:171","msg":"trace[1579018004] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:93; response_revision:18821; }","duration":"226.227791ms","start":"2026-05-11T01:30:55.959486Z","end":"2026-05-11T01:30:56.185713Z","steps":["trace[1579018004] 'range keys from in-memory index tree' (duration: 225.11041ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:30:56.195382Z","caller":"traceutil/trace.go:171","msg":"trace[447896699] transaction","detail":"{read_only:false; response_revision:18822; number_of_response:1; }","duration":"227.144027ms","start":"2026-05-11T01:30:55.968216Z","end":"2026-05-11T01:30:56.19536Z","steps":["trace[447896699] 'process raft request' (duration: 226.940243ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:30:56.208459Z","caller":"traceutil/trace.go:171","msg":"trace[1079591193] transaction","detail":"{read_only:false; response_revision:18823; number_of_response:1; }","duration":"138.15461ms","start":"2026-05-11T01:30:56.070283Z","end":"2026-05-11T01:30:56.208438Z","steps":["trace[1079591193] 'process raft request' (duration: 138.020807ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:31:15.158188Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:31:15.164795Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T01:31:15.164899Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-11T01:33:58.395149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17840} {"level":"info","ts":"2026-05-11T01:33:58.511463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17840,"took":"112.470358ms","hash":220360239,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":32649216,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-11T01:33:58.511606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":220360239,"revision":17840,"compact-revision":14835} {"level":"info","ts":"2026-05-11T01:38:41.42053Z","caller":"traceutil/trace.go:171","msg":"trace[97097273] transaction","detail":"{read_only:false; response_revision:23256; number_of_response:1; }","duration":"121.259609ms","start":"2026-05-11T01:38:41.299234Z","end":"2026-05-11T01:38:41.420494Z","steps":["trace[97097273] 'process raft request' (duration: 120.378512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:46.972944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.967643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:38:46.973107Z","caller":"traceutil/trace.go:171","msg":"trace[622595564] transaction","detail":"{read_only:false; response_revision:23292; number_of_response:1; }","duration":"305.890941ms","start":"2026-05-11T01:38:46.667195Z","end":"2026-05-11T01:38:46.973086Z","steps":["trace[622595564] 'process raft request' (duration: 191.656193ms)","trace[622595564] 'compare' (duration: 113.80939ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:38:46.973183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:38:46.667171Z","time spent":"305.978703ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:38:47.241402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.788235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:38:47.241639Z","caller":"traceutil/trace.go:171","msg":"trace[1678037958] linearizableReadLoop","detail":"{readStateIndex:24388; appliedIndex:24387; }","duration":"258.913871ms","start":"2026-05-11T01:38:46.982702Z","end":"2026-05-11T01:38:47.241616Z","steps":["trace[1678037958] 'read index received' (duration: 147.651178ms)","trace[1678037958] 'applied index is now lower than readState.Index' (duration: 111.260633ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:38:47.242046Z","caller":"traceutil/trace.go:171","msg":"trace[1009198067] transaction","detail":"{read_only:false; response_revision:23293; number_of_response:1; }","duration":"260.251585ms","start":"2026-05-11T01:38:46.981775Z","end":"2026-05-11T01:38:47.242026Z","steps":["trace[1009198067] 'process raft request' (duration: 148.724888ms)","trace[1009198067] 'compare' (duration: 110.200633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:38:47.242412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.23059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-05-11T01:38:47.242566Z","caller":"traceutil/trace.go:171","msg":"trace[864512091] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23293; }","duration":"237.418133ms","start":"2026-05-11T01:38:47.005096Z","end":"2026-05-11T01:38:47.242514Z","steps":["trace[864512091] 'agreement among raft nodes before linearized reading' (duration: 236.977945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:47.242668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.95292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-05-11T01:38:47.242757Z","caller":"traceutil/trace.go:171","msg":"trace[252722864] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23293; }","duration":"260.055432ms","start":"2026-05-11T01:38:46.982686Z","end":"2026-05-11T01:38:47.242741Z","steps":["trace[252722864] 'agreement among raft nodes before linearized reading' (duration: 259.737776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:47.24314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.522935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-11T01:38:47.243225Z","caller":"traceutil/trace.go:171","msg":"trace[1799751607] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:23293; }","duration":"147.658117ms","start":"2026-05-11T01:38:47.095546Z","end":"2026-05-11T01:38:47.243204Z","steps":["trace[1799751607] 'agreement among raft nodes before linearized reading' (duration: 147.438293ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:47.554813Z","caller":"traceutil/trace.go:171","msg":"trace[1527459808] transaction","detail":"{read_only:false; response_revision:23296; number_of_response:1; }","duration":"244.413832ms","start":"2026-05-11T01:38:47.310367Z","end":"2026-05-11T01:38:47.55478Z","steps":["trace[1527459808] 'process raft request' (duration: 243.997975ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:47.696312Z","caller":"traceutil/trace.go:171","msg":"trace[683609517] transaction","detail":"{read_only:false; response_revision:23298; number_of_response:1; }","duration":"137.061181ms","start":"2026-05-11T01:38:47.559228Z","end":"2026-05-11T01:38:47.696289Z","steps":["trace[683609517] 'process raft request' (duration: 132.20425ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:47.736601Z","caller":"traceutil/trace.go:171","msg":"trace[371390956] transaction","detail":"{read_only:false; response_revision:23299; number_of_response:1; }","duration":"142.8897ms","start":"2026-05-11T01:38:47.593671Z","end":"2026-05-11T01:38:47.736561Z","steps":["trace[371390956] 'process raft request' (duration: 142.700646ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:47.898711Z","caller":"traceutil/trace.go:171","msg":"trace[666562324] transaction","detail":"{read_only:false; response_revision:23301; number_of_response:1; }","duration":"130.632782ms","start":"2026-05-11T01:38:47.768052Z","end":"2026-05-11T01:38:47.898685Z","steps":["trace[666562324] 'process raft request' (duration: 130.5579ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:47.898767Z","caller":"traceutil/trace.go:171","msg":"trace[498624230] transaction","detail":"{read_only:false; response_revision:23300; number_of_response:1; }","duration":"131.198543ms","start":"2026-05-11T01:38:47.76754Z","end":"2026-05-11T01:38:47.898738Z","steps":["trace[498624230] 'process raft request' (duration: 82.098051ms)","trace[498624230] 'compare' (duration: 48.833636ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:38:47.899065Z","caller":"traceutil/trace.go:171","msg":"trace[1428818152] transaction","detail":"{read_only:false; response_revision:23302; number_of_response:1; }","duration":"130.783715ms","start":"2026-05-11T01:38:47.768265Z","end":"2026-05-11T01:38:47.899048Z","steps":["trace[1428818152] 'process raft request' (duration: 130.385237ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:47.899241Z","caller":"traceutil/trace.go:171","msg":"trace[223495510] transaction","detail":"{read_only:false; response_revision:23303; number_of_response:1; }","duration":"129.167695ms","start":"2026-05-11T01:38:47.770032Z","end":"2026-05-11T01:38:47.899199Z","steps":["trace[223495510] 'process raft request' (duration: 128.980711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:48.18833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.491995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:38:48.188445Z","caller":"traceutil/trace.go:171","msg":"trace[1980854600] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:23303; }","duration":"153.690229ms","start":"2026-05-11T01:38:48.03473Z","end":"2026-05-11T01:38:48.18842Z","steps":["trace[1980854600] 'count revisions from in-memory index tree' (duration: 153.347223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:48.188356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.582648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-11T01:38:48.188763Z","caller":"traceutil/trace.go:171","msg":"trace[659069660] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:23303; }","duration":"118.989716ms","start":"2026-05-11T01:38:48.069733Z","end":"2026-05-11T01:38:48.188722Z","steps":["trace[659069660] 'range keys from in-memory index tree' (duration: 118.433326ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:48.191829Z","caller":"traceutil/trace.go:171","msg":"trace[1406263024] transaction","detail":"{read_only:false; response_revision:23304; number_of_response:1; }","duration":"100.972612ms","start":"2026-05-11T01:38:48.090841Z","end":"2026-05-11T01:38:48.191814Z","steps":["trace[1406263024] 'process raft request' (duration: 100.8501ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:58.40155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20896} {"level":"info","ts":"2026-05-11T01:38:58.526034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20896,"took":"119.915503ms","hash":3962910562,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":32231424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T01:38:58.526119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3962910562,"revision":20896,"compact-revision":17840} {"level":"info","ts":"2026-05-11T01:42:15.02069Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006996.wal"} {"level":"warn","ts":"2026-05-11T01:42:20.229888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.767974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:42:20.229997Z","caller":"traceutil/trace.go:171","msg":"trace[572065479] linearizableReadLoop","detail":"{readStateIndex:27132; appliedIndex:27131; }","duration":"287.135045ms","start":"2026-05-11T01:42:19.942847Z","end":"2026-05-11T01:42:20.229982Z","steps":["trace[572065479] 'read index received' (duration: 119.086385ms)","trace[572065479] 'applied index is now lower than readState.Index' (duration: 168.04725ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:42:20.230075Z","caller":"traceutil/trace.go:171","msg":"trace[1668805003] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"325.352443ms","start":"2026-05-11T01:42:19.904712Z","end":"2026-05-11T01:42:20.230064Z","steps":["trace[1668805003] 'process raft request' (duration: 157.316564ms)","trace[1668805003] 'compare' (duration: 167.650423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:42:20.230117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:42:19.90465Z","time spent":"325.446885ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:42:20.23039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.517632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:42:20.230419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.663995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-05-11T01:42:20.23051Z","caller":"traceutil/trace.go:171","msg":"trace[1375455869] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25924; }","duration":"120.816998ms","start":"2026-05-11T01:42:20.109668Z","end":"2026-05-11T01:42:20.230485Z","steps":["trace[1375455869] 'agreement among raft nodes before linearized reading' (duration: 120.585814ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:42:20.230635Z","caller":"traceutil/trace.go:171","msg":"trace[2112466815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25924; }","duration":"287.708125ms","start":"2026-05-11T01:42:19.942761Z","end":"2026-05-11T01:42:20.230469Z","steps":["trace[2112466815] 'agreement among raft nodes before linearized reading' (duration: 287.485572ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:43:58.409229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23373} {"level":"info","ts":"2026-05-11T01:43:58.519136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23373,"took":"104.691283ms","hash":2378516490,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":43597824,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-05-11T01:43:58.519211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2378516490,"revision":23373,"compact-revision":20896} {"level":"info","ts":"2026-05-11T01:45:50.117172Z","caller":"traceutil/trace.go:171","msg":"trace[288750641] transaction","detail":"{read_only:false; response_revision:28386; number_of_response:1; }","duration":"221.489949ms","start":"2026-05-11T01:45:49.895642Z","end":"2026-05-11T01:45:50.117132Z","steps":["trace[288750641] 'process raft request' (duration: 220.692353ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:50.424826Z","caller":"traceutil/trace.go:171","msg":"trace[335622011] transaction","detail":"{read_only:false; response_revision:28389; number_of_response:1; }","duration":"103.017199ms","start":"2026-05-11T01:45:50.321776Z","end":"2026-05-11T01:45:50.424793Z","steps":["trace[335622011] 'process raft request' (duration: 102.743264ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:50.501082Z","caller":"traceutil/trace.go:171","msg":"trace[425958071] transaction","detail":"{read_only:false; response_revision:28390; number_of_response:1; }","duration":"178.081534ms","start":"2026-05-11T01:45:50.322934Z","end":"2026-05-11T01:45:50.501016Z","steps":["trace[425958071] 'process raft request' (duration: 177.787429ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:50.501154Z","caller":"traceutil/trace.go:171","msg":"trace[1271103093] transaction","detail":"{read_only:false; response_revision:28393; number_of_response:1; }","duration":"123.769848ms","start":"2026-05-11T01:45:50.377372Z","end":"2026-05-11T01:45:50.501142Z","steps":["trace[1271103093] 'process raft request' (duration: 123.721347ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:50.501015Z","caller":"traceutil/trace.go:171","msg":"trace[1816515965] transaction","detail":"{read_only:false; response_revision:28391; number_of_response:1; }","duration":"176.841792ms","start":"2026-05-11T01:45:50.32412Z","end":"2026-05-11T01:45:50.500962Z","steps":["trace[1816515965] 'process raft request' (duration: 176.71966ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:50.501258Z","caller":"traceutil/trace.go:171","msg":"trace[1403708372] transaction","detail":"{read_only:false; response_revision:28392; number_of_response:1; }","duration":"174.889655ms","start":"2026-05-11T01:45:50.326345Z","end":"2026-05-11T01:45:50.501234Z","steps":["trace[1403708372] 'process raft request' (duration: 174.559689ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:54.587042Z","caller":"traceutil/trace.go:171","msg":"trace[1285655057] linearizableReadLoop","detail":"{readStateIndex:29730; appliedIndex:29729; }","duration":"129.301481ms","start":"2026-05-11T01:45:54.457702Z","end":"2026-05-11T01:45:54.587004Z","steps":["trace[1285655057] 'read index received' (duration: 123.303289ms)","trace[1285655057] 'applied index is now lower than readState.Index' (duration: 5.995782ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:54.587145Z","caller":"traceutil/trace.go:171","msg":"trace[988314017] transaction","detail":"{read_only:false; response_revision:28424; number_of_response:1; }","duration":"137.660157ms","start":"2026-05-11T01:45:54.449432Z","end":"2026-05-11T01:45:54.587092Z","steps":["trace[988314017] 'process raft request' (duration: 131.438701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:54.587345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.630127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-05-11T01:45:54.587436Z","caller":"traceutil/trace.go:171","msg":"trace[1569244581] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:28424; }","duration":"129.749759ms","start":"2026-05-11T01:45:54.457662Z","end":"2026-05-11T01:45:54.587411Z","steps":["trace[1569244581] 'agreement among raft nodes before linearized reading' (duration: 129.486564ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:54.786755Z","caller":"traceutil/trace.go:171","msg":"trace[1970737433] linearizableReadLoop","detail":"{readStateIndex:29731; appliedIndex:29730; }","duration":"199.589798ms","start":"2026-05-11T01:45:54.587136Z","end":"2026-05-11T01:45:54.786725Z","steps":["trace[1970737433] 'read index received' (duration: 181.301675ms)","trace[1970737433] 'applied index is now lower than readState.Index' (duration: 18.286903ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:54.786848Z","caller":"traceutil/trace.go:171","msg":"trace[1170950105] transaction","detail":"{read_only:false; response_revision:28425; number_of_response:1; }","duration":"273.461272ms","start":"2026-05-11T01:45:54.513317Z","end":"2026-05-11T01:45:54.786778Z","steps":["trace[1170950105] 'process raft request' (duration: 255.2004ms)","trace[1170950105] 'compare' (duration: 17.862184ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:54.786956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.807543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-05-11T01:45:54.78701Z","caller":"traceutil/trace.go:171","msg":"trace[374972737] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28425; }","duration":"269.880714ms","start":"2026-05-11T01:45:54.51712Z","end":"2026-05-11T01:45:54.787Z","steps":["trace[374972737] 'agreement among raft nodes before linearized reading' (duration: 269.698651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:54.78711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.425835ms","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-11T01:45:54.787369Z","caller":"traceutil/trace.go:171","msg":"trace[188736202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28425; }","duration":"269.618719ms","start":"2026-05-11T01:45:54.517584Z","end":"2026-05-11T01:45:54.787203Z","steps":["trace[188736202] 'agreement among raft nodes before linearized reading' (duration: 269.1571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:54.787604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.114188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T01:45:54.787744Z","caller":"traceutil/trace.go:171","msg":"trace[89613641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28425; }","duration":"270.283881ms","start":"2026-05-11T01:45:54.517424Z","end":"2026-05-11T01:45:54.787708Z","steps":["trace[89613641] 'agreement among raft nodes before linearized reading' (duration: 269.334954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:55.064904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.897468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:55.065128Z","caller":"traceutil/trace.go:171","msg":"trace[1448395022] transaction","detail":"{read_only:false; response_revision:28426; number_of_response:1; }","duration":"273.128284ms","start":"2026-05-11T01:45:54.791969Z","end":"2026-05-11T01:45:55.065098Z","steps":["trace[1448395022] 'process raft request' (duration: 131.91449ms)","trace[1448395022] 'compare' (duration: 140.670724ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:55.06528Z","caller":"traceutil/trace.go:171","msg":"trace[239290495] linearizableReadLoop","detail":"{readStateIndex:29734; appliedIndex:29731; }","duration":"272.797989ms","start":"2026-05-11T01:45:54.792467Z","end":"2026-05-11T01:45:55.065265Z","steps":["trace[239290495] 'read index received' (duration: 131.433962ms)","trace[239290495] 'applied index is now lower than readState.Index' (duration: 141.363157ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:55.06547Z","caller":"traceutil/trace.go:171","msg":"trace[1503236682] transaction","detail":"{read_only:false; response_revision:28429; number_of_response:1; }","duration":"269.564178ms","start":"2026-05-11T01:45:54.795872Z","end":"2026-05-11T01:45:55.065437Z","steps":["trace[1503236682] 'process raft request' (duration: 269.309523ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:55.065494Z","caller":"traceutil/trace.go:171","msg":"trace[900166577] transaction","detail":"{read_only:false; response_revision:28427; number_of_response:1; }","duration":"273.42544ms","start":"2026-05-11T01:45:54.792031Z","end":"2026-05-11T01:45:55.065456Z","steps":["trace[900166577] 'process raft request' (duration: 273.020272ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:55.065567Z","caller":"traceutil/trace.go:171","msg":"trace[1903169310] transaction","detail":"{read_only:false; response_revision:28430; number_of_response:1; }","duration":"266.45417ms","start":"2026-05-11T01:45:54.799067Z","end":"2026-05-11T01:45:55.065521Z","steps":["trace[1903169310] 'process raft request' (duration: 266.158185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:55.065621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.094839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:55.065591Z","caller":"traceutil/trace.go:171","msg":"trace[1405550575] transaction","detail":"{read_only:false; response_revision:28428; number_of_response:1; }","duration":"273.155845ms","start":"2026-05-11T01:45:54.792286Z","end":"2026-05-11T01:45:55.065442Z","steps":["trace[1405550575] 'process raft request' (duration: 272.85614ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:55.065694Z","caller":"traceutil/trace.go:171","msg":"trace[324774206] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:28430; }","duration":"167.253282ms","start":"2026-05-11T01:45:54.898425Z","end":"2026-05-11T01:45:55.065678Z","steps":["trace[324774206] 'agreement among raft nodes before linearized reading' (duration: 167.084469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:55.065762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.266627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-11T01:45:55.065841Z","caller":"traceutil/trace.go:171","msg":"trace[327205243] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28430; }","duration":"273.388819ms","start":"2026-05-11T01:45:54.792431Z","end":"2026-05-11T01:45:55.06582Z","steps":["trace[327205243] 'agreement among raft nodes before linearized reading' (duration: 273.108524ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:55.25965Z","caller":"traceutil/trace.go:171","msg":"trace[1287376958] transaction","detail":"{read_only:false; response_revision:28431; number_of_response:1; }","duration":"189.251524ms","start":"2026-05-11T01:45:55.070361Z","end":"2026-05-11T01:45:55.259612Z","steps":["trace[1287376958] 'process raft request' (duration: 137.641959ms)","trace[1287376958] 'compare' (duration: 51.453792ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:55.271421Z","caller":"traceutil/trace.go:171","msg":"trace[315882676] transaction","detail":"{read_only:false; response_revision:28432; number_of_response:1; }","duration":"191.785442ms","start":"2026-05-11T01:45:55.079615Z","end":"2026-05-11T01:45:55.2714Z","steps":["trace[315882676] 'process raft request' (duration: 191.440305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:55.561892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.28337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:55.562008Z","caller":"traceutil/trace.go:171","msg":"trace[2121723854] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:28433; }","duration":"192.463393ms","start":"2026-05-11T01:45:55.369521Z","end":"2026-05-11T01:45:55.561984Z","steps":["trace[2121723854] 'count revisions from in-memory index tree' (duration: 192.190708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:56.497682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.498419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:56.497891Z","caller":"traceutil/trace.go:171","msg":"trace[1148922978] transaction","detail":"{read_only:false; response_revision:28438; number_of_response:1; }","duration":"287.222629ms","start":"2026-05-11T01:45:56.210648Z","end":"2026-05-11T01:45:56.497871Z","steps":["trace[1148922978] 'process raft request' (duration: 287.158398ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:56.498525Z","caller":"traceutil/trace.go:171","msg":"trace[309148513] transaction","detail":"{read_only:false; response_revision:28437; number_of_response:1; }","duration":"288.33306ms","start":"2026-05-11T01:45:56.210132Z","end":"2026-05-11T01:45:56.498465Z","steps":["trace[309148513] 'process raft request' (duration: 144.921174ms)","trace[309148513] 'compare' (duration: 142.372346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:56.78712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.791584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:56.787236Z","caller":"traceutil/trace.go:171","msg":"trace[1849908879] linearizableReadLoop","detail":"{readStateIndex:29745; appliedIndex:29744; }","duration":"195.168404ms","start":"2026-05-11T01:45:56.592048Z","end":"2026-05-11T01:45:56.787216Z","steps":["trace[1849908879] 'read index received' (duration: 37.062494ms)","trace[1849908879] 'applied index is now lower than readState.Index' (duration: 158.10488ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:56.787291Z","caller":"traceutil/trace.go:171","msg":"trace[361059052] transaction","detail":"{read_only:false; response_revision:28439; number_of_response:1; }","duration":"414.809347ms","start":"2026-05-11T01:45:56.372437Z","end":"2026-05-11T01:45:56.787247Z","steps":["trace[361059052] 'process raft request' (duration: 256.794938ms)","trace[361059052] 'compare' (duration: 157.54276ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:56.787415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.378568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T01:45:56.787447Z","caller":"traceutil/trace.go:171","msg":"trace[2121478537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28439; }","duration":"195.45941ms","start":"2026-05-11T01:45:56.59198Z","end":"2026-05-11T01:45:56.787439Z","steps":["trace[2121478537] 'agreement among raft nodes before linearized reading' (duration: 195.299796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:56.787506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:56.372406Z","time spent":"414.978271ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:57.037617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.680378ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-11T01:45:57.037752Z","caller":"traceutil/trace.go:171","msg":"trace[1312751135] linearizableReadLoop","detail":"{readStateIndex:29746; appliedIndex:29745; }","duration":"247.124717ms","start":"2026-05-11T01:45:56.790605Z","end":"2026-05-11T01:45:57.037729Z","steps":["trace[1312751135] 'read index received' (duration: 93.374039ms)","trace[1312751135] 'applied index is now lower than readState.Index' (duration: 153.748808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:57.037916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.29992ms","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-11T01:45:57.037963Z","caller":"traceutil/trace.go:171","msg":"trace[409805743] 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:28439; }","duration":"247.410213ms","start":"2026-05-11T01:45:56.790536Z","end":"2026-05-11T01:45:57.037947Z","steps":["trace[409805743] 'agreement among raft nodes before linearized reading' (duration: 247.30239ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:57.045353Z","caller":"traceutil/trace.go:171","msg":"trace[1052773262] transaction","detail":"{read_only:false; response_revision:28441; number_of_response:1; }","duration":"251.770655ms","start":"2026-05-11T01:45:56.793538Z","end":"2026-05-11T01:45:57.045308Z","steps":["trace[1052773262] 'process raft request' (duration: 251.640832ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:57.045502Z","caller":"traceutil/trace.go:171","msg":"trace[1673164439] transaction","detail":"{read_only:false; response_revision:28440; number_of_response:1; }","duration":"253.13439ms","start":"2026-05-11T01:45:56.792336Z","end":"2026-05-11T01:45:57.045471Z","steps":["trace[1673164439] 'process raft request' (duration: 252.645692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:57.045456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.897452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-11T01:45:57.045726Z","caller":"traceutil/trace.go:171","msg":"trace[631425539] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:28441; }","duration":"209.194027ms","start":"2026-05-11T01:45:56.836483Z","end":"2026-05-11T01:45:57.045677Z","steps":["trace[631425539] 'agreement among raft nodes before linearized reading' (duration: 208.757009ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:57.260462Z","caller":"traceutil/trace.go:171","msg":"trace[499663557] linearizableReadLoop","detail":"{readStateIndex:29753; appliedIndex:29751; }","duration":"168.282291ms","start":"2026-05-11T01:45:57.092158Z","end":"2026-05-11T01:45:57.260441Z","steps":["trace[499663557] 'read index received' (duration: 6.36586ms)","trace[499663557] 'applied index is now lower than readState.Index' (duration: 161.915161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:57.260793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.620038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:57.260858Z","caller":"traceutil/trace.go:171","msg":"trace[681182691] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:28446; }","duration":"168.71569ms","start":"2026-05-11T01:45:57.092124Z","end":"2026-05-11T01:45:57.26084Z","steps":["trace[681182691] 'agreement among raft nodes before linearized reading' (duration: 168.583727ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:57.260903Z","caller":"traceutil/trace.go:171","msg":"trace[1351780996] transaction","detail":"{read_only:false; response_revision:28446; number_of_response:1; }","duration":"169.633216ms","start":"2026-05-11T01:45:57.091235Z","end":"2026-05-11T01:45:57.260868Z","steps":["trace[1351780996] 'process raft request' (duration: 169.155596ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:57.260909Z","caller":"traceutil/trace.go:171","msg":"trace[1861948186] transaction","detail":"{read_only:false; response_revision:28445; number_of_response:1; }","duration":"169.639797ms","start":"2026-05-11T01:45:57.091198Z","end":"2026-05-11T01:45:57.260838Z","steps":["trace[1861948186] 'process raft request' (duration: 128.975745ms)","trace[1861948186] 'compare' (duration: 40.06331ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:57.551372Z","caller":"traceutil/trace.go:171","msg":"trace[675944515] linearizableReadLoop","detail":"{readStateIndex:29754; appliedIndex:29753; }","duration":"265.681616ms","start":"2026-05-11T01:45:57.285661Z","end":"2026-05-11T01:45:57.551342Z","steps":["trace[675944515] 'read index received' (duration: 265.39789ms)","trace[675944515] 'applied index is now lower than readState.Index' (duration: 282.246µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:57.551505Z","caller":"traceutil/trace.go:171","msg":"trace[523133046] transaction","detail":"{read_only:false; response_revision:28447; number_of_response:1; }","duration":"267.605001ms","start":"2026-05-11T01:45:57.283865Z","end":"2026-05-11T01:45:57.55147Z","steps":["trace[523133046] 'process raft request' (duration: 267.185963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:57.551602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.899729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:57.551654Z","caller":"traceutil/trace.go:171","msg":"trace[1616733525] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:28447; }","duration":"265.997641ms","start":"2026-05-11T01:45:57.285642Z","end":"2026-05-11T01:45:57.551639Z","steps":["trace[1616733525] 'agreement among raft nodes before linearized reading' (duration: 265.868039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:57.551747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.62413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-05-11T01:45:57.551832Z","caller":"traceutil/trace.go:171","msg":"trace[1986107891] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28447; }","duration":"260.776864ms","start":"2026-05-11T01:45:57.291036Z","end":"2026-05-11T01:45:57.551813Z","steps":["trace[1986107891] 'agreement among raft nodes before linearized reading' (duration: 260.523878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:57.940849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.378021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:57.941129Z","caller":"traceutil/trace.go:171","msg":"trace[905942753] transaction","detail":"{read_only:false; response_revision:28448; number_of_response:1; }","duration":"380.414113ms","start":"2026-05-11T01:45:57.560672Z","end":"2026-05-11T01:45:57.941086Z","steps":["trace[905942753] 'process raft request' (duration: 108.646504ms)","trace[905942753] 'compare' (duration: 271.097246ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:57.941207Z","caller":"traceutil/trace.go:171","msg":"trace[1498454494] linearizableReadLoop","detail":"{readStateIndex:29757; appliedIndex:29754; }","duration":"252.57161ms","start":"2026-05-11T01:45:57.688614Z","end":"2026-05-11T01:45:57.941186Z","steps":["trace[1498454494] 'read index received' (duration: 118.446138ms)","trace[1498454494] 'applied index is now lower than readState.Index' (duration: 134.124292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:57.941324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:57.560647Z","time spent":"380.562636ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:57.941349Z","caller":"traceutil/trace.go:171","msg":"trace[1252374074] transaction","detail":"{read_only:false; response_revision:28449; number_of_response:1; }","duration":"380.429544ms","start":"2026-05-11T01:45:57.560907Z","end":"2026-05-11T01:45:57.941337Z","steps":["trace[1252374074] 'process raft request' (duration: 380.103398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:57.941424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:57.560898Z","time spent":"380.484895ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:57.941688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.088189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-11T01:45:57.941727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.210506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:57.941755Z","caller":"traceutil/trace.go:171","msg":"trace[1216786253] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:28450; }","duration":"253.246612ms","start":"2026-05-11T01:45:57.688476Z","end":"2026-05-11T01:45:57.941723Z","steps":["trace[1216786253] 'agreement among raft nodes before linearized reading' (duration: 253.079799ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:57.941761Z","caller":"traceutil/trace.go:171","msg":"trace[1085376314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28450; }","duration":"156.294806ms","start":"2026-05-11T01:45:57.785454Z","end":"2026-05-11T01:45:57.941749Z","steps":["trace[1085376314] 'agreement among raft nodes before linearized reading' (duration: 156.231215ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:57.942049Z","caller":"traceutil/trace.go:171","msg":"trace[1098358977] transaction","detail":"{read_only:false; response_revision:28450; number_of_response:1; }","duration":"362.906246ms","start":"2026-05-11T01:45:57.579089Z","end":"2026-05-11T01:45:57.941995Z","steps":["trace[1098358977] 'process raft request' (duration: 362.03824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:57.94298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:57.579065Z","time spent":"363.104969ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:58.159148Z","caller":"traceutil/trace.go:171","msg":"trace[471896912] transaction","detail":"{read_only:false; response_revision:28451; number_of_response:1; }","duration":"212.205323ms","start":"2026-05-11T01:45:57.946908Z","end":"2026-05-11T01:45:58.159113Z","steps":["trace[471896912] 'process raft request' (duration: 189.437207ms)","trace[471896912] 'compare' (duration: 22.336369ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:58.159724Z","caller":"traceutil/trace.go:171","msg":"trace[2066520098] transaction","detail":"{read_only:false; response_revision:28452; number_of_response:1; }","duration":"208.045816ms","start":"2026-05-11T01:45:57.951422Z","end":"2026-05-11T01:45:58.159468Z","steps":["trace[2066520098] 'process raft request' (duration: 207.617067ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:58.159873Z","caller":"traceutil/trace.go:171","msg":"trace[6199921] transaction","detail":"{read_only:false; response_revision:28453; number_of_response:1; }","duration":"133.383267ms","start":"2026-05-11T01:45:58.026454Z","end":"2026-05-11T01:45:58.159837Z","steps":["trace[6199921] 'process raft request' (duration: 132.896928ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:58.325653Z","caller":"traceutil/trace.go:171","msg":"trace[432998698] transaction","detail":"{read_only:false; response_revision:28454; number_of_response:1; }","duration":"114.394383ms","start":"2026-05-11T01:45:58.2112Z","end":"2026-05-11T01:45:58.325594Z","steps":["trace[432998698] 'process raft request' (duration: 111.289115ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:58.829723Z","caller":"traceutil/trace.go:171","msg":"trace[934513415] transaction","detail":"{read_only:false; response_revision:28457; number_of_response:1; }","duration":"243.685793ms","start":"2026-05-11T01:45:58.585999Z","end":"2026-05-11T01:45:58.829684Z","steps":["trace[934513415] 'process raft request' (duration: 243.462579ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:59.170939Z","caller":"traceutil/trace.go:171","msg":"trace[1972750271] transaction","detail":"{read_only:false; response_revision:28458; number_of_response:1; }","duration":"116.717215ms","start":"2026-05-11T01:45:59.054189Z","end":"2026-05-11T01:45:59.170906Z","steps":["trace[1972750271] 'process raft request' (duration: 116.524311ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:59.181374Z","caller":"traceutil/trace.go:171","msg":"trace[2065763791] transaction","detail":"{read_only:false; response_revision:28459; number_of_response:1; }","duration":"125.902418ms","start":"2026-05-11T01:45:59.055434Z","end":"2026-05-11T01:45:59.181337Z","steps":["trace[2065763791] 'process raft request' (duration: 125.584662ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:59.380438Z","caller":"traceutil/trace.go:171","msg":"trace[715919930] linearizableReadLoop","detail":"{readStateIndex:29769; appliedIndex:29768; }","duration":"114.565075ms","start":"2026-05-11T01:45:59.265851Z","end":"2026-05-11T01:45:59.380417Z","steps":["trace[715919930] 'read index received' (duration: 114.34126ms)","trace[715919930] 'applied index is now lower than readState.Index' (duration: 223.325µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:59.380535Z","caller":"traceutil/trace.go:171","msg":"trace[2115046859] transaction","detail":"{read_only:false; response_revision:28462; number_of_response:1; }","duration":"193.210117ms","start":"2026-05-11T01:45:59.187317Z","end":"2026-05-11T01:45:59.380527Z","steps":["trace[2115046859] 'process raft request' (duration: 192.943912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:59.380798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.941173ms","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-11T01:45:59.380878Z","caller":"traceutil/trace.go:171","msg":"trace[385850950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28462; }","duration":"115.085655ms","start":"2026-05-11T01:45:59.265776Z","end":"2026-05-11T01:45:59.380862Z","steps":["trace[385850950] 'agreement among raft nodes before linearized reading' (duration: 114.868282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:59.380956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.358122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-05-11T01:45:59.381066Z","caller":"traceutil/trace.go:171","msg":"trace[1063740567] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28462; }","duration":"114.519405ms","start":"2026-05-11T01:45:59.26652Z","end":"2026-05-11T01:45:59.381039Z","steps":["trace[1063740567] 'agreement among raft nodes before linearized reading' (duration: 114.151118ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:59.488689Z","caller":"traceutil/trace.go:171","msg":"trace[1870289217] transaction","detail":"{read_only:false; response_revision:28464; number_of_response:1; }","duration":"102.789225ms","start":"2026-05-11T01:45:59.385871Z","end":"2026-05-11T01:45:59.48866Z","steps":["trace[1870289217] 'process raft request' (duration: 102.626982ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:59.488696Z","caller":"traceutil/trace.go:171","msg":"trace[1579026479] transaction","detail":"{read_only:false; response_revision:28463; number_of_response:1; }","duration":"103.237093ms","start":"2026-05-11T01:45:59.385428Z","end":"2026-05-11T01:45:59.488665Z","steps":["trace[1579026479] 'process raft request' (duration: 101.748145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:59.716524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.298612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-05-11T01:45:59.716627Z","caller":"traceutil/trace.go:171","msg":"trace[1116944230] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28464; }","duration":"120.483926ms","start":"2026-05-11T01:45:59.596119Z","end":"2026-05-11T01:45:59.716603Z","steps":["trace[1116944230] 'range keys from in-memory index tree' (duration: 120.048988ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:10.244962Z","caller":"traceutil/trace.go:171","msg":"trace[1306526257] linearizableReadLoop","detail":"{readStateIndex:29863; appliedIndex:29862; }","duration":"137.949813ms","start":"2026-05-11T01:46:10.106961Z","end":"2026-05-11T01:46:10.244911Z","steps":["trace[1306526257] 'read index received' (duration: 137.890322ms)","trace[1306526257] 'applied index is now lower than readState.Index' (duration: 58.031µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:10.245097Z","caller":"traceutil/trace.go:171","msg":"trace[476266143] transaction","detail":"{read_only:false; response_revision:28552; number_of_response:1; }","duration":"162.151066ms","start":"2026-05-11T01:46:10.082933Z","end":"2026-05-11T01:46:10.245085Z","steps":["trace[476266143] 'process raft request' (duration: 161.77631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:10.24531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.31281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-11T01:46:10.245418Z","caller":"traceutil/trace.go:171","msg":"trace[195690724] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:28552; }","duration":"138.434373ms","start":"2026-05-11T01:46:10.106947Z","end":"2026-05-11T01:46:10.245381Z","steps":["trace[195690724] 'agreement among raft nodes before linearized reading' (duration: 138.141986ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:10.319889Z","caller":"traceutil/trace.go:171","msg":"trace[1683461795] transaction","detail":"{read_only:false; response_revision:28553; number_of_response:1; }","duration":"205.760773ms","start":"2026-05-11T01:46:10.114099Z","end":"2026-05-11T01:46:10.319859Z","steps":["trace[1683461795] 'process raft request' (duration: 205.494138ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:10.320249Z","caller":"traceutil/trace.go:171","msg":"trace[748385098] transaction","detail":"{read_only:false; response_revision:28554; number_of_response:1; }","duration":"122.901721ms","start":"2026-05-11T01:46:10.197315Z","end":"2026-05-11T01:46:10.320217Z","steps":["trace[748385098] 'process raft request' (duration: 122.463373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:10.320295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.037017ms","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-11T01:46:10.320382Z","caller":"traceutil/trace.go:171","msg":"trace[1723010466] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:28554; }","duration":"191.158549ms","start":"2026-05-11T01:46:10.129192Z","end":"2026-05-11T01:46:10.32035Z","steps":["trace[1723010466] 'agreement among raft nodes before linearized reading' (duration: 190.910984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:10.320565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.591631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:10.320625Z","caller":"traceutil/trace.go:171","msg":"trace[1892388150] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:28554; }","duration":"102.689062ms","start":"2026-05-11T01:46:10.217921Z","end":"2026-05-11T01:46:10.32061Z","steps":["trace[1892388150] 'agreement among raft nodes before linearized reading' (duration: 102.58004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.002012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.796184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-11T01:46:11.00211Z","caller":"traceutil/trace.go:171","msg":"trace[720635574] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:28611; }","duration":"174.947236ms","start":"2026-05-11T01:46:10.827141Z","end":"2026-05-11T01:46:11.002089Z","steps":["trace[720635574] 'range keys from in-memory index tree' (duration: 174.533209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.002212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.170698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-05-11T01:46:11.002319Z","caller":"traceutil/trace.go:171","msg":"trace[1736520528] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:28611; }","duration":"177.342101ms","start":"2026-05-11T01:46:10.824951Z","end":"2026-05-11T01:46:11.002293Z","steps":["trace[1736520528] 'range keys from in-memory index tree' (duration: 176.940663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.002762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.909759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:46:11.002855Z","caller":"traceutil/trace.go:171","msg":"trace[499992885] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:28611; }","duration":"125.242455ms","start":"2026-05-11T01:46:10.877596Z","end":"2026-05-11T01:46:11.002838Z","steps":["trace[499992885] 'count revisions from in-memory index tree' (duration: 124.782526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.619094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954623263760740,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T01:46:11.642701Z","caller":"traceutil/trace.go:171","msg":"trace[2008597992] transaction","detail":"{read_only:false; response_revision:28612; number_of_response:1; }","duration":"562.638794ms","start":"2026-05-11T01:46:11.080031Z","end":"2026-05-11T01:46:11.642669Z","steps":["trace[2008597992] 'process raft request' (duration: 562.41147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.642896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.080016Z","time spent":"562.773148ms","remote":"127.0.0.1:38732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:11.682367Z","caller":"traceutil/trace.go:171","msg":"trace[1879519449] linearizableReadLoop","detail":"{readStateIndex:29927; appliedIndex:29924; }","duration":"563.38786ms","start":"2026-05-11T01:46:11.118949Z","end":"2026-05-11T01:46:11.682337Z","steps":["trace[1879519449] 'read index received' (duration: 523.440632ms)","trace[1879519449] 'applied index is now lower than readState.Index' (duration: 39.945798ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:11.682428Z","caller":"traceutil/trace.go:171","msg":"trace[531688462] transaction","detail":"{read_only:false; response_revision:28614; number_of_response:1; }","duration":"458.807532ms","start":"2026-05-11T01:46:11.22359Z","end":"2026-05-11T01:46:11.682398Z","steps":["trace[531688462] 'process raft request' (duration: 458.683239ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:11.682398Z","caller":"traceutil/trace.go:171","msg":"trace[533961406] transaction","detail":"{read_only:false; response_revision:28613; number_of_response:1; }","duration":"473.286662ms","start":"2026-05-11T01:46:11.209073Z","end":"2026-05-11T01:46:11.68236Z","steps":["trace[533961406] 'process raft request' (duration: 473.019117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.682581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.223546Z","time spent":"458.960895ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:11.682676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.209046Z","time spent":"473.501426ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:11.682802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.822278ms","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-11T01:46:11.682919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.373343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T01:46:11.682923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.293816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-05-11T01:46:11.682966Z","caller":"traceutil/trace.go:171","msg":"trace[244616293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28614; }","duration":"246.457075ms","start":"2026-05-11T01:46:11.436497Z","end":"2026-05-11T01:46:11.682954Z","steps":["trace[244616293] 'agreement among raft nodes before linearized reading' (duration: 246.364043ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:11.683Z","caller":"traceutil/trace.go:171","msg":"trace[676361830] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28614; }","duration":"110.366617ms","start":"2026-05-11T01:46:11.572601Z","end":"2026-05-11T01:46:11.682968Z","steps":["trace[676361830] 'agreement among raft nodes before linearized reading' (duration: 110.130623ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:11.682911Z","caller":"traceutil/trace.go:171","msg":"trace[645241773] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:28614; }","duration":"563.996361ms","start":"2026-05-11T01:46:11.118887Z","end":"2026-05-11T01:46:11.682884Z","steps":["trace[645241773] 'agreement among raft nodes before linearized reading' (duration: 563.586264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.683129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.118875Z","time spent":"564.236475ms","remote":"127.0.0.1:46386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":238,"response size":32,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:46:11.683171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.190821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-11T01:46:11.683163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.848078ms","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-11T01:46:11.683218Z","caller":"traceutil/trace.go:171","msg":"trace[816588281] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:28614; }","duration":"410.234382ms","start":"2026-05-11T01:46:11.272966Z","end":"2026-05-11T01:46:11.683201Z","steps":["trace[816588281] 'agreement among raft nodes before linearized reading' (duration: 410.057929ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:11.683243Z","caller":"traceutil/trace.go:171","msg":"trace[235768292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28614; }","duration":"116.950179ms","start":"2026-05-11T01:46:11.566273Z","end":"2026-05-11T01:46:11.683223Z","steps":["trace[235768292] 'agreement among raft nodes before linearized reading' (duration: 116.734316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.68326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.272894Z","time spent":"410.353974ms","remote":"127.0.0.1:46346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":136,"response size":32,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:46:11.682829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.925308ms","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-11T01:46:11.683311Z","caller":"traceutil/trace.go:171","msg":"trace[1571666591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28614; }","duration":"440.476958ms","start":"2026-05-11T01:46:11.24282Z","end":"2026-05-11T01:46:11.683297Z","steps":["trace[1571666591] 'agreement among raft nodes before linearized reading' (duration: 439.801776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.683353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.242799Z","time spent":"440.54567ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T01:46:11.684988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.922632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f10b655-dd25-4664-8f2b-1e0639f777fe\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-11T01:46:11.685201Z","caller":"traceutil/trace.go:171","msg":"trace[1867462238] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f10b655-dd25-4664-8f2b-1e0639f777fe; range_end:; response_count:1; response_revision:28614; }","duration":"122.093996ms","start":"2026-05-11T01:46:11.562979Z","end":"2026-05-11T01:46:11.685072Z","steps":["trace[1867462238] 'agreement among raft nodes before linearized reading' (duration: 119.68967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.686264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.074396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2577011"} {"level":"info","ts":"2026-05-11T01:46:11.686339Z","caller":"traceutil/trace.go:171","msg":"trace[66644776] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:28614; }","duration":"342.245769ms","start":"2026-05-11T01:46:11.344079Z","end":"2026-05-11T01:46:11.686324Z","steps":["trace[66644776] 'agreement among raft nodes before linearized reading' (duration: 339.070579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:11.686381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.344067Z","time spent":"342.30066ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2577034,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-05-11T01:46:11.963821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.43975ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-11T01:46:12.309346Z","caller":"traceutil/trace.go:171","msg":"trace[105677759] transaction","detail":"{read_only:false; response_revision:28615; number_of_response:1; }","duration":"618.708156ms","start":"2026-05-11T01:46:11.69059Z","end":"2026-05-11T01:46:12.309299Z","steps":["trace[105677759] 'process raft request' (duration: 582.916685ms)","trace[105677759] 'compare' (duration: 35.27195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:12.309509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.690566Z","time spent":"618.862828ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:12.310023Z","caller":"traceutil/trace.go:171","msg":"trace[2055091179] transaction","detail":"{read_only:false; response_revision:28618; number_of_response:1; }","duration":"618.724195ms","start":"2026-05-11T01:46:11.691283Z","end":"2026-05-11T01:46:12.310007Z","steps":["trace[2055091179] 'process raft request' (duration: 618.360809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.310106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.691257Z","time spent":"618.813838ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:12.310207Z","caller":"traceutil/trace.go:171","msg":"trace[1262313653] transaction","detail":"{read_only:false; response_revision:28617; number_of_response:1; }","duration":"619.261666ms","start":"2026-05-11T01:46:11.690932Z","end":"2026-05-11T01:46:12.310194Z","steps":["trace[1262313653] 'process raft request' (duration: 618.617744ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:12.310188Z","caller":"traceutil/trace.go:171","msg":"trace[1571968521] transaction","detail":"{read_only:false; response_revision:28619; number_of_response:1; }","duration":"344.770705ms","start":"2026-05-11T01:46:11.965377Z","end":"2026-05-11T01:46:12.310148Z","steps":["trace[1571968521] 'process raft request' (duration: 344.322047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.310266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.690906Z","time spent":"619.333237ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:12.310241Z","caller":"traceutil/trace.go:171","msg":"trace[1419981227] transaction","detail":"{read_only:false; response_revision:28620; number_of_response:1; }","duration":"313.098732ms","start":"2026-05-11T01:46:11.997112Z","end":"2026-05-11T01:46:12.310211Z","steps":["trace[1419981227] 'process raft request' (duration: 312.636424ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:12.310282Z","caller":"traceutil/trace.go:171","msg":"trace[1017487977] transaction","detail":"{read_only:false; response_revision:28616; number_of_response:1; }","duration":"619.413168ms","start":"2026-05-11T01:46:11.690798Z","end":"2026-05-11T01:46:12.310211Z","steps":["trace[1017487977] 'process raft request' (duration: 618.40302ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:12.310378Z","caller":"traceutil/trace.go:171","msg":"trace[120358433] linearizableReadLoop","detail":"{readStateIndex:29932; appliedIndex:29927; }","duration":"597.015369ms","start":"2026-05-11T01:46:11.713351Z","end":"2026-05-11T01:46:12.310367Z","steps":["trace[120358433] 'read index received' (duration: 38.990991ms)","trace[120358433] 'applied index is now lower than readState.Index' (duration: 558.023128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:12.310381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.965346Z","time spent":"344.928338ms","remote":"127.0.0.1:46338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:12.310412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.99708Z","time spent":"313.240995ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:12.310515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.172253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:46:12.310517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.69079Z","time spent":"619.598841ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:12.310556Z","caller":"traceutil/trace.go:171","msg":"trace[759808940] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:28621; }","duration":"448.252373ms","start":"2026-05-11T01:46:11.862291Z","end":"2026-05-11T01:46:12.310543Z","steps":["trace[759808940] 'agreement among raft nodes before linearized reading' (duration: 448.160652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.310601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.862268Z","time spent":"448.321306ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:46:12.310719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.104532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-11T01:46:12.310768Z","caller":"traceutil/trace.go:171","msg":"trace[1311695188] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:28621; }","duration":"243.184184ms","start":"2026-05-11T01:46:12.067571Z","end":"2026-05-11T01:46:12.310755Z","steps":["trace[1311695188] 'agreement among raft nodes before linearized reading' (duration: 242.99356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.310811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.488328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:12.31085Z","caller":"traceutil/trace.go:171","msg":"trace[1873574998] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:28621; }","duration":"268.57113ms","start":"2026-05-11T01:46:12.04227Z","end":"2026-05-11T01:46:12.310842Z","steps":["trace[1873574998] 'agreement among raft nodes before linearized reading' (duration: 268.510629ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:12.310926Z","caller":"traceutil/trace.go:171","msg":"trace[1466044951] transaction","detail":"{read_only:false; response_revision:28621; number_of_response:1; }","duration":"228.909188ms","start":"2026-05-11T01:46:12.081982Z","end":"2026-05-11T01:46:12.310892Z","steps":["trace[1466044951] 'process raft request' (duration: 227.812727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.310943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.891328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:12.311034Z","caller":"traceutil/trace.go:171","msg":"trace[668200169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28621; }","duration":"369.046101ms","start":"2026-05-11T01:46:11.941967Z","end":"2026-05-11T01:46:12.311013Z","steps":["trace[668200169] 'agreement among raft nodes before linearized reading' (duration: 368.870308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.31111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.941942Z","time spent":"369.129623ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T01:46:12.311113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.133513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-11T01:46:12.311171Z","caller":"traceutil/trace.go:171","msg":"trace[34297616] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:28621; }","duration":"219.214005ms","start":"2026-05-11T01:46:12.091946Z","end":"2026-05-11T01:46:12.311159Z","steps":["trace[34297616] 'agreement among raft nodes before linearized reading' (duration: 219.019782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.311411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.023468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-05-11T01:46:12.311526Z","caller":"traceutil/trace.go:171","msg":"trace[905779252] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:28621; }","duration":"598.283542ms","start":"2026-05-11T01:46:11.713213Z","end":"2026-05-11T01:46:12.311496Z","steps":["trace[905779252] 'agreement among raft nodes before linearized reading' (duration: 597.208832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.311637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:11.713191Z","time spent":"598.390615ms","remote":"127.0.0.1:46386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1841,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-05-11T01:46:12.798599Z","caller":"traceutil/trace.go:171","msg":"trace[1309053240] linearizableReadLoop","detail":"{readStateIndex:29936; appliedIndex:29935; }","duration":"479.5916ms","start":"2026-05-11T01:46:12.318981Z","end":"2026-05-11T01:46:12.798572Z","steps":["trace[1309053240] 'read index received' (duration: 479.456128ms)","trace[1309053240] 'applied index is now lower than readState.Index' (duration: 133.212µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:12.798764Z","caller":"traceutil/trace.go:171","msg":"trace[299297200] transaction","detail":"{read_only:false; response_revision:28622; number_of_response:1; }","duration":"481.655099ms","start":"2026-05-11T01:46:12.317051Z","end":"2026-05-11T01:46:12.798706Z","steps":["trace[299297200] 'process raft request' (duration: 481.326303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.798852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.855146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T01:46:12.798891Z","caller":"traceutil/trace.go:171","msg":"trace[1761792629] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:28622; }","duration":"479.933757ms","start":"2026-05-11T01:46:12.318945Z","end":"2026-05-11T01:46:12.798879Z","steps":["trace[1761792629] 'agreement among raft nodes before linearized reading' (duration: 479.770304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:12.798943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.318934Z","time spent":"479.996528ms","remote":"127.0.0.1:46596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-11T01:46:12.799019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.317037Z","time spent":"481.847453ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:13.296366Z","caller":"traceutil/trace.go:171","msg":"trace[1620107634] linearizableReadLoop","detail":"{readStateIndex:29939; appliedIndex:29936; }","duration":"497.642799ms","start":"2026-05-11T01:46:12.798683Z","end":"2026-05-11T01:46:13.296326Z","steps":["trace[1620107634] 'read index received' (duration: 497.141369ms)","trace[1620107634] 'applied index is now lower than readState.Index' (duration: 500.18µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:13.296514Z","caller":"traceutil/trace.go:171","msg":"trace[1227684594] transaction","detail":"{read_only:false; response_revision:28623; number_of_response:1; }","duration":"976.816572ms","start":"2026-05-11T01:46:12.319688Z","end":"2026-05-11T01:46:13.296504Z","steps":["trace[1227684594] 'process raft request' (duration: 976.265611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.296645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.31967Z","time spent":"976.877512ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2227,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T01:46:13.296882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.986642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-05-11T01:46:13.297016Z","caller":"traceutil/trace.go:171","msg":"trace[503042521] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28625; }","duration":"964.182805ms","start":"2026-05-11T01:46:12.33281Z","end":"2026-05-11T01:46:13.296993Z","steps":["trace[503042521] 'agreement among raft nodes before linearized reading' (duration: 963.86274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.297014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.363934ms","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-11T01:46:13.297242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.453633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:46:13.29718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.6298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f10b655-dd25-4664-8f2b-1e0639f777fe\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-11T01:46:13.297275Z","caller":"traceutil/trace.go:171","msg":"trace[340171858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28625; }","duration":"700.811622ms","start":"2026-05-11T01:46:12.596442Z","end":"2026-05-11T01:46:13.297254Z","steps":["trace[340171858] 'agreement among raft nodes before linearized reading' (duration: 700.108889ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:13.297292Z","caller":"traceutil/trace.go:171","msg":"trace[602645235] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:28625; }","duration":"514.550524ms","start":"2026-05-11T01:46:12.782728Z","end":"2026-05-11T01:46:13.297278Z","steps":["trace[602645235] 'agreement among raft nodes before linearized reading' (duration: 514.466793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.297335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.782708Z","time spent":"514.612196ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:46:13.29733Z","caller":"traceutil/trace.go:171","msg":"trace[1562583384] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f10b655-dd25-4664-8f2b-1e0639f777fe; range_end:; response_count:1; response_revision:28625; }","duration":"605.908406ms","start":"2026-05-11T01:46:12.691396Z","end":"2026-05-11T01:46:13.297305Z","steps":["trace[1562583384] 'agreement among raft nodes before linearized reading' (duration: 605.451528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.297535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.430052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"warn","ts":"2026-05-11T01:46:13.297611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.691375Z","time spent":"606.206372ms","remote":"127.0.0.1:46500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f10b655-dd25-4664-8f2b-1e0639f777fe\" "} {"level":"warn","ts":"2026-05-11T01:46:13.29759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.678264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-11T01:46:13.297074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.332786Z","time spent":"964.277467ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":685,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T01:46:13.297614Z","caller":"traceutil/trace.go:171","msg":"trace[1631971735] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:28625; }","duration":"154.494023ms","start":"2026-05-11T01:46:13.143093Z","end":"2026-05-11T01:46:13.297587Z","steps":["trace[1631971735] 'agreement among raft nodes before linearized reading' (duration: 154.287759ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:13.297743Z","caller":"traceutil/trace.go:171","msg":"trace[2114331937] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:28625; }","duration":"132.931849ms","start":"2026-05-11T01:46:13.164754Z","end":"2026-05-11T01:46:13.297686Z","steps":["trace[2114331937] 'agreement among raft nodes before linearized reading' (duration: 132.558141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.297336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.59642Z","time spent":"700.899734ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T01:46:13.29688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.943712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:46:13.297951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.223159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3191"} {"level":"info","ts":"2026-05-11T01:46:13.29691Z","caller":"traceutil/trace.go:171","msg":"trace[1146614859] transaction","detail":"{read_only:false; response_revision:28624; number_of_response:1; }","duration":"971.818487ms","start":"2026-05-11T01:46:12.325071Z","end":"2026-05-11T01:46:13.296889Z","steps":["trace[1146614859] 'process raft request' (duration: 971.077983ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:13.298032Z","caller":"traceutil/trace.go:171","msg":"trace[1791351245] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:28625; }","duration":"508.109824ms","start":"2026-05-11T01:46:12.789832Z","end":"2026-05-11T01:46:13.297941Z","steps":["trace[1791351245] 'agreement among raft nodes before linearized reading' (duration: 506.929892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.29815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.325049Z","time spent":"973.01539ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:13.297139Z","caller":"traceutil/trace.go:171","msg":"trace[1595010575] transaction","detail":"{read_only:false; response_revision:28625; number_of_response:1; }","duration":"909.566532ms","start":"2026-05-11T01:46:12.387424Z","end":"2026-05-11T01:46:13.296991Z","steps":["trace[1595010575] 'process raft request' (duration: 908.813798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.29847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.789805Z","time spent":"508.362629ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:46:13.298026Z","caller":"traceutil/trace.go:171","msg":"trace[2050007673] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:28625; }","duration":"773.355082ms","start":"2026-05-11T01:46:12.524659Z","end":"2026-05-11T01:46:13.298014Z","steps":["trace[2050007673] 'agreement among raft nodes before linearized reading' (duration: 772.442684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.29853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.387403Z","time spent":"910.828567ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:13.29855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.524629Z","time spent":"773.885282ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3214,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-11T01:46:13.297311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.5187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-11T01:46:13.297476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.222909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-05-11T01:46:13.298631Z","caller":"traceutil/trace.go:171","msg":"trace[2081390540] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28625; }","duration":"424.893546ms","start":"2026-05-11T01:46:12.8737Z","end":"2026-05-11T01:46:13.298594Z","steps":["trace[2081390540] 'agreement among raft nodes before linearized reading' (duration: 423.46791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.297471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.691952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-11T01:46:13.298779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.87368Z","time spent":"425.030388ms","remote":"127.0.0.1:36894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:46:13.298799Z","caller":"traceutil/trace.go:171","msg":"trace[202301087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28625; }","duration":"693.075149ms","start":"2026-05-11T01:46:12.605704Z","end":"2026-05-11T01:46:13.298779Z","steps":["trace[202301087] 'agreement among raft nodes before linearized reading' (duration: 691.508009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.297636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.453597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3523"} {"level":"info","ts":"2026-05-11T01:46:13.29886Z","caller":"traceutil/trace.go:171","msg":"trace[938952027] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:28625; }","duration":"967.719121ms","start":"2026-05-11T01:46:12.331129Z","end":"2026-05-11T01:46:13.298848Z","steps":["trace[938952027] 'agreement among raft nodes before linearized reading' (duration: 966.368315ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:13.298694Z","caller":"traceutil/trace.go:171","msg":"trace[1413026088] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28625; }","duration":"954.465613ms","start":"2026-05-11T01:46:12.344209Z","end":"2026-05-11T01:46:13.298675Z","steps":["trace[1413026088] 'agreement among raft nodes before linearized reading' (duration: 953.159208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.298946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.331119Z","time spent":"967.807642ms","remote":"127.0.0.1:46386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3546,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-05-11T01:46:13.298863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.605694Z","time spent":"693.14862ms","remote":"127.0.0.1:46594","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-11T01:46:13.299064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.344194Z","time spent":"954.834309ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":670,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T01:46:13.299308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.893775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T01:46:13.299373Z","caller":"traceutil/trace.go:171","msg":"trace[1283205566] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:28625; }","duration":"920.987616ms","start":"2026-05-11T01:46:12.378368Z","end":"2026-05-11T01:46:13.299356Z","steps":["trace[1283205566] 'agreement among raft nodes before linearized reading' (duration: 918.907496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.299409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:12.378358Z","time spent":"921.042077ms","remote":"127.0.0.1:46830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2717,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:46:13.719226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.947771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:13.719725Z","caller":"traceutil/trace.go:171","msg":"trace[1811707075] transaction","detail":"{read_only:false; response_revision:28626; number_of_response:1; }","duration":"416.254505ms","start":"2026-05-11T01:46:13.303439Z","end":"2026-05-11T01:46:13.719693Z","steps":["trace[1811707075] 'process raft request' (duration: 181.718442ms)","trace[1811707075] 'compare' (duration: 233.790718ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:13.719955Z","caller":"traceutil/trace.go:171","msg":"trace[739707939] transaction","detail":"{read_only:false; response_revision:28628; number_of_response:1; }","duration":"416.155913ms","start":"2026-05-11T01:46:13.303762Z","end":"2026-05-11T01:46:13.719918Z","steps":["trace[739707939] 'process raft request' (duration: 415.861457ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:13.719978Z","caller":"traceutil/trace.go:171","msg":"trace[2059403490] transaction","detail":"{read_only:false; response_revision:28627; number_of_response:1; }","duration":"416.174023ms","start":"2026-05-11T01:46:13.303756Z","end":"2026-05-11T01:46:13.71993Z","steps":["trace[2059403490] 'process raft request' (duration: 415.748745ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:13.720021Z","caller":"traceutil/trace.go:171","msg":"trace[1042203544] transaction","detail":"{read_only:false; response_revision:28629; number_of_response:1; }","duration":"411.1704ms","start":"2026-05-11T01:46:13.308813Z","end":"2026-05-11T01:46:13.719983Z","steps":["trace[1042203544] 'process raft request' (duration: 410.854544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.720041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.445129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-11T01:46:13.720147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:13.303743Z","time spent":"416.299415ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:13.719768Z","caller":"traceutil/trace.go:171","msg":"trace[1198384774] linearizableReadLoop","detail":"{readStateIndex:29942; appliedIndex:29939; }","duration":"415.161835ms","start":"2026-05-11T01:46:13.304582Z","end":"2026-05-11T01:46:13.719744Z","steps":["trace[1198384774] 'read index received' (duration: 180.598402ms)","trace[1198384774] 'applied index is now lower than readState.Index' (duration: 234.561333ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:13.720185Z","caller":"traceutil/trace.go:171","msg":"trace[2146061125] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28629; }","duration":"415.640333ms","start":"2026-05-11T01:46:13.30453Z","end":"2026-05-11T01:46:13.720171Z","steps":["trace[2146061125] 'agreement among raft nodes before linearized reading' (duration: 415.271177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.720188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:13.30372Z","time spent":"416.358307ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:13.720212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:13.308795Z","time spent":"411.326462ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:13.720053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:13.303426Z","time spent":"416.449268ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:13.720287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:13.3045Z","time spent":"415.770726ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T01:46:13.75542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.713813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3523"} {"level":"info","ts":"2026-05-11T01:46:13.755491Z","caller":"traceutil/trace.go:171","msg":"trace[1702964103] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:28632; }","duration":"447.814205ms","start":"2026-05-11T01:46:13.30766Z","end":"2026-05-11T01:46:13.755475Z","steps":["trace[1702964103] 'agreement among raft nodes before linearized reading' (duration: 447.637302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.755522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:13.307649Z","time spent":"447.863357ms","remote":"127.0.0.1:46386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3546,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-05-11T01:46:13.755748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.388774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-05-11T01:46:13.755807Z","caller":"traceutil/trace.go:171","msg":"trace[298604800] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:28632; }","duration":"450.483266ms","start":"2026-05-11T01:46:13.305308Z","end":"2026-05-11T01:46:13.755791Z","steps":["trace[298604800] 'agreement among raft nodes before linearized reading' (duration: 450.248911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.755875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:13.3053Z","time spent":"450.565087ms","remote":"127.0.0.1:46516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1281,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"info","ts":"2026-05-11T01:46:15.864791Z","caller":"traceutil/trace.go:171","msg":"trace[446659803] transaction","detail":"{read_only:false; response_revision:28655; number_of_response:1; }","duration":"104.522307ms","start":"2026-05-11T01:46:15.760235Z","end":"2026-05-11T01:46:15.864758Z","steps":["trace[446659803] 'process raft request' (duration: 104.420726ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:15.865219Z","caller":"traceutil/trace.go:171","msg":"trace[1565164248] transaction","detail":"{read_only:false; response_revision:28656; number_of_response:1; }","duration":"102.845825ms","start":"2026-05-11T01:46:15.762343Z","end":"2026-05-11T01:46:15.865189Z","steps":["trace[1565164248] 'process raft request' (duration: 102.367237ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:15.865255Z","caller":"traceutil/trace.go:171","msg":"trace[814657441] transaction","detail":"{read_only:false; response_revision:28654; number_of_response:1; }","duration":"133.642432ms","start":"2026-05-11T01:46:15.731599Z","end":"2026-05-11T01:46:15.865241Z","steps":["trace[814657441] 'process raft request' (duration: 132.863058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:15.865695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.087835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-05-11T01:46:15.865753Z","caller":"traceutil/trace.go:171","msg":"trace[522466870] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28656; }","duration":"100.221387ms","start":"2026-05-11T01:46:15.765512Z","end":"2026-05-11T01:46:15.865734Z","steps":["trace[522466870] 'agreement among raft nodes before linearized reading' (duration: 100.009473ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:17.905402Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:46:17.90921Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T01:46:17.909295Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T01:46:19.251683Z","caller":"traceutil/trace.go:171","msg":"trace[135870933] transaction","detail":"{read_only:false; response_revision:28705; number_of_response:1; }","duration":"126.519029ms","start":"2026-05-11T01:46:19.125125Z","end":"2026-05-11T01:46:19.251644Z","steps":["trace[135870933] 'process raft request' (duration: 126.099801ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:48.379992Z","caller":"traceutil/trace.go:171","msg":"trace[1554417432] transaction","detail":"{read_only:false; response_revision:28981; number_of_response:1; }","duration":"133.53633ms","start":"2026-05-11T01:46:48.246419Z","end":"2026-05-11T01:46:48.379955Z","steps":["trace[1554417432] 'process raft request' (duration: 133.295676ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:48.472028Z","caller":"traceutil/trace.go:171","msg":"trace[968931443] linearizableReadLoop","detail":"{readStateIndex:30310; appliedIndex:30308; }","duration":"137.695299ms","start":"2026-05-11T01:46:48.334295Z","end":"2026-05-11T01:46:48.47199Z","steps":["trace[968931443] 'read index received' (duration: 45.462841ms)","trace[968931443] 'applied index is now lower than readState.Index' (duration: 92.230908ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:48.472191Z","caller":"traceutil/trace.go:171","msg":"trace[1961007735] transaction","detail":"{read_only:false; response_revision:28982; number_of_response:1; }","duration":"223.431654ms","start":"2026-05-11T01:46:48.248745Z","end":"2026-05-11T01:46:48.472177Z","steps":["trace[1961007735] 'process raft request' (duration: 222.636179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:48.472476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.138487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:46:48.472593Z","caller":"traceutil/trace.go:171","msg":"trace[1614024818] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:28982; }","duration":"138.30362ms","start":"2026-05-11T01:46:48.334264Z","end":"2026-05-11T01:46:48.472568Z","steps":["trace[1614024818] 'agreement among raft nodes before linearized reading' (duration: 138.090706ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:50.70533Z","caller":"traceutil/trace.go:171","msg":"trace[1165862160] linearizableReadLoop","detail":"{readStateIndex:30327; appliedIndex:30326; }","duration":"177.478033ms","start":"2026-05-11T01:46:50.527825Z","end":"2026-05-11T01:46:50.705303Z","steps":["trace[1165862160] 'read index received' (duration: 177.362101ms)","trace[1165862160] 'applied index is now lower than readState.Index' (duration: 114.192µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:50.705355Z","caller":"traceutil/trace.go:171","msg":"trace[1279339362] transaction","detail":"{read_only:false; response_revision:28999; number_of_response:1; }","duration":"177.643396ms","start":"2026-05-11T01:46:50.527673Z","end":"2026-05-11T01:46:50.705316Z","steps":["trace[1279339362] 'process raft request' (duration: 177.438843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:50.705607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.757749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-05-11T01:46:50.705653Z","caller":"traceutil/trace.go:171","msg":"trace[1714648217] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28999; }","duration":"177.82656ms","start":"2026-05-11T01:46:50.527808Z","end":"2026-05-11T01:46:50.705635Z","steps":["trace[1714648217] 'agreement among raft nodes before linearized reading' (duration: 177.627267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.106973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.452533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:51.107223Z","caller":"traceutil/trace.go:171","msg":"trace[2071690812] transaction","detail":"{read_only:false; response_revision:29003; number_of_response:1; }","duration":"395.123999ms","start":"2026-05-11T01:46:50.712082Z","end":"2026-05-11T01:46:51.107206Z","steps":["trace[2071690812] 'process raft request' (duration: 395.087338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.10728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:50.712044Z","time spent":"395.20913ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:51.107363Z","caller":"traceutil/trace.go:171","msg":"trace[831776902] transaction","detail":"{read_only:false; response_revision:29001; number_of_response:1; }","duration":"579.323808ms","start":"2026-05-11T01:46:50.527996Z","end":"2026-05-11T01:46:51.10732Z","steps":["trace[831776902] 'process raft request' (duration: 579.079413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.107571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:50.527972Z","time spent":"579.482941ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:51.107616Z","caller":"traceutil/trace.go:171","msg":"trace[1386370153] linearizableReadLoop","detail":"{readStateIndex:30330; appliedIndex:30327; }","duration":"402.179911ms","start":"2026-05-11T01:46:50.70541Z","end":"2026-05-11T01:46:51.10759Z","steps":["trace[1386370153] 'read index received' (duration: 114.999074ms)","trace[1386370153] 'applied index is now lower than readState.Index' (duration: 287.178817ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:51.10771Z","caller":"traceutil/trace.go:171","msg":"trace[1249331265] transaction","detail":"{read_only:false; response_revision:29002; number_of_response:1; }","duration":"575.961825ms","start":"2026-05-11T01:46:50.531739Z","end":"2026-05-11T01:46:51.107701Z","steps":["trace[1249331265] 'process raft request' (duration: 575.383144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.107759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:50.531729Z","time spent":"575.996675ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:51.107935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.296398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:46:51.108006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.228741ms","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-11T01:46:51.108036Z","caller":"traceutil/trace.go:171","msg":"trace[510928940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29003; }","duration":"575.276333ms","start":"2026-05-11T01:46:50.532748Z","end":"2026-05-11T01:46:51.108024Z","steps":["trace[510928940] 'agreement among raft nodes before linearized reading' (duration: 575.15167ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:51.108034Z","caller":"traceutil/trace.go:171","msg":"trace[141000457] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:29003; }","duration":"571.43678ms","start":"2026-05-11T01:46:50.536572Z","end":"2026-05-11T01:46:51.108008Z","steps":["trace[141000457] 'agreement among raft nodes before linearized reading' (duration: 571.235237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.108058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:50.532734Z","time spent":"575.319473ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T01:46:51.108079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.835869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:46:51.108091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:50.536559Z","time spent":"571.518492ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:46:51.108128Z","caller":"traceutil/trace.go:171","msg":"trace[1955774426] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:29003; }","duration":"499.88579ms","start":"2026-05-11T01:46:50.608228Z","end":"2026-05-11T01:46:51.108114Z","steps":["trace[1955774426] 'agreement among raft nodes before linearized reading' (duration: 499.781868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.108164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:50.608165Z","time spent":"499.990792ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:46:51.108201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.574026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:51.108225Z","caller":"traceutil/trace.go:171","msg":"trace[741535394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29003; }","duration":"168.641017ms","start":"2026-05-11T01:46:50.939577Z","end":"2026-05-11T01:46:51.108218Z","steps":["trace[741535394] 'agreement among raft nodes before linearized reading' (duration: 168.595806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.10838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.416373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:51.10842Z","caller":"traceutil/trace.go:171","msg":"trace[107488716] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:29003; }","duration":"530.476553ms","start":"2026-05-11T01:46:50.577932Z","end":"2026-05-11T01:46:51.108408Z","steps":["trace[107488716] 'agreement among raft nodes before linearized reading' (duration: 530.407062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.108456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:50.577904Z","time spent":"530.539024ms","remote":"127.0.0.1:41970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:46:51.109608Z","caller":"traceutil/trace.go:171","msg":"trace[2133029722] transaction","detail":"{read_only:false; response_revision:29000; number_of_response:1; }","duration":"581.519979ms","start":"2026-05-11T01:46:50.527909Z","end":"2026-05-11T01:46:51.109429Z","steps":["trace[2133029722] 'process raft request' (duration: 292.480617ms)","trace[2133029722] 'compare' (duration: 285.638239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:51.109737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:50.527895Z","time spent":"581.764384ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:51.349375Z","caller":"traceutil/trace.go:171","msg":"trace[1232997452] linearizableReadLoop","detail":"{readStateIndex:30332; appliedIndex:30331; }","duration":"234.601283ms","start":"2026-05-11T01:46:51.114743Z","end":"2026-05-11T01:46:51.349344Z","steps":["trace[1232997452] 'read index received' (duration: 233.233298ms)","trace[1232997452] 'applied index is now lower than readState.Index' (duration: 1.366415ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:51.349473Z","caller":"traceutil/trace.go:171","msg":"trace[180298718] transaction","detail":"{read_only:false; response_revision:29004; number_of_response:1; }","duration":"235.387238ms","start":"2026-05-11T01:46:51.114043Z","end":"2026-05-11T01:46:51.349431Z","steps":["trace[180298718] 'process raft request' (duration: 235.061952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.349715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.933289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0b51f735-0e9e-4c49-8f83-160d1c76eff8\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-05-11T01:46:51.349823Z","caller":"traceutil/trace.go:171","msg":"trace[838107504] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0b51f735-0e9e-4c49-8f83-160d1c76eff8; range_end:; response_count:1; response_revision:29004; }","duration":"235.071972ms","start":"2026-05-11T01:46:51.114724Z","end":"2026-05-11T01:46:51.349796Z","steps":["trace[838107504] 'agreement among raft nodes before linearized reading' (duration: 234.744096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.400835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.895409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:51.400867Z","caller":"traceutil/trace.go:171","msg":"trace[1842185068] transaction","detail":"{read_only:false; response_revision:29005; number_of_response:1; }","duration":"281.733795ms","start":"2026-05-11T01:46:51.119106Z","end":"2026-05-11T01:46:51.40084Z","steps":["trace[1842185068] 'process raft request' (duration: 269.478446ms)","trace[1842185068] 'compare' (duration: 11.661448ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:51.400914Z","caller":"traceutil/trace.go:171","msg":"trace[263866737] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:29006; }","duration":"250.040453ms","start":"2026-05-11T01:46:51.150856Z","end":"2026-05-11T01:46:51.400897Z","steps":["trace[263866737] 'agreement among raft nodes before linearized reading' (duration: 249.890689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.401101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.515568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:51.401133Z","caller":"traceutil/trace.go:171","msg":"trace[641497050] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:29006; }","duration":"198.671381ms","start":"2026-05-11T01:46:51.202451Z","end":"2026-05-11T01:46:51.401123Z","steps":["trace[641497050] 'agreement among raft nodes before linearized reading' (duration: 198.579889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.400835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.399889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:51.401185Z","caller":"traceutil/trace.go:171","msg":"trace[460921149] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:29006; }","duration":"171.804148ms","start":"2026-05-11T01:46:51.229368Z","end":"2026-05-11T01:46:51.401172Z","steps":["trace[460921149] 'agreement among raft nodes before linearized reading' (duration: 171.394739ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:51.401287Z","caller":"traceutil/trace.go:171","msg":"trace[1478342289] transaction","detail":"{read_only:false; response_revision:29006; number_of_response:1; }","duration":"281.670175ms","start":"2026-05-11T01:46:51.119239Z","end":"2026-05-11T01:46:51.400909Z","steps":["trace[1478342289] 'process raft request' (duration: 281.388859ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:51.626815Z","caller":"traceutil/trace.go:171","msg":"trace[1006588194] linearizableReadLoop","detail":"{readStateIndex:30337; appliedIndex:30334; }","duration":"120.993046ms","start":"2026-05-11T01:46:51.505798Z","end":"2026-05-11T01:46:51.626791Z","steps":["trace[1006588194] 'read index received' (duration: 32.272624ms)","trace[1006588194] 'applied index is now lower than readState.Index' (duration: 88.719352ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:51.626926Z","caller":"traceutil/trace.go:171","msg":"trace[280497131] transaction","detail":"{read_only:false; response_revision:29008; number_of_response:1; }","duration":"156.032212ms","start":"2026-05-11T01:46:51.470851Z","end":"2026-05-11T01:46:51.626883Z","steps":["trace[280497131] 'process raft request' (duration: 155.667395ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:51.626926Z","caller":"traceutil/trace.go:171","msg":"trace[110416303] transaction","detail":"{read_only:false; response_revision:29009; number_of_response:1; }","duration":"147.121425ms","start":"2026-05-11T01:46:51.479757Z","end":"2026-05-11T01:46:51.626878Z","steps":["trace[110416303] 'process raft request' (duration: 146.948782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.627065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.23304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:51.627166Z","caller":"traceutil/trace.go:171","msg":"trace[1175383436] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:29009; }","duration":"121.394284ms","start":"2026-05-11T01:46:51.505748Z","end":"2026-05-11T01:46:51.627142Z","steps":["trace[1175383436] 'agreement among raft nodes before linearized reading' (duration: 121.18225ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:51.982824Z","caller":"traceutil/trace.go:171","msg":"trace[148848391] transaction","detail":"{read_only:false; number_of_response:0; response_revision:29009; }","duration":"347.728301ms","start":"2026-05-11T01:46:51.635066Z","end":"2026-05-11T01:46:51.982794Z","steps":["trace[148848391] 'process raft request' (duration: 288.003293ms)","trace[148848391] 'compare' (duration: 59.595426ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:51.982908Z","caller":"traceutil/trace.go:171","msg":"trace[642266212] linearizableReadLoop","detail":"{readStateIndex:30340; appliedIndex:30338; }","duration":"177.570435ms","start":"2026-05-11T01:46:51.805319Z","end":"2026-05-11T01:46:51.98289Z","steps":["trace[642266212] 'read index received' (duration: 117.730674ms)","trace[642266212] 'applied index is now lower than readState.Index' (duration: 59.838441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:51.982981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:51.635052Z","time spent":"347.846663ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T01:46:51.983013Z","caller":"traceutil/trace.go:171","msg":"trace[72090541] transaction","detail":"{read_only:false; response_revision:29010; number_of_response:1; }","duration":"195.068092ms","start":"2026-05-11T01:46:51.787869Z","end":"2026-05-11T01:46:51.982937Z","steps":["trace[72090541] 'process raft request' (duration: 194.887249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:51.983074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.749179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:51.983119Z","caller":"traceutil/trace.go:171","msg":"trace[538153164] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:29010; }","duration":"177.83692ms","start":"2026-05-11T01:46:51.805267Z","end":"2026-05-11T01:46:51.983104Z","steps":["trace[538153164] 'agreement among raft nodes before linearized reading' (duration: 177.727688ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:52.154302Z","caller":"traceutil/trace.go:171","msg":"trace[138744884] linearizableReadLoop","detail":"{readStateIndex:30341; appliedIndex:30340; }","duration":"164.208216ms","start":"2026-05-11T01:46:51.990055Z","end":"2026-05-11T01:46:52.154263Z","steps":["trace[138744884] 'read index received' (duration: 125.230576ms)","trace[138744884] 'applied index is now lower than readState.Index' (duration: 38.97632ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:52.154454Z","caller":"traceutil/trace.go:171","msg":"trace[1121618258] transaction","detail":"{read_only:false; response_revision:29011; number_of_response:1; }","duration":"165.371787ms","start":"2026-05-11T01:46:51.989065Z","end":"2026-05-11T01:46:52.154437Z","steps":["trace[1121618258] 'process raft request' (duration: 126.179993ms)","trace[1121618258] 'compare' (duration: 38.730754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:52.15474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.665184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T01:46:52.154817Z","caller":"traceutil/trace.go:171","msg":"trace[394171460] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:29011; }","duration":"164.798446ms","start":"2026-05-11T01:46:51.990001Z","end":"2026-05-11T01:46:52.1548Z","steps":["trace[394171460] 'agreement among raft nodes before linearized reading' (duration: 164.512651ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:52.309748Z","caller":"traceutil/trace.go:171","msg":"trace[285998114] transaction","detail":"{read_only:false; response_revision:29012; number_of_response:1; }","duration":"315.529289ms","start":"2026-05-11T01:46:51.994172Z","end":"2026-05-11T01:46:52.309701Z","steps":["trace[285998114] 'process raft request' (duration: 301.276951ms)","trace[285998114] 'compare' (duration: 14.018373ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:52.309783Z","caller":"traceutil/trace.go:171","msg":"trace[1576419280] linearizableReadLoop","detail":"{readStateIndex:30344; appliedIndex:30341; }","duration":"155.363429ms","start":"2026-05-11T01:46:52.154389Z","end":"2026-05-11T01:46:52.309752Z","steps":["trace[1576419280] 'read index received' (duration: 141.01458ms)","trace[1576419280] 'applied index is now lower than readState.Index' (duration: 14.347459ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:52.309824Z","caller":"traceutil/trace.go:171","msg":"trace[2136048608] transaction","detail":"{read_only:false; response_revision:29013; number_of_response:1; }","duration":"315.314856ms","start":"2026-05-11T01:46:51.994457Z","end":"2026-05-11T01:46:52.309771Z","steps":["trace[2136048608] 'process raft request' (duration: 315.169213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:52.309939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:51.994161Z","time spent":"315.674242ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:52.310014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:51.994443Z","time spent":"315.463448ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:52.310053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.296512ms","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-11T01:46:52.310106Z","caller":"traceutil/trace.go:171","msg":"trace[1311698786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29014; }","duration":"286.386193ms","start":"2026-05-11T01:46:52.023705Z","end":"2026-05-11T01:46:52.310091Z","steps":["trace[1311698786] 'agreement among raft nodes before linearized reading' (duration: 286.155889ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:52.310162Z","caller":"traceutil/trace.go:171","msg":"trace[1926527032] transaction","detail":"{read_only:false; response_revision:29014; number_of_response:1; }","duration":"297.502871ms","start":"2026-05-11T01:46:52.01263Z","end":"2026-05-11T01:46:52.310133Z","steps":["trace[1926527032] 'process raft request' (duration: 297.065393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:52.576554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.277622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:52.576764Z","caller":"traceutil/trace.go:171","msg":"trace[1798362840] linearizableReadLoop","detail":"{readStateIndex:30346; appliedIndex:30345; }","duration":"185.785619ms","start":"2026-05-11T01:46:52.390952Z","end":"2026-05-11T01:46:52.576737Z","steps":["trace[1798362840] 'read index received' (duration: 65.13938ms)","trace[1798362840] 'applied index is now lower than readState.Index' (duration: 120.644059ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:52.576816Z","caller":"traceutil/trace.go:171","msg":"trace[1214663223] transaction","detail":"{read_only:false; response_revision:29016; number_of_response:1; }","duration":"192.112427ms","start":"2026-05-11T01:46:52.384652Z","end":"2026-05-11T01:46:52.576764Z","steps":["trace[1214663223] 'process raft request' (duration: 71.442588ms)","trace[1214663223] 'compare' (duration: 120.094569ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:52.577083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.134266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-05-11T01:46:52.577132Z","caller":"traceutil/trace.go:171","msg":"trace[154538175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:29016; }","duration":"186.221568ms","start":"2026-05-11T01:46:52.390896Z","end":"2026-05-11T01:46:52.577117Z","steps":["trace[154538175] 'agreement among raft nodes before linearized reading' (duration: 185.961082ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:52.837955Z","caller":"traceutil/trace.go:171","msg":"trace[1247155195] linearizableReadLoop","detail":"{readStateIndex:30347; appliedIndex:30346; }","duration":"261.1198ms","start":"2026-05-11T01:46:52.576809Z","end":"2026-05-11T01:46:52.837929Z","steps":["trace[1247155195] 'read index received' (duration: 186.607485ms)","trace[1247155195] 'applied index is now lower than readState.Index' (duration: 74.511235ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:52.838011Z","caller":"traceutil/trace.go:171","msg":"trace[735337727] transaction","detail":"{read_only:false; response_revision:29017; number_of_response:1; }","duration":"306.294456ms","start":"2026-05-11T01:46:52.531661Z","end":"2026-05-11T01:46:52.837956Z","steps":["trace[735337727] 'process raft request' (duration: 231.878362ms)","trace[735337727] 'compare' (duration: 74.019837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:52.838052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.829306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:46:52.838116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.198386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:52.838154Z","caller":"traceutil/trace.go:171","msg":"trace[1008580825] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:29017; }","duration":"414.300998ms","start":"2026-05-11T01:46:52.423838Z","end":"2026-05-11T01:46:52.838139Z","steps":["trace[1008580825] 'agreement among raft nodes before linearized reading' (duration: 414.209157ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:52.838149Z","caller":"traceutil/trace.go:171","msg":"trace[1735605524] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:29017; }","duration":"345.968048ms","start":"2026-05-11T01:46:52.492158Z","end":"2026-05-11T01:46:52.838126Z","steps":["trace[1735605524] 'agreement among raft nodes before linearized reading' (duration: 345.778075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:52.838189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:52.423815Z","time spent":"414.36512ms","remote":"127.0.0.1:37494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:46:52.838211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:52.492146Z","time spent":"346.05124ms","remote":"127.0.0.1:52528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:46:52.838235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:52.531642Z","time spent":"306.460609ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:52.848144Z","caller":"traceutil/trace.go:171","msg":"trace[294610868] transaction","detail":"{read_only:false; response_revision:29018; number_of_response:1; }","duration":"211.889817ms","start":"2026-05-11T01:46:52.636239Z","end":"2026-05-11T01:46:52.848129Z","steps":["trace[294610868] 'process raft request' (duration: 211.692803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:52.848306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.227398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-05-11T01:46:52.848385Z","caller":"traceutil/trace.go:171","msg":"trace[1391406455] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29018; }","duration":"138.36029ms","start":"2026-05-11T01:46:52.710009Z","end":"2026-05-11T01:46:52.848369Z","steps":["trace[1391406455] 'agreement among raft nodes before linearized reading' (duration: 138.101696ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:53.219271Z","caller":"traceutil/trace.go:171","msg":"trace[295493220] linearizableReadLoop","detail":"{readStateIndex:30349; appliedIndex:30348; }","duration":"276.13575ms","start":"2026-05-11T01:46:52.943107Z","end":"2026-05-11T01:46:53.219242Z","steps":["trace[295493220] 'read index received' (duration: 275.871125ms)","trace[295493220] 'applied index is now lower than readState.Index' (duration: 263.445µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:53.219424Z","caller":"traceutil/trace.go:171","msg":"trace[285842222] transaction","detail":"{read_only:false; response_revision:29019; number_of_response:1; }","duration":"366.324439ms","start":"2026-05-11T01:46:52.853084Z","end":"2026-05-11T01:46:53.219408Z","steps":["trace[285842222] 'process raft request' (duration: 365.921282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:53.219597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.046616ms","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-11T01:46:53.219674Z","caller":"traceutil/trace.go:171","msg":"trace[1710685929] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:29019; }","duration":"176.202289ms","start":"2026-05-11T01:46:53.043455Z","end":"2026-05-11T01:46:53.219657Z","steps":["trace[1710685929] 'agreement among raft nodes before linearized reading' (duration: 176.068546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:53.219765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.636781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:46:53.219762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:52.853059Z","time spent":"366.499602ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:53.21986Z","caller":"traceutil/trace.go:171","msg":"trace[468942718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29019; }","duration":"276.809564ms","start":"2026-05-11T01:46:52.94303Z","end":"2026-05-11T01:46:53.219839Z","steps":["trace[468942718] 'agreement among raft nodes before linearized reading' (duration: 276.606339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:53.220028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.352733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T01:46:53.220127Z","caller":"traceutil/trace.go:171","msg":"trace[905453999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29019; }","duration":"105.517737ms","start":"2026-05-11T01:46:53.114589Z","end":"2026-05-11T01:46:53.220106Z","steps":["trace[905453999] 'agreement among raft nodes before linearized reading' (duration: 105.188101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:53.220247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.568116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T01:46:53.22068Z","caller":"traceutil/trace.go:171","msg":"trace[1054886321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29019; }","duration":"106.044395ms","start":"2026-05-11T01:46:53.114614Z","end":"2026-05-11T01:46:53.220659Z","steps":["trace[1054886321] 'agreement among raft nodes before linearized reading' (duration: 105.131789ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:53.410482Z","caller":"traceutil/trace.go:171","msg":"trace[1992937103] transaction","detail":"{read_only:false; response_revision:29020; number_of_response:1; }","duration":"185.125757ms","start":"2026-05-11T01:46:53.225327Z","end":"2026-05-11T01:46:53.410452Z","steps":["trace[1992937103] 'process raft request' (duration: 144.315353ms)","trace[1992937103] 'compare' (duration: 39.604831ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:53.410897Z","caller":"traceutil/trace.go:171","msg":"trace[521187061] transaction","detail":"{read_only:false; response_revision:29021; number_of_response:1; }","duration":"185.516764ms","start":"2026-05-11T01:46:53.22537Z","end":"2026-05-11T01:46:53.410887Z","steps":["trace[521187061] 'process raft request' (duration: 184.832772ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:53.544515Z","caller":"traceutil/trace.go:171","msg":"trace[1400693930] transaction","detail":"{read_only:false; response_revision:29023; number_of_response:1; }","duration":"123.091075ms","start":"2026-05-11T01:46:53.421395Z","end":"2026-05-11T01:46:53.544486Z","steps":["trace[1400693930] 'process raft request' (duration: 123.009253ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:53.544549Z","caller":"traceutil/trace.go:171","msg":"trace[37764856] transaction","detail":"{read_only:false; response_revision:29022; number_of_response:1; }","duration":"127.187432ms","start":"2026-05-11T01:46:53.417322Z","end":"2026-05-11T01:46:53.544509Z","steps":["trace[37764856] 'process raft request' (duration: 122.32646ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:53.544837Z","caller":"traceutil/trace.go:171","msg":"trace[644906655] linearizableReadLoop","detail":"{readStateIndex:30352; appliedIndex:30351; }","duration":"126.813865ms","start":"2026-05-11T01:46:53.41801Z","end":"2026-05-11T01:46:53.544824Z","steps":["trace[644906655] 'read index received' (duration: 121.654058ms)","trace[644906655] 'applied index is now lower than readState.Index' (duration: 5.158647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:53.545047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.007569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-11T01:46:53.545096Z","caller":"traceutil/trace.go:171","msg":"trace[522479659] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29023; }","duration":"127.08949ms","start":"2026-05-11T01:46:53.417993Z","end":"2026-05-11T01:46:53.545083Z","steps":["trace[522479659] 'agreement among raft nodes before linearized reading' (duration: 126.881706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:53.854559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.141763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:53.854906Z","caller":"traceutil/trace.go:171","msg":"trace[960829940] transaction","detail":"{read_only:false; response_revision:29027; number_of_response:1; }","duration":"221.63216ms","start":"2026-05-11T01:46:53.633254Z","end":"2026-05-11T01:46:53.854886Z","steps":["trace[960829940] 'process raft request' (duration: 221.581069ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:53.855164Z","caller":"traceutil/trace.go:171","msg":"trace[1161887743] transaction","detail":"{read_only:false; response_revision:29025; number_of_response:1; }","duration":"304.339599ms","start":"2026-05-11T01:46:53.550814Z","end":"2026-05-11T01:46:53.855154Z","steps":["trace[1161887743] 'process raft request' (duration: 303.86923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:53.855245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:53.550795Z","time spent":"304.40709ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:53.855319Z","caller":"traceutil/trace.go:171","msg":"trace[364583854] transaction","detail":"{read_only:false; response_revision:29024; number_of_response:1; }","duration":"305.182854ms","start":"2026-05-11T01:46:53.550089Z","end":"2026-05-11T01:46:53.855272Z","steps":["trace[364583854] 'process raft request' (duration: 171.200126ms)","trace[364583854] 'compare' (duration: 132.99193ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:53.855399Z","caller":"traceutil/trace.go:171","msg":"trace[1527139535] transaction","detail":"{read_only:false; response_revision:29026; number_of_response:1; }","duration":"302.512966ms","start":"2026-05-11T01:46:53.552876Z","end":"2026-05-11T01:46:53.855388Z","steps":["trace[1527139535] 'process raft request' (duration: 301.920424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:53.85547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:53.552866Z","time spent":"302.558357ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:53.855569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:53.55008Z","time spent":"305.338508ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:54.118081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.88701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:54.118213Z","caller":"traceutil/trace.go:171","msg":"trace[1082259434] linearizableReadLoop","detail":"{readStateIndex:30358; appliedIndex:30357; }","duration":"175.18506ms","start":"2026-05-11T01:46:53.94301Z","end":"2026-05-11T01:46:54.118195Z","steps":["trace[1082259434] 'read index received' (duration: 43.078127ms)","trace[1082259434] 'applied index is now lower than readState.Index' (duration: 132.105873ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:54.118261Z","caller":"traceutil/trace.go:171","msg":"trace[1110982009] transaction","detail":"{read_only:false; response_revision:29028; number_of_response:1; }","duration":"254.417884ms","start":"2026-05-11T01:46:53.86379Z","end":"2026-05-11T01:46:54.118207Z","steps":["trace[1110982009] 'process raft request' (duration: 122.265249ms)","trace[1110982009] 'compare' (duration: 131.736787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:54.118352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.153389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:54.118386Z","caller":"traceutil/trace.go:171","msg":"trace[1037624993] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:29028; }","duration":"128.228221ms","start":"2026-05-11T01:46:53.990146Z","end":"2026-05-11T01:46:54.118375Z","steps":["trace[1037624993] 'agreement among raft nodes before linearized reading' (duration: 128.157239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:54.118417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.393724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:54.118535Z","caller":"traceutil/trace.go:171","msg":"trace[1970377520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29028; }","duration":"175.572327ms","start":"2026-05-11T01:46:53.942934Z","end":"2026-05-11T01:46:54.118506Z","steps":["trace[1970377520] 'agreement among raft nodes before linearized reading' (duration: 175.348213ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:54.349459Z","caller":"traceutil/trace.go:171","msg":"trace[725782217] transaction","detail":"{read_only:false; response_revision:29029; number_of_response:1; }","duration":"186.345629ms","start":"2026-05-11T01:46:54.16309Z","end":"2026-05-11T01:46:54.349435Z","steps":["trace[725782217] 'process raft request' (duration: 122.681667ms)","trace[725782217] 'compare' (duration: 63.56404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:54.702542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.614463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:54.702638Z","caller":"traceutil/trace.go:171","msg":"trace[1592542427] transaction","detail":"{read_only:false; response_revision:29030; number_of_response:1; }","duration":"401.223953ms","start":"2026-05-11T01:46:54.301399Z","end":"2026-05-11T01:46:54.702623Z","steps":["trace[1592542427] 'process raft request' (duration: 276.448357ms)","trace[1592542427] 'compare' (duration: 124.396909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:54.70269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:54.301383Z","time spent":"401.289084ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:54.705666Z","caller":"traceutil/trace.go:171","msg":"trace[559675496] linearizableReadLoop","detail":"{readStateIndex:30362; appliedIndex:30359; }","duration":"325.586597ms","start":"2026-05-11T01:46:54.380056Z","end":"2026-05-11T01:46:54.705642Z","steps":["trace[559675496] 'read index received' (duration: 197.760573ms)","trace[559675496] 'applied index is now lower than readState.Index' (duration: 127.824714ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:54.706032Z","caller":"traceutil/trace.go:171","msg":"trace[1869477443] transaction","detail":"{read_only:false; response_revision:29031; number_of_response:1; }","duration":"389.877321ms","start":"2026-05-11T01:46:54.316141Z","end":"2026-05-11T01:46:54.706018Z","steps":["trace[1869477443] 'process raft request' (duration: 389.319591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:54.7062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:54.316108Z","time spent":"390.000392ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:54.706536Z","caller":"traceutil/trace.go:171","msg":"trace[1751737900] transaction","detail":"{read_only:false; response_revision:29032; number_of_response:1; }","duration":"388.361212ms","start":"2026-05-11T01:46:54.318156Z","end":"2026-05-11T01:46:54.706517Z","steps":["trace[1751737900] 'process raft request' (duration: 387.437135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:54.706618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:54.318145Z","time spent":"388.432824ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:54.706779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.719179ms","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-11T01:46:54.706879Z","caller":"traceutil/trace.go:171","msg":"trace[1253022372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29032; }","duration":"326.882402ms","start":"2026-05-11T01:46:54.379971Z","end":"2026-05-11T01:46:54.706854Z","steps":["trace[1253022372] 'agreement among raft nodes before linearized reading' (duration: 326.615446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:54.706933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:54.379944Z","time spent":"326.976843ms","remote":"127.0.0.1:46594","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-11T01:46:55.165409Z","caller":"traceutil/trace.go:171","msg":"trace[108130921] transaction","detail":"{read_only:false; response_revision:29034; number_of_response:1; }","duration":"321.125723ms","start":"2026-05-11T01:46:54.844255Z","end":"2026-05-11T01:46:55.165381Z","steps":["trace[108130921] 'process raft request' (duration: 320.95677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:55.165584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:54.844227Z","time spent":"321.263995ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:55.352435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.51396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-05-11T01:46:55.352531Z","caller":"traceutil/trace.go:171","msg":"trace[36076904] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29034; }","duration":"126.672063ms","start":"2026-05-11T01:46:55.225837Z","end":"2026-05-11T01:46:55.352509Z","steps":["trace[36076904] 'range keys from in-memory index tree' (duration: 126.258375ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:55.48349Z","caller":"traceutil/trace.go:171","msg":"trace[903955950] transaction","detail":"{read_only:false; response_revision:29035; number_of_response:1; }","duration":"125.844987ms","start":"2026-05-11T01:46:55.357605Z","end":"2026-05-11T01:46:55.48345Z","steps":["trace[903955950] 'process raft request' (duration: 125.626773ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:55.762054Z","caller":"traceutil/trace.go:171","msg":"trace[1374301046] transaction","detail":"{read_only:false; response_revision:29036; number_of_response:1; }","duration":"274.316326ms","start":"2026-05-11T01:46:55.487699Z","end":"2026-05-11T01:46:55.762016Z","steps":["trace[1374301046] 'process raft request' (duration: 263.162237ms)","trace[1374301046] 'compare' (duration: 10.820013ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:55.773745Z","caller":"traceutil/trace.go:171","msg":"trace[1576160679] linearizableReadLoop","detail":"{readStateIndex:30367; appliedIndex:30365; }","duration":"224.341382ms","start":"2026-05-11T01:46:55.549372Z","end":"2026-05-11T01:46:55.773713Z","steps":["trace[1576160679] 'read index received' (duration: 201.678957ms)","trace[1576160679] 'applied index is now lower than readState.Index' (duration: 22.661205ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:55.774015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.612416ms","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-11T01:46:55.774021Z","caller":"traceutil/trace.go:171","msg":"trace[1997297351] transaction","detail":"{read_only:false; response_revision:29037; number_of_response:1; }","duration":"285.313833ms","start":"2026-05-11T01:46:55.488685Z","end":"2026-05-11T01:46:55.773999Z","steps":["trace[1997297351] 'process raft request' (duration: 284.843364ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:55.774061Z","caller":"traceutil/trace.go:171","msg":"trace[1522533278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29037; }","duration":"224.722109ms","start":"2026-05-11T01:46:55.549325Z","end":"2026-05-11T01:46:55.774047Z","steps":["trace[1522533278] 'agreement among raft nodes before linearized reading' (duration: 224.511775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:56.011621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.86857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:56.011752Z","caller":"traceutil/trace.go:171","msg":"trace[1824233954] linearizableReadLoop","detail":"{readStateIndex:30368; appliedIndex:30367; }","duration":"154.185357ms","start":"2026-05-11T01:46:55.857549Z","end":"2026-05-11T01:46:56.011735Z","steps":["trace[1824233954] 'read index received' (duration: 46.106542ms)","trace[1824233954] 'applied index is now lower than readState.Index' (duration: 108.077565ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:56.011934Z","caller":"traceutil/trace.go:171","msg":"trace[1997998923] transaction","detail":"{read_only:false; response_revision:29038; number_of_response:1; }","duration":"232.949663ms","start":"2026-05-11T01:46:55.77892Z","end":"2026-05-11T01:46:56.01187Z","steps":["trace[1997998923] 'process raft request' (duration: 124.717445ms)","trace[1997998923] 'compare' (duration: 107.724997ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:56.01205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.441462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:56.012154Z","caller":"traceutil/trace.go:171","msg":"trace[722077824] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:29038; }","duration":"154.645546ms","start":"2026-05-11T01:46:55.857487Z","end":"2026-05-11T01:46:56.012133Z","steps":["trace[722077824] 'agreement among raft nodes before linearized reading' (duration: 154.375271ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:56.020432Z","caller":"traceutil/trace.go:171","msg":"trace[741768298] transaction","detail":"{read_only:false; response_revision:29039; number_of_response:1; }","duration":"159.472926ms","start":"2026-05-11T01:46:55.860938Z","end":"2026-05-11T01:46:56.020411Z","steps":["trace[741768298] 'process raft request' (duration: 159.236042ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:56.020509Z","caller":"traceutil/trace.go:171","msg":"trace[1494171203] transaction","detail":"{read_only:false; response_revision:29040; number_of_response:1; }","duration":"159.003668ms","start":"2026-05-11T01:46:55.861467Z","end":"2026-05-11T01:46:56.02047Z","steps":["trace[1494171203] 'process raft request' (duration: 158.878576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:56.020607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.009708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-05-11T01:46:56.020713Z","caller":"traceutil/trace.go:171","msg":"trace[270695710] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29040; }","duration":"159.13606ms","start":"2026-05-11T01:46:55.861553Z","end":"2026-05-11T01:46:56.020689Z","steps":["trace[270695710] 'agreement among raft nodes before linearized reading' (duration: 158.842275ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:01.291737Z","caller":"traceutil/trace.go:171","msg":"trace[1942064071] transaction","detail":"{read_only:false; response_revision:29087; number_of_response:1; }","duration":"102.920218ms","start":"2026-05-11T01:47:01.188787Z","end":"2026-05-11T01:47:01.291707Z","steps":["trace[1942064071] 'process raft request' (duration: 102.754725ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:01.872479Z","caller":"traceutil/trace.go:171","msg":"trace[142870568] transaction","detail":"{read_only:false; response_revision:29089; number_of_response:1; }","duration":"123.996011ms","start":"2026-05-11T01:47:01.748455Z","end":"2026-05-11T01:47:01.872451Z","steps":["trace[142870568] 'process raft request' (duration: 123.533313ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:02.176549Z","caller":"traceutil/trace.go:171","msg":"trace[1336869776] transaction","detail":"{read_only:false; response_revision:29092; number_of_response:1; }","duration":"135.867475ms","start":"2026-05-11T01:47:02.040643Z","end":"2026-05-11T01:47:02.176511Z","steps":["trace[1336869776] 'process raft request' (duration: 135.679341ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:02.362364Z","caller":"traceutil/trace.go:171","msg":"trace[890588486] linearizableReadLoop","detail":"{readStateIndex:30427; appliedIndex:30424; }","duration":"272.563693ms","start":"2026-05-11T01:47:02.089738Z","end":"2026-05-11T01:47:02.362301Z","steps":["trace[890588486] 'read index received' (duration: 86.601641ms)","trace[890588486] 'applied index is now lower than readState.Index' (duration: 185.960482ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:47:02.36259Z","caller":"traceutil/trace.go:171","msg":"trace[2127790373] transaction","detail":"{read_only:false; response_revision:29094; number_of_response:1; }","duration":"318.979553ms","start":"2026-05-11T01:47:02.043557Z","end":"2026-05-11T01:47:02.362537Z","steps":["trace[2127790373] 'process raft request' (duration: 318.587375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:47:02.362723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.971402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-05-11T01:47:02.362781Z","caller":"traceutil/trace.go:171","msg":"trace[408161333] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29094; }","duration":"273.099134ms","start":"2026-05-11T01:47:02.089664Z","end":"2026-05-11T01:47:02.362763Z","steps":["trace[408161333] 'agreement among raft nodes before linearized reading' (duration: 272.853499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:47:02.362851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:47:02.043523Z","time spent":"319.182227ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:47:02.363006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.353666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-05-11T01:47:02.36306Z","caller":"traceutil/trace.go:171","msg":"trace[1124978754] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29094; }","duration":"165.472028ms","start":"2026-05-11T01:47:02.197574Z","end":"2026-05-11T01:47:02.363046Z","steps":["trace[1124978754] 'agreement among raft nodes before linearized reading' (duration: 165.241594ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:02.363288Z","caller":"traceutil/trace.go:171","msg":"trace[446995370] transaction","detail":"{read_only:false; response_revision:29093; number_of_response:1; }","duration":"319.849959ms","start":"2026-05-11T01:47:02.043407Z","end":"2026-05-11T01:47:02.363257Z","steps":["trace[446995370] 'process raft request' (duration: 299.694232ms)","trace[446995370] 'compare' (duration: 18.18372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:47:02.363497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:47:02.043398Z","time spent":"319.981951ms","remote":"127.0.0.1:46594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:47:02.624262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.987224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:47:02.624567Z","caller":"traceutil/trace.go:171","msg":"trace[1310588334] transaction","detail":"{read_only:false; response_revision:29095; number_of_response:1; }","duration":"252.204573ms","start":"2026-05-11T01:47:02.372327Z","end":"2026-05-11T01:47:02.624532Z","steps":["trace[1310588334] 'process raft request' (duration: 121.822911ms)","trace[1310588334] 'compare' (duration: 129.767ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:47:02.624692Z","caller":"traceutil/trace.go:171","msg":"trace[1907217514] linearizableReadLoop","detail":"{readStateIndex:30429; appliedIndex:30427; }","duration":"240.596466ms","start":"2026-05-11T01:47:02.38403Z","end":"2026-05-11T01:47:02.624626Z","steps":["trace[1907217514] 'read index received' (duration: 110.074981ms)","trace[1907217514] 'applied index is now lower than readState.Index' (duration: 130.519714ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:47:02.624784Z","caller":"traceutil/trace.go:171","msg":"trace[277354839] transaction","detail":"{read_only:false; response_revision:29096; number_of_response:1; }","duration":"252.280804ms","start":"2026-05-11T01:47:02.372433Z","end":"2026-05-11T01:47:02.624713Z","steps":["trace[277354839] 'process raft request' (duration: 252.102221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:47:02.624994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.931891ms","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-11T01:47:02.625113Z","caller":"traceutil/trace.go:171","msg":"trace[845778195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29096; }","duration":"241.073334ms","start":"2026-05-11T01:47:02.38401Z","end":"2026-05-11T01:47:02.625083Z","steps":["trace[845778195] 'agreement among raft nodes before linearized reading' (duration: 240.736988ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:02.80388Z","caller":"traceutil/trace.go:171","msg":"trace[540066923] transaction","detail":"{read_only:false; response_revision:29098; number_of_response:1; }","duration":"170.270388ms","start":"2026-05-11T01:47:02.633572Z","end":"2026-05-11T01:47:02.803843Z","steps":["trace[540066923] 'process raft request' (duration: 159.200611ms)","trace[540066923] 'compare' (duration: 10.759521ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:47:02.803935Z","caller":"traceutil/trace.go:171","msg":"trace[1634808021] transaction","detail":"{read_only:false; response_revision:29099; number_of_response:1; }","duration":"169.968094ms","start":"2026-05-11T01:47:02.633891Z","end":"2026-05-11T01:47:02.803859Z","steps":["trace[1634808021] 'process raft request' (duration: 169.851732ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:02.947349Z","caller":"traceutil/trace.go:171","msg":"trace[545839497] transaction","detail":"{read_only:false; response_revision:29102; number_of_response:1; }","duration":"124.664854ms","start":"2026-05-11T01:47:02.822645Z","end":"2026-05-11T01:47:02.94731Z","steps":["trace[545839497] 'process raft request' (duration: 99.740457ms)","trace[545839497] 'compare' (duration: 24.749104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:47:03.091488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.488281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:47:03.091494Z","caller":"traceutil/trace.go:171","msg":"trace[2064063454] linearizableReadLoop","detail":"{readStateIndex:30436; appliedIndex:30435; }","duration":"147.38891ms","start":"2026-05-11T01:47:02.943983Z","end":"2026-05-11T01:47:03.091372Z","steps":["trace[2064063454] 'read index received' (duration: 146.967182ms)","trace[2064063454] 'applied index is now lower than readState.Index' (duration: 420.498µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:47:03.091514Z","caller":"traceutil/trace.go:171","msg":"trace[5105675] transaction","detail":"{read_only:false; response_revision:29103; number_of_response:1; }","duration":"218.43015ms","start":"2026-05-11T01:47:02.873052Z","end":"2026-05-11T01:47:03.091483Z","steps":["trace[5105675] 'process raft request' (duration: 217.911ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:03.091566Z","caller":"traceutil/trace.go:171","msg":"trace[135157991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29103; }","duration":"147.658924ms","start":"2026-05-11T01:47:02.94389Z","end":"2026-05-11T01:47:03.091549Z","steps":["trace[135157991] 'agreement among raft nodes before linearized reading' (duration: 147.491321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:47:03.09178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.324907ms","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-11T01:47:03.09184Z","caller":"traceutil/trace.go:171","msg":"trace[884471671] 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:29103; }","duration":"124.470101ms","start":"2026-05-11T01:47:02.967353Z","end":"2026-05-11T01:47:03.091823Z","steps":["trace[884471671] 'agreement among raft nodes before linearized reading' (duration: 124.297498ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:08.366885Z","caller":"traceutil/trace.go:171","msg":"trace[271632566] transaction","detail":"{read_only:false; response_revision:29154; number_of_response:1; }","duration":"142.664671ms","start":"2026-05-11T01:47:08.22419Z","end":"2026-05-11T01:47:08.366855Z","steps":["trace[271632566] 'process raft request' (duration: 142.491228ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:08.590469Z","caller":"traceutil/trace.go:171","msg":"trace[1879984338] transaction","detail":"{read_only:false; number_of_response:0; response_revision:29154; }","duration":"214.062868ms","start":"2026-05-11T01:47:08.376377Z","end":"2026-05-11T01:47:08.59044Z","steps":["trace[1879984338] 'process raft request' (duration: 213.933465ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:08.647949Z","caller":"traceutil/trace.go:171","msg":"trace[1549801860] transaction","detail":"{read_only:false; response_revision:29155; number_of_response:1; }","duration":"262.944303ms","start":"2026-05-11T01:47:08.384958Z","end":"2026-05-11T01:47:08.647903Z","steps":["trace[1549801860] 'process raft request' (duration: 262.639937ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:08.648008Z","caller":"traceutil/trace.go:171","msg":"trace[1343326643] transaction","detail":"{read_only:false; response_revision:29157; number_of_response:1; }","duration":"214.186041ms","start":"2026-05-11T01:47:08.433791Z","end":"2026-05-11T01:47:08.647977Z","steps":["trace[1343326643] 'process raft request' (duration: 214.032358ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:08.647971Z","caller":"traceutil/trace.go:171","msg":"trace[1265817115] transaction","detail":"{read_only:false; response_revision:29156; number_of_response:1; }","duration":"261.575438ms","start":"2026-05-11T01:47:08.386348Z","end":"2026-05-11T01:47:08.647923Z","steps":["trace[1265817115] 'process raft request' (duration: 261.431025ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:47:08.820408Z","caller":"traceutil/trace.go:171","msg":"trace[408732794] linearizableReadLoop","detail":"{readStateIndex:30494; appliedIndex:30493; }","duration":"158.659741ms","start":"2026-05-11T01:47:08.661721Z","end":"2026-05-11T01:47:08.82038Z","steps":["trace[408732794] 'read index received' (duration: 121.668138ms)","trace[408732794] 'applied index is now lower than readState.Index' (duration: 36.990473ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:47:08.820492Z","caller":"traceutil/trace.go:171","msg":"trace[529170375] transaction","detail":"{read_only:false; response_revision:29158; number_of_response:1; }","duration":"160.468445ms","start":"2026-05-11T01:47:08.659979Z","end":"2026-05-11T01:47:08.820447Z","steps":["trace[529170375] 'process raft request' (duration: 123.431641ms)","trace[529170375] 'compare' (duration: 36.84709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:47:08.820688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.939476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:47:08.820738Z","caller":"traceutil/trace.go:171","msg":"trace[2095163658] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:29158; }","duration":"159.072339ms","start":"2026-05-11T01:47:08.661652Z","end":"2026-05-11T01:47:08.820724Z","steps":["trace[2095163658] 'agreement among raft nodes before linearized reading' (duration: 158.880245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:47:08.820894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.147499ms","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-11T01:47:08.82105Z","caller":"traceutil/trace.go:171","msg":"trace[1745443793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29158; }","duration":"136.355513ms","start":"2026-05-11T01:47:08.684664Z","end":"2026-05-11T01:47:08.821019Z","steps":["trace[1745443793] 'agreement among raft nodes before linearized reading' (duration: 135.939706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:47:08.820975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.514653ms","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-11T01:47:08.821202Z","caller":"traceutil/trace.go:171","msg":"trace[476819032] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:29158; }","duration":"131.808268ms","start":"2026-05-11T01:47:08.689367Z","end":"2026-05-11T01:47:08.821176Z","steps":["trace[476819032] 'agreement among raft nodes before linearized reading' (duration: 131.262988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:47:08.822871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.172539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T01:47:08.822963Z","caller":"traceutil/trace.go:171","msg":"trace[420502747] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:29158; }","duration":"114.322941ms","start":"2026-05-11T01:47:08.708622Z","end":"2026-05-11T01:47:08.822944Z","steps":["trace[420502747] 'agreement among raft nodes before linearized reading' (duration: 112.012727ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:48:58.416395Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27063} {"level":"info","ts":"2026-05-11T01:48:58.570476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27063,"took":"148.460557ms","hash":330266845,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":36450304,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-11T01:48:58.570541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":330266845,"revision":27063,"compact-revision":23373} {"level":"info","ts":"2026-05-11T01:50:03.460786Z","caller":"traceutil/trace.go:171","msg":"trace[46343972] transaction","detail":"{read_only:false; response_revision:31211; number_of_response:1; }","duration":"116.540023ms","start":"2026-05-11T01:50:03.344215Z","end":"2026-05-11T01:50:03.460755Z","steps":["trace[46343972] 'process raft request' (duration: 32.526146ms)","trace[46343972] 'compare' (duration: 83.484918ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:50:03.461335Z","caller":"traceutil/trace.go:171","msg":"trace[1874031223] transaction","detail":"{read_only:false; response_revision:31212; number_of_response:1; }","duration":"115.187409ms","start":"2026-05-11T01:50:03.346135Z","end":"2026-05-11T01:50:03.461323Z","steps":["trace[1874031223] 'process raft request' (duration: 114.566026ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:53:58.4258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30416} {"level":"info","ts":"2026-05-11T01:53:58.575685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30416,"took":"143.990903ms","hash":518537959,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":36610048,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-11T01:53:58.57577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":518537959,"revision":30416,"compact-revision":27063} {"level":"info","ts":"2026-05-11T01:58:58.433145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33426} {"level":"info","ts":"2026-05-11T01:58:58.573001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33426,"took":"134.084994ms","hash":3488372768,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":34250752,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T01:58:58.573061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3488372768,"revision":33426,"compact-revision":30416} {"level":"info","ts":"2026-05-11T02:02:49.096334Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T02:02:49.100967Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T02:02:49.101055Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-11T02:03:58.439145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36097} {"level":"info","ts":"2026-05-11T02:03:58.567329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36097,"took":"123.552587ms","hash":1252253302,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":34627584,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-11T02:03:58.567399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1252253302,"revision":36097,"compact-revision":33426} {"level":"info","ts":"2026-05-11T02:08:58.446398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38875} {"level":"info","ts":"2026-05-11T02:08:58.598574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38875,"took":"148.10049ms","hash":267558846,"current-db-size-bytes":61726720,"current-db-size":"62 MB","current-db-size-in-use-bytes":34996224,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-11T02:08:58.598638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":267558846,"revision":38875,"compact-revision":36097} {"level":"info","ts":"2026-05-11T02:13:58.454437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41653} {"level":"info","ts":"2026-05-11T02:13:58.604312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41653,"took":"145.865558ms","hash":2500047690,"current-db-size-bytes":62226432,"current-db-size":"62 MB","current-db-size-in-use-bytes":33976320,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T02:13:58.604361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2500047690,"revision":41653,"compact-revision":38875}