{"level":"warn","ts":"2026-05-11T00:58:46.316786Z","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:46.316869Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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:46.316929Z","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:46.316937Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-11T00:58:46.316954Z","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:46.317571Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-05-11T00:58:46.317643Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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:46.322691Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.838402ms"} {"level":"info","ts":"2026-05-11T00:58:46.328767Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-11T00:58:46.328876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:58:46.328919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-11T00:58:46.328939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:58:46.328958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-11T00:58:46.329017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-11T00:58:46.334319Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:58:46.336078Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:58:46.337292Z","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:46.340175Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:58:46.340695Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:58:46.340695Z","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:46.340948Z","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:46.341125Z","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:46.344995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-11T00:58:46.34518Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-11T00:58:46.348234Z","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:46.348669Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-11T00:58:46.348696Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:58:46.348737Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-11T00:58:46.348775Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:58:46.930003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:58:46.930097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:58:46.930147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-11T00:58:46.930172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:58:46.930184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-11T00:58:46.930205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-11T00:58:46.930222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-11T00:58:46.931477Z","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:46.932585Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:58:46.932602Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:58:46.932826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:58:46.93335Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:58:46.933457Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:58:46.933496Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:58:46.936281Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-11T00:58:46.936599Z","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:46.936758Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:58:46.936796Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:59:25.392973Z","caller":"traceutil/trace.go:171","msg":"trace[1734506383] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"270.427154ms","start":"2026-05-11T00:59:25.12252Z","end":"2026-05-11T00:59:25.392947Z","steps":["trace[1734506383] 'process raft request' (duration: 270.192019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:43.898797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.248373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:43.898936Z","caller":"traceutil/trace.go:171","msg":"trace[80759611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"121.450188ms","start":"2026-05-11T00:59:43.77744Z","end":"2026-05-11T00:59:43.89889Z","steps":["trace[80759611] 'range keys from in-memory index tree' (duration: 121.173772ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:14.253342Z","caller":"traceutil/trace.go:171","msg":"trace[1064073982] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"152.039035ms","start":"2026-05-11T01:00:14.101284Z","end":"2026-05-11T01:00:14.253323Z","steps":["trace[1064073982] 'process raft request' (duration: 151.908812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:58.472529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.402937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9033"} {"level":"info","ts":"2026-05-11T01:00:58.472621Z","caller":"traceutil/trace.go:171","msg":"trace[29074790] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1840; }","duration":"194.545132ms","start":"2026-05-11T01:00:58.278058Z","end":"2026-05-11T01:00:58.472603Z","steps":["trace[29074790] 'range keys from in-memory index tree' (duration: 194.214494ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:58.573023Z","caller":"traceutil/trace.go:171","msg":"trace[810981643] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"114.802087ms","start":"2026-05-11T01:00:58.458193Z","end":"2026-05-11T01:00:58.572995Z","steps":["trace[810981643] 'process raft request' (duration: 114.637354ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:58.601658Z","caller":"traceutil/trace.go:171","msg":"trace[1271163772] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"117.285921ms","start":"2026-05-11T01:00:58.484349Z","end":"2026-05-11T01:00:58.601635Z","steps":["trace[1271163772] 'process raft request' (duration: 117.085206ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:01:44.950625Z","caller":"traceutil/trace.go:171","msg":"trace[1731951342] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"118.410614ms","start":"2026-05-11T01:01:44.832142Z","end":"2026-05-11T01:01:44.950552Z","steps":["trace[1731951342] 'process raft request' (duration: 64.78583ms)","trace[1731951342] 'compare' (duration: 53.498922ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:02:26.018002Z","caller":"traceutil/trace.go:171","msg":"trace[402428127] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"204.523295ms","start":"2026-05-11T01:02:25.813445Z","end":"2026-05-11T01:02:26.017968Z","steps":["trace[402428127] 'process raft request' (duration: 204.367261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:26.02002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.41189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T01:02:26.020098Z","caller":"traceutil/trace.go:171","msg":"trace[1667028808] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2534; }","duration":"100.529453ms","start":"2026-05-11T01:02:25.919551Z","end":"2026-05-11T01:02:26.02008Z","steps":["trace[1667028808] 'agreement among raft nodes before linearized reading' (duration: 100.38061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:57.055535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.739314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-11T01:02:57.055752Z","caller":"traceutil/trace.go:171","msg":"trace[1421473366] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2750; }","duration":"264.33107ms","start":"2026-05-11T01:02:56.791389Z","end":"2026-05-11T01:02:57.05572Z","steps":["trace[1421473366] 'process raft request' (duration: 110.86171ms)","trace[1421473366] 'compare' (duration: 152.699224ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:02:57.055882Z","caller":"traceutil/trace.go:171","msg":"trace[122720651] linearizableReadLoop","detail":"{readStateIndex:2854; appliedIndex:2853; }","duration":"261.592474ms","start":"2026-05-11T01:02:56.794253Z","end":"2026-05-11T01:02:57.055846Z","steps":["trace[122720651] 'read index received' (duration: 107.926381ms)","trace[122720651] 'applied index is now lower than readState.Index' (duration: 153.634683ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:02:57.056073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.799578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T01:02:57.05613Z","caller":"traceutil/trace.go:171","msg":"trace[2009069560] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2751; }","duration":"261.9108ms","start":"2026-05-11T01:02:56.794209Z","end":"2026-05-11T01:02:57.05612Z","steps":["trace[2009069560] 'agreement among raft nodes before linearized reading' (duration: 261.796448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:57.056281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.364965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-05-11T01:02:57.05633Z","caller":"traceutil/trace.go:171","msg":"trace[70829115] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2751; }","duration":"236.426166ms","start":"2026-05-11T01:02:56.819893Z","end":"2026-05-11T01:02:57.056319Z","steps":["trace[70829115] 'agreement among raft nodes before linearized reading' (duration: 236.260253ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:03:02.388077Z","caller":"traceutil/trace.go:171","msg":"trace[2005695324] linearizableReadLoop","detail":"{readStateIndex:2886; appliedIndex:2885; }","duration":"190.974696ms","start":"2026-05-11T01:03:02.19708Z","end":"2026-05-11T01:03:02.388055Z","steps":["trace[2005695324] 'read index received' (duration: 190.790902ms)","trace[2005695324] 'applied index is now lower than readState.Index' (duration: 182.764µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:03:02.388171Z","caller":"traceutil/trace.go:171","msg":"trace[922531211] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"221.207587ms","start":"2026-05-11T01:03:02.166938Z","end":"2026-05-11T01:03:02.388146Z","steps":["trace[922531211] 'process raft request' (duration: 220.968882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:03:02.388305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.212132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T01:03:02.388342Z","caller":"traceutil/trace.go:171","msg":"trace[1633934271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2781; }","duration":"191.301793ms","start":"2026-05-11T01:03:02.197029Z","end":"2026-05-11T01:03:02.388331Z","steps":["trace[1633934271] 'agreement among raft nodes before linearized reading' (duration: 191.15139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:03:02.388574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.517271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-11T01:03:02.388663Z","caller":"traceutil/trace.go:171","msg":"trace[2067176159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2781; }","duration":"102.662433ms","start":"2026-05-11T01:03:02.285983Z","end":"2026-05-11T01:03:02.388646Z","steps":["trace[2067176159] 'agreement among raft nodes before linearized reading' (duration: 102.300436ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:03:02.492614Z","caller":"traceutil/trace.go:171","msg":"trace[1339164310] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"100.01823ms","start":"2026-05-11T01:03:02.392565Z","end":"2026-05-11T01:03:02.492583Z","steps":["trace[1339164310] 'process raft request' (duration: 84.134929ms)","trace[1339164310] 'compare' (duration: 15.399441ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:04:04.192078Z","caller":"traceutil/trace.go:171","msg":"trace[329697508] linearizableReadLoop","detail":"{readStateIndex:3507; appliedIndex:3506; }","duration":"159.984123ms","start":"2026-05-11T01:04:04.032069Z","end":"2026-05-11T01:04:04.192053Z","steps":["trace[329697508] 'read index received' (duration: 118.455494ms)","trace[329697508] 'applied index is now lower than readState.Index' (duration: 41.527569ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:04:04.192153Z","caller":"traceutil/trace.go:171","msg":"trace[1426458683] transaction","detail":"{read_only:false; response_revision:3374; number_of_response:1; }","duration":"188.039132ms","start":"2026-05-11T01:04:04.004074Z","end":"2026-05-11T01:04:04.192114Z","steps":["trace[1426458683] 'process raft request' (duration: 146.509353ms)","trace[1426458683] 'compare' (duration: 41.313565ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:04:04.19228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.180006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T01:04:04.192354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.461114ms","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:04:04.192357Z","caller":"traceutil/trace.go:171","msg":"trace[1855578593] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3374; }","duration":"160.320889ms","start":"2026-05-11T01:04:04.032017Z","end":"2026-05-11T01:04:04.192338Z","steps":["trace[1855578593] 'agreement among raft nodes before linearized reading' (duration: 160.172286ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:04:04.1924Z","caller":"traceutil/trace.go:171","msg":"trace[1278989246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3374; }","duration":"113.553665ms","start":"2026-05-11T01:04:04.078836Z","end":"2026-05-11T01:04:04.19239Z","steps":["trace[1278989246] 'agreement among raft nodes before linearized reading' (duration: 113.372352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:16.958747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.534585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:05:16.958853Z","caller":"traceutil/trace.go:171","msg":"trace[932225713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4523; }","duration":"180.740979ms","start":"2026-05-11T01:05:16.77809Z","end":"2026-05-11T01:05:16.958831Z","steps":["trace[932225713] 'range keys from in-memory index tree' (duration: 180.394213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:16.958992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.132625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8aa5750-9c1d-4f4d-9a55-2625d98ff388\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-11T01:05:16.959082Z","caller":"traceutil/trace.go:171","msg":"trace[1749240362] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8aa5750-9c1d-4f4d-9a55-2625d98ff388; range_end:; response_count:1; response_revision:4523; }","duration":"126.264468ms","start":"2026-05-11T01:05:16.832793Z","end":"2026-05-11T01:05:16.959058Z","steps":["trace[1749240362] 'range keys from in-memory index tree' (duration: 125.941921ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:16.999841Z","caller":"traceutil/trace.go:171","msg":"trace[1399195128] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"126.976181ms","start":"2026-05-11T01:05:16.872804Z","end":"2026-05-11T01:05:16.99978Z","steps":["trace[1399195128] 'process raft request' (duration: 126.707706ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:22.79134Z","caller":"traceutil/trace.go:171","msg":"trace[227149115] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"118.034333ms","start":"2026-05-11T01:05:22.673275Z","end":"2026-05-11T01:05:22.791309Z","steps":["trace[227149115] 'process raft request' (duration: 116.626095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:22.791449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.850999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b7t5z\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-11T01:05:22.791525Z","caller":"traceutil/trace.go:171","msg":"trace[1032494800] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b7t5z; range_end:; response_count:1; response_revision:4659; }","duration":"101.965662ms","start":"2026-05-11T01:05:22.689542Z","end":"2026-05-11T01:05:22.791508Z","steps":["trace[1032494800] 'agreement among raft nodes before linearized reading' (duration: 101.746158ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:30.894031Z","caller":"traceutil/trace.go:171","msg":"trace[2136226852] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"115.673045ms","start":"2026-05-11T01:05:30.778326Z","end":"2026-05-11T01:05:30.893999Z","steps":["trace[2136226852] 'read index received' (duration: 68.234186ms)","trace[2136226852] 'applied index is now lower than readState.Index' (duration: 47.437499ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:30.894192Z","caller":"traceutil/trace.go:171","msg":"trace[137356064] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"144.529953ms","start":"2026-05-11T01:05:30.74964Z","end":"2026-05-11T01:05:30.89417Z","steps":["trace[137356064] 'process raft request' (duration: 96.942821ms)","trace[137356064] 'compare' (duration: 47.298117ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:05:30.894379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.035352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:05:30.894417Z","caller":"traceutil/trace.go:171","msg":"trace[888312268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4805; }","duration":"116.128634ms","start":"2026-05-11T01:05:30.778278Z","end":"2026-05-11T01:05:30.894407Z","steps":["trace[888312268] 'agreement among raft nodes before linearized reading' (duration: 116.048213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:31.380926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.885973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T01:05:31.381029Z","caller":"traceutil/trace.go:171","msg":"trace[992135827] linearizableReadLoop","detail":"{readStateIndex:5019; appliedIndex:5018; }","duration":"183.603976ms","start":"2026-05-11T01:05:31.19741Z","end":"2026-05-11T01:05:31.381014Z","steps":["trace[992135827] 'read index received' (duration: 58.361µs)","trace[992135827] 'applied index is now lower than readState.Index' (duration: 183.544385ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:31.381125Z","caller":"traceutil/trace.go:171","msg":"trace[1217158560] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"262.933384ms","start":"2026-05-11T01:05:31.118156Z","end":"2026-05-11T01:05:31.381089Z","steps":["trace[1217158560] 'process raft request' (duration: 33.666054ms)","trace[1217158560] 'compare' (duration: 228.73907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:05:31.381219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.8012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11491"} {"level":"info","ts":"2026-05-11T01:05:31.381243Z","caller":"traceutil/trace.go:171","msg":"trace[809652879] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4820; }","duration":"183.883432ms","start":"2026-05-11T01:05:31.197352Z","end":"2026-05-11T01:05:31.381236Z","steps":["trace[809652879] 'agreement among raft nodes before linearized reading' (duration: 183.740549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:31.381494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.8886ms","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:05:31.381518Z","caller":"traceutil/trace.go:171","msg":"trace[1823862101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4820; }","duration":"142.966242ms","start":"2026-05-11T01:05:31.238546Z","end":"2026-05-11T01:05:31.381512Z","steps":["trace[1823862101] 'agreement among raft nodes before linearized reading' (duration: 142.90048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:31.381715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.114025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-11T01:05:31.381823Z","caller":"traceutil/trace.go:171","msg":"trace[429428417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4820; }","duration":"139.288368ms","start":"2026-05-11T01:05:31.242519Z","end":"2026-05-11T01:05:31.381807Z","steps":["trace[429428417] 'agreement among raft nodes before linearized reading' (duration: 138.980602ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:38.282625Z","caller":"traceutil/trace.go:171","msg":"trace[1564922289] linearizableReadLoop","detail":"{readStateIndex:5191; appliedIndex:5189; }","duration":"194.2684ms","start":"2026-05-11T01:05:38.088326Z","end":"2026-05-11T01:05:38.282594Z","steps":["trace[1564922289] 'read index received' (duration: 16.853727ms)","trace[1564922289] 'applied index is now lower than readState.Index' (duration: 177.413853ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:38.282727Z","caller":"traceutil/trace.go:171","msg":"trace[964525281] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"208.534535ms","start":"2026-05-11T01:05:38.074184Z","end":"2026-05-11T01:05:38.282719Z","steps":["trace[964525281] 'process raft request' (duration: 137.448661ms)","trace[964525281] 'compare' (duration: 70.868629ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:05:38.28284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.514465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T01:05:38.282865Z","caller":"traceutil/trace.go:171","msg":"trace[883144192] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4986; }","duration":"194.580286ms","start":"2026-05-11T01:05:38.088277Z","end":"2026-05-11T01:05:38.282857Z","steps":["trace[883144192] 'agreement among raft nodes before linearized reading' (duration: 194.519555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:38.283048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.780889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-11T01:05:38.283067Z","caller":"traceutil/trace.go:171","msg":"trace[655263325] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4986; }","duration":"186.838531ms","start":"2026-05-11T01:05:38.096224Z","end":"2026-05-11T01:05:38.283062Z","steps":["trace[655263325] 'agreement among raft nodes before linearized reading' (duration: 186.731109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:38.283292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.034045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd7954a0-0624-4eea-bfb3-611998ada0fc\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-11T01:05:38.283396Z","caller":"traceutil/trace.go:171","msg":"trace[888638927] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd7954a0-0624-4eea-bfb3-611998ada0fc; range_end:; response_count:1; response_revision:4986; }","duration":"178.162838ms","start":"2026-05-11T01:05:38.105209Z","end":"2026-05-11T01:05:38.283372Z","steps":["trace[888638927] 'agreement among raft nodes before linearized reading' (duration: 177.935173ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:38.421049Z","caller":"traceutil/trace.go:171","msg":"trace[1935465247] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"131.178756ms","start":"2026-05-11T01:05:38.289835Z","end":"2026-05-11T01:05:38.421014Z","steps":["trace[1935465247] 'process raft request' (duration: 94.584133ms)","trace[1935465247] 'compare' (duration: 36.481591ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:38.439356Z","caller":"traceutil/trace.go:171","msg":"trace[1890774850] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"145.87592ms","start":"2026-05-11T01:05:38.293463Z","end":"2026-05-11T01:05:38.439339Z","steps":["trace[1890774850] 'process raft request' (duration: 145.834849ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:38.439785Z","caller":"traceutil/trace.go:171","msg":"trace[385643078] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"148.944743ms","start":"2026-05-11T01:05:38.290828Z","end":"2026-05-11T01:05:38.439773Z","steps":["trace[385643078] 'process raft request' (duration: 148.387281ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:38.439791Z","caller":"traceutil/trace.go:171","msg":"trace[1789338309] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"149.564355ms","start":"2026-05-11T01:05:38.290211Z","end":"2026-05-11T01:05:38.439776Z","steps":["trace[1789338309] 'process raft request' (duration: 148.902621ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:38.439842Z","caller":"traceutil/trace.go:171","msg":"trace[1806676285] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"146.383851ms","start":"2026-05-11T01:05:38.29342Z","end":"2026-05-11T01:05:38.439804Z","steps":["trace[1806676285] 'process raft request' (duration: 145.83561ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:48.068193Z","caller":"traceutil/trace.go:171","msg":"trace[40681766] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"113.963552ms","start":"2026-05-11T01:05:47.954208Z","end":"2026-05-11T01:05:48.068171Z","steps":["trace[40681766] 'process raft request' (duration: 113.88565ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:48.068221Z","caller":"traceutil/trace.go:171","msg":"trace[2011060698] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"114.182826ms","start":"2026-05-11T01:05:47.954009Z","end":"2026-05-11T01:05:48.068192Z","steps":["trace[2011060698] 'process raft request' (duration: 93.815378ms)","trace[2011060698] 'compare' (duration: 20.103703ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:48.068325Z","caller":"traceutil/trace.go:171","msg":"trace[870662800] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"113.994982ms","start":"2026-05-11T01:05:47.954303Z","end":"2026-05-11T01:05:48.068298Z","steps":["trace[870662800] 'process raft request' (duration: 113.835299ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:48.097294Z","caller":"traceutil/trace.go:171","msg":"trace[1583006556] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5321; }","duration":"105.38713ms","start":"2026-05-11T01:05:47.991885Z","end":"2026-05-11T01:05:48.097272Z","steps":["trace[1583006556] 'read index received' (duration: 55.885559ms)","trace[1583006556] 'applied index is now lower than readState.Index' (duration: 49.500501ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:48.097367Z","caller":"traceutil/trace.go:171","msg":"trace[1916531734] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"133.094874ms","start":"2026-05-11T01:05:47.964244Z","end":"2026-05-11T01:05:48.097339Z","steps":["trace[1916531734] 'process raft request' (duration: 132.91864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:48.097495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.590554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-11T01:05:48.097534Z","caller":"traceutil/trace.go:171","msg":"trace[997729824] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5115; }","duration":"105.645055ms","start":"2026-05-11T01:05:47.991879Z","end":"2026-05-11T01:05:48.097524Z","steps":["trace[997729824] 'agreement among raft nodes before linearized reading' (duration: 105.505452ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:48.219063Z","caller":"traceutil/trace.go:171","msg":"trace[2000154533] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"115.571434ms","start":"2026-05-11T01:05:48.103462Z","end":"2026-05-11T01:05:48.219033Z","steps":["trace[2000154533] 'process raft request' (duration: 66.339008ms)","trace[2000154533] 'compare' (duration: 49.048152ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:48.219518Z","caller":"traceutil/trace.go:171","msg":"trace[765767601] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5325; }","duration":"115.163375ms","start":"2026-05-11T01:05:48.10433Z","end":"2026-05-11T01:05:48.219493Z","steps":["trace[765767601] 'read index received' (duration: 21.386447ms)","trace[765767601] 'applied index is now lower than readState.Index' (duration: 93.775628ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:48.219518Z","caller":"traceutil/trace.go:171","msg":"trace[761236902] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"115.783008ms","start":"2026-05-11T01:05:48.103698Z","end":"2026-05-11T01:05:48.219481Z","steps":["trace[761236902] 'process raft request' (duration: 115.292898ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:48.219653Z","caller":"traceutil/trace.go:171","msg":"trace[30638844] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"104.39325ms","start":"2026-05-11T01:05:48.11519Z","end":"2026-05-11T01:05:48.219583Z","steps":["trace[30638844] 'process raft request' (duration: 104.198436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:48.219789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.43108ms","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:05:48.219845Z","caller":"traceutil/trace.go:171","msg":"trace[476465665] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5121; }","duration":"115.530502ms","start":"2026-05-11T01:05:48.104302Z","end":"2026-05-11T01:05:48.219833Z","steps":["trace[476465665] 'agreement among raft nodes before linearized reading' (duration: 115.295557ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:48.220143Z","caller":"traceutil/trace.go:171","msg":"trace[411145308] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"115.550643ms","start":"2026-05-11T01:05:48.104569Z","end":"2026-05-11T01:05:48.220119Z","steps":["trace[411145308] 'process raft request' (duration: 114.591744ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:48.22016Z","caller":"traceutil/trace.go:171","msg":"trace[559930253] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"115.444531ms","start":"2026-05-11T01:05:48.104691Z","end":"2026-05-11T01:05:48.220135Z","steps":["trace[559930253] 'process raft request' (duration: 114.606764ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:08:46.965711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3257} {"level":"info","ts":"2026-05-11T01:08:47.056261Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3257,"took":"89.155973ms","hash":436117839,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":34131968,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T01:08:47.056336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":436117839,"revision":3257,"compact-revision":-1} {"level":"info","ts":"2026-05-11T01:11:34.408105Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002175.wal"} {"level":"info","ts":"2026-05-11T01:11:38.759133Z","caller":"traceutil/trace.go:171","msg":"trace[170361948] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"224.835863ms","start":"2026-05-11T01:11:38.534271Z","end":"2026-05-11T01:11:38.759106Z","steps":["trace[170361948] 'process raft request' (duration: 224.665919ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:50.742155Z","caller":"traceutil/trace.go:171","msg":"trace[9159316] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"218.047606ms","start":"2026-05-11T01:11:50.524074Z","end":"2026-05-11T01:11:50.742121Z","steps":["trace[9159316] 'process raft request' (duration: 217.858982ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:50.758178Z","caller":"traceutil/trace.go:171","msg":"trace[534501480] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"229.851764ms","start":"2026-05-11T01:11:50.528287Z","end":"2026-05-11T01:11:50.758139Z","steps":["trace[534501480] 'process raft request' (duration: 229.565278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:51.286128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.539355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b7t5z\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-11T01:11:51.286217Z","caller":"traceutil/trace.go:171","msg":"trace[1046417294] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b7t5z; range_end:; response_count:1; response_revision:8318; }","duration":"213.680818ms","start":"2026-05-11T01:11:51.072514Z","end":"2026-05-11T01:11:51.286195Z","steps":["trace[1046417294] 'range keys from in-memory index tree' (duration: 213.29514ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:51.28622Z","caller":"traceutil/trace.go:171","msg":"trace[1786928379] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"190.590294ms","start":"2026-05-11T01:11:51.095609Z","end":"2026-05-11T01:11:51.286199Z","steps":["trace[1786928379] 'process raft request' (duration: 184.784487ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:51.571993Z","caller":"traceutil/trace.go:171","msg":"trace[144900169] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"467.988623ms","start":"2026-05-11T01:11:51.103927Z","end":"2026-05-11T01:11:51.571916Z","steps":["trace[144900169] 'process raft request' (duration: 467.766388ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:51.571889Z","caller":"traceutil/trace.go:171","msg":"trace[965201449] linearizableReadLoop","detail":"{readStateIndex:8687; appliedIndex:8686; }","duration":"280.93251ms","start":"2026-05-11T01:11:51.290932Z","end":"2026-05-11T01:11:51.571864Z","steps":["trace[965201449] 'read index received' (duration: 280.663084ms)","trace[965201449] 'applied index is now lower than readState.Index' (duration: 268.426µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:11:51.572239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:11:51.103871Z","time spent":"468.241398ms","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:11:51.572345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.202327ms","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:11:51.572385Z","caller":"traceutil/trace.go:171","msg":"trace[2004088233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8320; }","duration":"200.310999ms","start":"2026-05-11T01:11:51.37206Z","end":"2026-05-11T01:11:51.572371Z","steps":["trace[2004088233] 'agreement among raft nodes before linearized reading' (duration: 200.117145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:51.572429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.656118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:11:51.572505Z","caller":"traceutil/trace.go:171","msg":"trace[1620011824] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:8320; }","duration":"139.764731ms","start":"2026-05-11T01:11:51.432723Z","end":"2026-05-11T01:11:51.572487Z","steps":["trace[1620011824] 'agreement among raft nodes before linearized reading' (duration: 139.636928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:51.572592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.633824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:203481"} {"level":"warn","ts":"2026-05-11T01:11:51.572675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.361329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T01:11:51.572712Z","caller":"traceutil/trace.go:171","msg":"trace[564202656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8320; }","duration":"198.4156ms","start":"2026-05-11T01:11:51.374284Z","end":"2026-05-11T01:11:51.572699Z","steps":["trace[564202656] 'agreement among raft nodes before linearized reading' (duration: 198.318198ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:51.572704Z","caller":"traceutil/trace.go:171","msg":"trace[148759114] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8320; }","duration":"281.788907ms","start":"2026-05-11T01:11:51.290891Z","end":"2026-05-11T01:11:51.57268Z","steps":["trace[148759114] 'agreement among raft nodes before linearized reading' (duration: 281.123794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:11:51.572852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.840059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-11T01:11:51.572891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.767488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T01:11:51.572898Z","caller":"traceutil/trace.go:171","msg":"trace[1919687530] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8320; }","duration":"107.948012ms","start":"2026-05-11T01:11:51.464939Z","end":"2026-05-11T01:11:51.572887Z","steps":["trace[1919687530] 'agreement among raft nodes before linearized reading' (duration: 107.771387ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:11:51.572922Z","caller":"traceutil/trace.go:171","msg":"trace[699575688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8320; }","duration":"198.854679ms","start":"2026-05-11T01:11:51.374058Z","end":"2026-05-11T01:11:51.572913Z","steps":["trace[699575688] 'agreement among raft nodes before linearized reading' (duration: 198.774397ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:19.372143Z","caller":"traceutil/trace.go:171","msg":"trace[1047532414] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"258.52108ms","start":"2026-05-11T01:12:19.113593Z","end":"2026-05-11T01:12:19.372114Z","steps":["trace[1047532414] 'process raft request' (duration: 258.315566ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:12:19.372125Z","caller":"traceutil/trace.go:171","msg":"trace[1646566225] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8934; }","duration":"255.833166ms","start":"2026-05-11T01:12:19.116264Z","end":"2026-05-11T01:12:19.372097Z","steps":["trace[1646566225] 'read index received' (duration: 255.633382ms)","trace[1646566225] 'applied index is now lower than readState.Index' (duration: 197.994µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:12:19.372364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.04006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-11T01:12:19.372423Z","caller":"traceutil/trace.go:171","msg":"trace[340605451] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8556; }","duration":"256.174143ms","start":"2026-05-11T01:12:19.116235Z","end":"2026-05-11T01:12:19.372409Z","steps":["trace[340605451] 'agreement among raft nodes before linearized reading' (duration: 255.949948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:12:19.372505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.294944ms","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:12:19.372578Z","caller":"traceutil/trace.go:171","msg":"trace[2129389683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8556; }","duration":"248.386087ms","start":"2026-05-11T01:12:19.124176Z","end":"2026-05-11T01:12:19.372562Z","steps":["trace[2129389683] 'agreement among raft nodes before linearized reading' (duration: 248.182633ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:13:46.975563Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6594} {"level":"info","ts":"2026-05-11T01:13:47.075738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6594,"took":"97.752235ms","hash":1743610065,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":26632192,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-11T01:13:47.075832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1743610065,"revision":6594,"compact-revision":3257} {"level":"info","ts":"2026-05-11T01:13:55.889534Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:13:55.894657Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T01:13:55.894768Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T01:17:20.659235Z","caller":"traceutil/trace.go:171","msg":"trace[842218597] transaction","detail":"{read_only:false; response_revision:11611; number_of_response:1; }","duration":"105.251077ms","start":"2026-05-11T01:17:20.553944Z","end":"2026-05-11T01:17:20.659195Z","steps":["trace[842218597] 'process raft request' (duration: 104.992522ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:17:21.000059Z","caller":"traceutil/trace.go:171","msg":"trace[2110227111] linearizableReadLoop","detail":"{readStateIndex:12133; appliedIndex:12132; }","duration":"223.281202ms","start":"2026-05-11T01:17:20.77675Z","end":"2026-05-11T01:17:21.000032Z","steps":["trace[2110227111] 'read index received' (duration: 223.080918ms)","trace[2110227111] 'applied index is now lower than readState.Index' (duration: 198.844µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:17:21.00018Z","caller":"traceutil/trace.go:171","msg":"trace[929992326] transaction","detail":"{read_only:false; response_revision:11613; number_of_response:1; }","duration":"252.219454ms","start":"2026-05-11T01:17:20.747901Z","end":"2026-05-11T01:17:21.00012Z","steps":["trace[929992326] 'process raft request' (duration: 251.941878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:17:21.000352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.079726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-11T01:17:21.000399Z","caller":"traceutil/trace.go:171","msg":"trace[1369743063] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11613; }","duration":"126.196218ms","start":"2026-05-11T01:17:20.874191Z","end":"2026-05-11T01:17:21.000387Z","steps":["trace[1369743063] 'agreement among raft nodes before linearized reading' (duration: 126.009024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:17:21.000385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.840081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-11T01:17:21.000463Z","caller":"traceutil/trace.go:171","msg":"trace[1957036845] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:11613; }","duration":"118.966024ms","start":"2026-05-11T01:17:20.881476Z","end":"2026-05-11T01:17:21.000442Z","steps":["trace[1957036845] 'agreement among raft nodes before linearized reading' (duration: 118.76183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:17:21.00051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.742681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:17:21.000596Z","caller":"traceutil/trace.go:171","msg":"trace[1682449879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11613; }","duration":"223.868394ms","start":"2026-05-11T01:17:20.776705Z","end":"2026-05-11T01:17:21.000574Z","steps":["trace[1682449879] 'agreement among raft nodes before linearized reading' (duration: 223.425395ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:17:21.125643Z","caller":"traceutil/trace.go:171","msg":"trace[665719439] transaction","detail":"{read_only:false; response_revision:11614; number_of_response:1; }","duration":"115.938023ms","start":"2026-05-11T01:17:21.009654Z","end":"2026-05-11T01:17:21.125592Z","steps":["trace[665719439] 'process raft request' (duration: 23.860571ms)","trace[665719439] 'compare' (duration: 91.915678ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:17:21.134189Z","caller":"traceutil/trace.go:171","msg":"trace[1124900464] linearizableReadLoop","detail":"{readStateIndex:12135; appliedIndex:12133; }","duration":"108.952001ms","start":"2026-05-11T01:17:21.02521Z","end":"2026-05-11T01:17:21.134162Z","steps":["trace[1124900464] 'read index received' (duration: 8.288176ms)","trace[1124900464] 'applied index is now lower than readState.Index' (duration: 100.662745ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:17:21.13432Z","caller":"traceutil/trace.go:171","msg":"trace[1156067360] transaction","detail":"{read_only:false; response_revision:11615; number_of_response:1; }","duration":"123.609307ms","start":"2026-05-11T01:17:21.010662Z","end":"2026-05-11T01:17:21.134271Z","steps":["trace[1156067360] 'process raft request' (duration: 123.357792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:17:21.134503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.220687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11491"} {"level":"info","ts":"2026-05-11T01:17:21.134567Z","caller":"traceutil/trace.go:171","msg":"trace[1769486280] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11615; }","duration":"109.39837ms","start":"2026-05-11T01:17:21.025154Z","end":"2026-05-11T01:17:21.134552Z","steps":["trace[1769486280] 'agreement among raft nodes before linearized reading' (duration: 109.119685ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:18:46.983034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9473} {"level":"info","ts":"2026-05-11T01:18:47.068904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9473,"took":"82.50122ms","hash":852556905,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":28504064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T01:18:47.068981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":852556905,"revision":9473,"compact-revision":6594} {"level":"info","ts":"2026-05-11T01:23:32.284103Z","caller":"traceutil/trace.go:171","msg":"trace[1635796465] linearizableReadLoop","detail":"{readStateIndex:15310; appliedIndex:15309; }","duration":"213.427655ms","start":"2026-05-11T01:23:32.07065Z","end":"2026-05-11T01:23:32.284078Z","steps":["trace[1635796465] 'read index received' (duration: 213.271581ms)","trace[1635796465] 'applied index is now lower than readState.Index' (duration: 155.154µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:23:32.284161Z","caller":"traceutil/trace.go:171","msg":"trace[1183328671] transaction","detail":"{read_only:false; response_revision:14630; number_of_response:1; }","duration":"220.400054ms","start":"2026-05-11T01:23:32.063744Z","end":"2026-05-11T01:23:32.284144Z","steps":["trace[1183328671] 'process raft request' (duration: 220.195189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:23:32.28442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.70146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T01:23:32.284522Z","caller":"traceutil/trace.go:171","msg":"trace[1939391496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:14630; }","duration":"213.906954ms","start":"2026-05-11T01:23:32.070593Z","end":"2026-05-11T01:23:32.2845Z","steps":["trace[1939391496] 'agreement among raft nodes before linearized reading' (duration: 213.656949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:23:32.295655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.799062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T01:23:32.29577Z","caller":"traceutil/trace.go:171","msg":"trace[867714427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14631; }","duration":"221.988677ms","start":"2026-05-11T01:23:32.073751Z","end":"2026-05-11T01:23:32.29574Z","steps":["trace[867714427] 'agreement among raft nodes before linearized reading' (duration: 221.64804ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:23:32.295792Z","caller":"traceutil/trace.go:171","msg":"trace[78639758] transaction","detail":"{read_only:false; response_revision:14631; number_of_response:1; }","duration":"172.008113ms","start":"2026-05-11T01:23:32.12373Z","end":"2026-05-11T01:23:32.295738Z","steps":["trace[78639758] 'process raft request' (duration: 171.480671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:23:32.295914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.729288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-05-11T01:23:32.296002Z","caller":"traceutil/trace.go:171","msg":"trace[1857470047] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:14631; }","duration":"187.857251ms","start":"2026-05-11T01:23:32.108133Z","end":"2026-05-11T01:23:32.29599Z","steps":["trace[1857470047] 'agreement among raft nodes before linearized reading' (duration: 187.617146ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:23:47.061246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12325} {"level":"info","ts":"2026-05-11T01:23:47.300195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12325,"took":"236.017467ms","hash":567512062,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":25456640,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T01:23:47.300289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":567512062,"revision":12325,"compact-revision":9473} {"level":"warn","ts":"2026-05-11T01:25:33.177482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.922872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T01:25:33.177643Z","caller":"traceutil/trace.go:171","msg":"trace[1021435328] transaction","detail":"{read_only:false; response_revision:16121; number_of_response:1; }","duration":"151.942543ms","start":"2026-05-11T01:25:33.025676Z","end":"2026-05-11T01:25:33.177618Z","steps":["trace[1021435328] 'process raft request' (duration: 22.746557ms)","trace[1021435328] 'compare' (duration: 128.751608ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:25:33.643347Z","caller":"traceutil/trace.go:171","msg":"trace[1901596349] transaction","detail":"{read_only:false; response_revision:16122; number_of_response:1; }","duration":"126.597714ms","start":"2026-05-11T01:25:33.516699Z","end":"2026-05-11T01:25:33.643296Z","steps":["trace[1901596349] 'process raft request' (duration: 126.37322ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:28:47.068745Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14745} {"level":"info","ts":"2026-05-11T01:28:47.166617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14745,"took":"92.817368ms","hash":2877869812,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":31195136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T01:28:47.16677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2877869812,"revision":14745,"compact-revision":12325} {"level":"info","ts":"2026-05-11T01:31:06.556458Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":20006,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:31:06.566447Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20006} {"level":"info","ts":"2026-05-11T01:31:06.566577Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15006} {"level":"info","ts":"2026-05-11T01:31:10.837951Z","caller":"traceutil/trace.go:171","msg":"trace[724513188] transaction","detail":"{read_only:false; response_revision:19279; number_of_response:1; }","duration":"124.908625ms","start":"2026-05-11T01:31:10.713012Z","end":"2026-05-11T01:31:10.837921Z","steps":["trace[724513188] 'process raft request' (duration: 124.428175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:31:12.268862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.851339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T01:31:12.268984Z","caller":"traceutil/trace.go:171","msg":"trace[1110172942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19292; }","duration":"180.011614ms","start":"2026-05-11T01:31:12.088942Z","end":"2026-05-11T01:31:12.268954Z","steps":["trace[1110172942] 'range keys from in-memory index tree' (duration: 179.697027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:33:33.006126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.3671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5029"} {"level":"info","ts":"2026-05-11T01:33:33.006222Z","caller":"traceutil/trace.go:171","msg":"trace[1406801976] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:20817; }","duration":"207.504773ms","start":"2026-05-11T01:33:32.798691Z","end":"2026-05-11T01:33:33.006196Z","steps":["trace[1406801976] 'range keys from in-memory index tree' (duration: 207.153304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:33:33.00627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.759651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11492"} {"level":"warn","ts":"2026-05-11T01:33:33.006244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.668377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:33:33.006321Z","caller":"traceutil/trace.go:171","msg":"trace[1646290591] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20817; }","duration":"245.850323ms","start":"2026-05-11T01:33:32.760453Z","end":"2026-05-11T01:33:33.006303Z","steps":["trace[1646290591] 'range keys from in-memory index tree' (duration: 245.510637ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:33:33.006364Z","caller":"traceutil/trace.go:171","msg":"trace[817793179] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:20817; }","duration":"212.85995ms","start":"2026-05-11T01:33:32.793478Z","end":"2026-05-11T01:33:33.006338Z","steps":["trace[817793179] 'range keys from in-memory index tree' (duration: 212.564484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:33:33.006427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.166387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:33:33.006108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.685126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:33:33.00657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.247649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:33:33.006628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.921431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:33:33.006618Z","caller":"traceutil/trace.go:171","msg":"trace[299728429] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:20817; }","duration":"213.227887ms","start":"2026-05-11T01:33:32.793342Z","end":"2026-05-11T01:33:33.00657Z","steps":["trace[299728429] 'range keys from in-memory index tree' (duration: 212.610014ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:33:33.006628Z","caller":"traceutil/trace.go:171","msg":"trace[1738763631] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:20817; }","duration":"211.327589ms","start":"2026-05-11T01:33:32.795193Z","end":"2026-05-11T01:33:33.00652Z","steps":["trace[1738763631] 'range keys from in-memory index tree' (duration: 210.994283ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:33:33.006685Z","caller":"traceutil/trace.go:171","msg":"trace[567253802] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:20817; }","duration":"226.357162ms","start":"2026-05-11T01:33:32.780249Z","end":"2026-05-11T01:33:33.006606Z","steps":["trace[567253802] 'count revisions from in-memory index tree' (duration: 226.146558ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:33:33.006679Z","caller":"traceutil/trace.go:171","msg":"trace[2043021705] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:20817; }","duration":"212.210417ms","start":"2026-05-11T01:33:32.794454Z","end":"2026-05-11T01:33:33.006665Z","steps":["trace[2043021705] 'range keys from in-memory index tree' (duration: 211.686837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:33:33.006436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.941521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:33:33.006766Z","caller":"traceutil/trace.go:171","msg":"trace[545392988] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:20817; }","duration":"213.295199ms","start":"2026-05-11T01:33:32.793454Z","end":"2026-05-11T01:33:33.00675Z","steps":["trace[545392988] 'range keys from in-memory index tree' (duration: 212.894771ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:33:33.007021Z","caller":"traceutil/trace.go:171","msg":"trace[1159175862] transaction","detail":"{read_only:false; response_revision:20818; number_of_response:1; }","duration":"200.956321ms","start":"2026-05-11T01:33:32.806026Z","end":"2026-05-11T01:33:33.006983Z","steps":["trace[1159175862] 'process raft request' (duration: 153.433615ms)","trace[1159175862] 'compare' (duration: 47.084357ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:33:33.007799Z","caller":"traceutil/trace.go:171","msg":"trace[1070807627] transaction","detail":"{read_only:false; response_revision:20819; number_of_response:1; }","duration":"115.167085ms","start":"2026-05-11T01:33:32.892614Z","end":"2026-05-11T01:33:33.007781Z","steps":["trace[1070807627] 'process raft request' (duration: 115.041833ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:33:47.076019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17871} {"level":"info","ts":"2026-05-11T01:33:47.201953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17871,"took":"121.146266ms","hash":3351989166,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":32481280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T01:33:47.202036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3351989166,"revision":17871,"compact-revision":14745} {"level":"info","ts":"2026-05-11T01:38:03.404938Z","caller":"traceutil/trace.go:171","msg":"trace[1788329004] linearizableReadLoop","detail":"{readStateIndex:24205; appliedIndex:24204; }","duration":"114.389908ms","start":"2026-05-11T01:38:03.290521Z","end":"2026-05-11T01:38:03.404911Z","steps":["trace[1788329004] 'read index received' (duration: 114.162104ms)","trace[1788329004] 'applied index is now lower than readState.Index' (duration: 226.454µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:38:03.405083Z","caller":"traceutil/trace.go:171","msg":"trace[509915564] transaction","detail":"{read_only:false; response_revision:23123; number_of_response:1; }","duration":"128.902633ms","start":"2026-05-11T01:38:03.276166Z","end":"2026-05-11T01:38:03.405069Z","steps":["trace[509915564] 'process raft request' (duration: 128.582075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:03.40542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.88465ms","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:38:03.405469Z","caller":"traceutil/trace.go:171","msg":"trace[1124331588] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:23123; }","duration":"114.990621ms","start":"2026-05-11T01:38:03.290464Z","end":"2026-05-11T01:38:03.405455Z","steps":["trace[1124331588] 'agreement among raft nodes before linearized reading' (duration: 114.895599ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:03.863721Z","caller":"traceutil/trace.go:171","msg":"trace[1711222054] transaction","detail":"{read_only:false; response_revision:23125; number_of_response:1; }","duration":"141.408683ms","start":"2026-05-11T01:38:03.722288Z","end":"2026-05-11T01:38:03.863697Z","steps":["trace[1711222054] 'process raft request' (duration: 141.226279ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:03.881093Z","caller":"traceutil/trace.go:171","msg":"trace[1788175675] linearizableReadLoop","detail":"{readStateIndex:24208; appliedIndex:24206; }","duration":"104.121072ms","start":"2026-05-11T01:38:03.776945Z","end":"2026-05-11T01:38:03.881066Z","steps":["trace[1788175675] 'read index received' (duration: 86.649562ms)","trace[1788175675] 'applied index is now lower than readState.Index' (duration: 17.47061ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:38:03.881179Z","caller":"traceutil/trace.go:171","msg":"trace[1980733532] transaction","detail":"{read_only:false; response_revision:23126; number_of_response:1; }","duration":"157.505656ms","start":"2026-05-11T01:38:03.723653Z","end":"2026-05-11T01:38:03.881159Z","steps":["trace[1980733532] 'process raft request' (duration: 157.268931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:03.881273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.321657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:38:03.881308Z","caller":"traceutil/trace.go:171","msg":"trace[1125681316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23126; }","duration":"104.42875ms","start":"2026-05-11T01:38:03.776868Z","end":"2026-05-11T01:38:03.881297Z","steps":["trace[1125681316] 'agreement among raft nodes before linearized reading' (duration: 104.311977ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:04.238705Z","caller":"traceutil/trace.go:171","msg":"trace[839754546] transaction","detail":"{read_only:false; response_revision:23127; number_of_response:1; }","duration":"351.843654ms","start":"2026-05-11T01:38:03.886834Z","end":"2026-05-11T01:38:04.238677Z","steps":["trace[839754546] 'process raft request' (duration: 351.582688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:04.238908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:38:03.886807Z","time spent":"351.982686ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:38:04.311683Z","caller":"traceutil/trace.go:171","msg":"trace[251114768] linearizableReadLoop","detail":"{readStateIndex:24211; appliedIndex:24208; }","duration":"199.270276ms","start":"2026-05-11T01:38:04.112384Z","end":"2026-05-11T01:38:04.311654Z","steps":["trace[251114768] 'read index received' (duration: 126.080584ms)","trace[251114768] 'applied index is now lower than readState.Index' (duration: 73.188452ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:38:04.311747Z","caller":"traceutil/trace.go:171","msg":"trace[244649499] transaction","detail":"{read_only:false; response_revision:23128; number_of_response:1; }","duration":"422.531765ms","start":"2026-05-11T01:38:03.889165Z","end":"2026-05-11T01:38:04.311696Z","steps":["trace[244649499] 'process raft request' (duration: 410.769848ms)","trace[244649499] 'compare' (duration: 11.457351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:38:04.311952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.429198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-11T01:38:04.311944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:38:03.889147Z","time spent":"422.679037ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:38:04.311926Z","caller":"traceutil/trace.go:171","msg":"trace[2046358842] transaction","detail":"{read_only:false; response_revision:23129; number_of_response:1; }","duration":"323.069074ms","start":"2026-05-11T01:38:03.988818Z","end":"2026-05-11T01:38:04.311887Z","steps":["trace[2046358842] 'process raft request' (duration: 322.762048ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:04.311987Z","caller":"traceutil/trace.go:171","msg":"trace[2036056753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23129; }","duration":"190.50462ms","start":"2026-05-11T01:38:04.12147Z","end":"2026-05-11T01:38:04.311975Z","steps":["trace[2036056753] 'agreement among raft nodes before linearized reading' (duration: 190.339587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:04.312027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.616793ms","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:38:04.312093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.718183ms","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:38:04.312133Z","caller":"traceutil/trace.go:171","msg":"trace[1940001240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23129; }","duration":"199.786276ms","start":"2026-05-11T01:38:04.112324Z","end":"2026-05-11T01:38:04.31211Z","steps":["trace[1940001240] 'agreement among raft nodes before linearized reading' (duration: 199.47435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:04.312132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:38:03.98877Z","time spent":"323.243478ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:38:04.312163Z","caller":"traceutil/trace.go:171","msg":"trace[1246377791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23129; }","duration":"189.824145ms","start":"2026-05-11T01:38:04.122321Z","end":"2026-05-11T01:38:04.312145Z","steps":["trace[1246377791] 'agreement among raft nodes before linearized reading' (duration: 189.56079ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:38:04.543114Z","caller":"traceutil/trace.go:171","msg":"trace[1029704651] transaction","detail":"{read_only:false; response_revision:23131; number_of_response:1; }","duration":"225.016984ms","start":"2026-05-11T01:38:04.318064Z","end":"2026-05-11T01:38:04.543081Z","steps":["trace[1029704651] 'process raft request' (duration: 149.285512ms)","trace[1029704651] 'compare' (duration: 75.241533ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:38:04.543236Z","caller":"traceutil/trace.go:171","msg":"trace[772188165] transaction","detail":"{read_only:false; response_revision:23132; number_of_response:1; }","duration":"225.086545ms","start":"2026-05-11T01:38:04.318138Z","end":"2026-05-11T01:38:04.543225Z","steps":["trace[772188165] 'process raft request' (duration: 224.778639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:04.849694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.121145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:38:04.849804Z","caller":"traceutil/trace.go:171","msg":"trace[853123950] linearizableReadLoop","detail":"{readStateIndex:24215; appliedIndex:24214; }","duration":"231.590145ms","start":"2026-05-11T01:38:04.618195Z","end":"2026-05-11T01:38:04.849786Z","steps":["trace[853123950] 'read index received' (duration: 106.236635ms)","trace[853123950] 'applied index is now lower than readState.Index' (duration: 125.35225ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:38:04.849903Z","caller":"traceutil/trace.go:171","msg":"trace[1578068130] transaction","detail":"{read_only:false; response_revision:23133; number_of_response:1; }","duration":"301.560132ms","start":"2026-05-11T01:38:04.548298Z","end":"2026-05-11T01:38:04.849858Z","steps":["trace[1578068130] 'process raft request' (duration: 176.180491ms)","trace[1578068130] 'compare' (duration: 124.995043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:38:04.849939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.710767ms","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:38:04.850029Z","caller":"traceutil/trace.go:171","msg":"trace[941739664] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:23133; }","duration":"231.85763ms","start":"2026-05-11T01:38:04.618147Z","end":"2026-05-11T01:38:04.850005Z","steps":["trace[941739664] 'agreement among raft nodes before linearized reading' (duration: 231.701237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:38:04.850147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:38:04.548263Z","time spent":"301.769176ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:38:47.083542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20955} {"level":"info","ts":"2026-05-11T01:38:47.201956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20955,"took":"113.209577ms","hash":2551629733,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":33239040,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-11T01:38:47.202041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2551629733,"revision":20955,"compact-revision":17871} {"level":"info","ts":"2026-05-11T01:41:58.181577Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a39.wal"} {"level":"info","ts":"2026-05-11T01:43:47.091359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23667} {"level":"info","ts":"2026-05-11T01:43:47.217886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23667,"took":"121.247707ms","hash":1894391148,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":42033152,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-11T01:43:47.217957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1894391148,"revision":23667,"compact-revision":20955} {"level":"info","ts":"2026-05-11T01:45:00.057775Z","caller":"traceutil/trace.go:171","msg":"trace[1599540832] transaction","detail":"{read_only:false; response_revision:28083; number_of_response:1; }","duration":"168.812814ms","start":"2026-05-11T01:44:59.888934Z","end":"2026-05-11T01:45:00.057747Z","steps":["trace[1599540832] 'process raft request' (duration: 168.62053ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.180451Z","caller":"traceutil/trace.go:171","msg":"trace[884472968] transaction","detail":"{read_only:false; response_revision:28085; number_of_response:1; }","duration":"204.099673ms","start":"2026-05-11T01:44:59.976316Z","end":"2026-05-11T01:45:00.180416Z","steps":["trace[884472968] 'process raft request' (duration: 203.988641ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.180655Z","caller":"traceutil/trace.go:171","msg":"trace[540761771] transaction","detail":"{read_only:false; response_revision:28084; number_of_response:1; }","duration":"289.415288ms","start":"2026-05-11T01:44:59.89116Z","end":"2026-05-11T01:45:00.180575Z","steps":["trace[540761771] 'process raft request' (duration: 278.006159ms)","trace[540761771] 'compare' (duration: 10.769186ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:00.590357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.10536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:00.590474Z","caller":"traceutil/trace.go:171","msg":"trace[1664344391] linearizableReadLoop","detail":"{readStateIndex:29371; appliedIndex:29367; }","duration":"457.955577ms","start":"2026-05-11T01:45:00.132507Z","end":"2026-05-11T01:45:00.590462Z","steps":["trace[1664344391] 'read index received' (duration: 36.683757ms)","trace[1664344391] 'applied index is now lower than readState.Index' (duration: 421.27109ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:00.590618Z","caller":"traceutil/trace.go:171","msg":"trace[318050572] transaction","detail":"{read_only:false; response_revision:28087; number_of_response:1; }","duration":"483.895418ms","start":"2026-05-11T01:45:00.106684Z","end":"2026-05-11T01:45:00.590579Z","steps":["trace[318050572] 'process raft request' (duration: 483.743315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.590788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.276193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-05-11T01:45:00.590817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.106639Z","time spent":"484.074272ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5414,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T01:45:00.590839Z","caller":"traceutil/trace.go:171","msg":"trace[1314136511] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:28087; }","duration":"458.394795ms","start":"2026-05-11T01:45:00.132436Z","end":"2026-05-11T01:45:00.590831Z","steps":["trace[1314136511] 'agreement among raft nodes before linearized reading' (duration: 458.201241ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.591132Z","caller":"traceutil/trace.go:171","msg":"trace[630838131] transaction","detail":"{read_only:false; response_revision:28086; number_of_response:1; }","duration":"485.0029ms","start":"2026-05-11T01:45:00.106117Z","end":"2026-05-11T01:45:00.59112Z","steps":["trace[630838131] 'process raft request' (duration: 207.080793ms)","trace[630838131] 'compare' (duration: 276.074569ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:00.591149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.132407Z","time spent":"458.733622ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"warn","ts":"2026-05-11T01:45:00.591175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.106087Z","time spent":"485.066151ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4968,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T01:45:00.591443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.221851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:45:00.591472Z","caller":"traceutil/trace.go:171","msg":"trace[873863058] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:28087; }","duration":"448.273501ms","start":"2026-05-11T01:45:00.143189Z","end":"2026-05-11T01:45:00.591462Z","steps":["trace[873863058] 'agreement among raft nodes before linearized reading' (duration: 448.18245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:00.591491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:00.143178Z","time spent":"448.308442ms","remote":"127.0.0.1:41318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":32,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:45:00.591936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.27791ms","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:45:00.591978Z","caller":"traceutil/trace.go:171","msg":"trace[118624229] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:28087; }","duration":"145.376202ms","start":"2026-05-11T01:45:00.44659Z","end":"2026-05-11T01:45:00.591966Z","steps":["trace[118624229] 'agreement among raft nodes before linearized reading' (duration: 145.160718ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.718396Z","caller":"traceutil/trace.go:171","msg":"trace[1946637798] transaction","detail":"{read_only:false; response_revision:28092; number_of_response:1; }","duration":"110.171764ms","start":"2026-05-11T01:45:00.6082Z","end":"2026-05-11T01:45:00.718372Z","steps":["trace[1946637798] 'process raft request' (duration: 110.120623ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.718409Z","caller":"traceutil/trace.go:171","msg":"trace[814615117] transaction","detail":"{read_only:false; response_revision:28090; number_of_response:1; }","duration":"118.532272ms","start":"2026-05-11T01:45:00.599794Z","end":"2026-05-11T01:45:00.718326Z","steps":["trace[814615117] 'process raft request' (duration: 118.366739ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.718567Z","caller":"traceutil/trace.go:171","msg":"trace[984673862] transaction","detail":"{read_only:false; response_revision:28091; number_of_response:1; }","duration":"110.405279ms","start":"2026-05-11T01:45:00.608145Z","end":"2026-05-11T01:45:00.71855Z","steps":["trace[984673862] 'process raft request' (duration: 110.061442ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:00.721588Z","caller":"traceutil/trace.go:171","msg":"trace[1023865980] transaction","detail":"{read_only:false; response_revision:28089; number_of_response:1; }","duration":"118.551462ms","start":"2026-05-11T01:45:00.59966Z","end":"2026-05-11T01:45:00.718212Z","steps":["trace[1023865980] 'process raft request' (duration: 118.217266ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.204882Z","caller":"traceutil/trace.go:171","msg":"trace[501988467] linearizableReadLoop","detail":"{readStateIndex:29439; appliedIndex:29438; }","duration":"123.38264ms","start":"2026-05-11T01:45:04.081474Z","end":"2026-05-11T01:45:04.204856Z","steps":["trace[501988467] 'read index received' (duration: 123.138135ms)","trace[501988467] 'applied index is now lower than readState.Index' (duration: 243.505µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.2052Z","caller":"traceutil/trace.go:171","msg":"trace[541600530] transaction","detail":"{read_only:false; response_revision:28153; number_of_response:1; }","duration":"129.198516ms","start":"2026-05-11T01:45:04.075988Z","end":"2026-05-11T01:45:04.205186Z","steps":["trace[541600530] 'process raft request' (duration: 128.693256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.205453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.961182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:04.20549Z","caller":"traceutil/trace.go:171","msg":"trace[1535740688] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:28153; }","duration":"124.047173ms","start":"2026-05-11T01:45:04.08143Z","end":"2026-05-11T01:45:04.205477Z","steps":["trace[1535740688] 'agreement among raft nodes before linearized reading' (duration: 123.954021ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:04.432369Z","caller":"traceutil/trace.go:171","msg":"trace[1714232276] transaction","detail":"{read_only:false; response_revision:28155; number_of_response:1; }","duration":"218.36872ms","start":"2026-05-11T01:45:04.213983Z","end":"2026-05-11T01:45:04.432352Z","steps":["trace[1714232276] 'process raft request' (duration: 218.224157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.66044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.139757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:04.660659Z","caller":"traceutil/trace.go:171","msg":"trace[1058890992] linearizableReadLoop","detail":"{readStateIndex:29443; appliedIndex:29440; }","duration":"365.410076ms","start":"2026-05-11T01:45:04.295224Z","end":"2026-05-11T01:45:04.660634Z","steps":["trace[1058890992] 'read index received' (duration: 136.992424ms)","trace[1058890992] 'applied index is now lower than readState.Index' (duration: 228.416012ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.660774Z","caller":"traceutil/trace.go:171","msg":"trace[1020631196] transaction","detail":"{read_only:false; response_revision:28157; number_of_response:1; }","duration":"404.625975ms","start":"2026-05-11T01:45:04.256136Z","end":"2026-05-11T01:45:04.660762Z","steps":["trace[1020631196] 'process raft request' (duration: 404.427351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.660842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.256126Z","time spent":"404.677616ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:04.660819Z","caller":"traceutil/trace.go:171","msg":"trace[424380008] transaction","detail":"{read_only:false; response_revision:28156; number_of_response:1; }","duration":"408.309008ms","start":"2026-05-11T01:45:04.252473Z","end":"2026-05-11T01:45:04.660782Z","steps":["trace[424380008] 'process raft request' (duration: 262.693491ms)","trace[424380008] 'compare' (duration: 144.952683ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:04.660916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.649641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:45:04.661002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.252441Z","time spent":"408.456311ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:04.661025Z","caller":"traceutil/trace.go:171","msg":"trace[1787621725] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:28157; }","duration":"365.806614ms","start":"2026-05-11T01:45:04.295188Z","end":"2026-05-11T01:45:04.660995Z","steps":["trace[1787621725] 'agreement among raft nodes before linearized reading' (duration: 365.63064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.661075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.295175Z","time spent":"365.886746ms","remote":"127.0.0.1:49414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:45:04.976073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.978139ms","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:04.976206Z","caller":"traceutil/trace.go:171","msg":"trace[120648686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28157; }","duration":"200.160373ms","start":"2026-05-11T01:45:04.776013Z","end":"2026-05-11T01:45:04.976174Z","steps":["trace[120648686] 'range keys from in-memory index tree' (duration: 199.879248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.977644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.139169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:04.977736Z","caller":"traceutil/trace.go:171","msg":"trace[1289940164] linearizableReadLoop","detail":"{readStateIndex:29444; appliedIndex:29443; }","duration":"195.114403ms","start":"2026-05-11T01:45:04.782606Z","end":"2026-05-11T01:45:04.977721Z","steps":["trace[1289940164] 'read index received' (duration: 40.640428ms)","trace[1289940164] 'applied index is now lower than readState.Index' (duration: 154.472585ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:04.97782Z","caller":"traceutil/trace.go:171","msg":"trace[1550286874] transaction","detail":"{read_only:false; response_revision:28158; number_of_response:1; }","duration":"200.588313ms","start":"2026-05-11T01:45:04.7772Z","end":"2026-05-11T01:45:04.977788Z","steps":["trace[1550286874] 'process raft request' (duration: 46.184259ms)","trace[1550286874] 'compare' (duration: 152.651888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:04.977964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.368418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T01:45:04.978002Z","caller":"traceutil/trace.go:171","msg":"trace[948938781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28158; }","duration":"195.444459ms","start":"2026-05-11T01:45:04.782546Z","end":"2026-05-11T01:45:04.97799Z","steps":["trace[948938781] 'agreement among raft nodes before linearized reading' (duration: 195.254925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.987569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.853743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:45:04.987633Z","caller":"traceutil/trace.go:171","msg":"trace[118961472] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:28159; }","duration":"150.935595ms","start":"2026-05-11T01:45:04.836669Z","end":"2026-05-11T01:45:04.987605Z","steps":["trace[118961472] 'agreement among raft nodes before linearized reading' (duration: 150.819673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.987759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.26348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-11T01:45:04.987794Z","caller":"traceutil/trace.go:171","msg":"trace[516560580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28159; }","duration":"141.32734ms","start":"2026-05-11T01:45:04.846458Z","end":"2026-05-11T01:45:04.987785Z","steps":["trace[516560580] 'agreement among raft nodes before linearized reading' (duration: 141.222328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.987868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.899603ms","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:04.987898Z","caller":"traceutil/trace.go:171","msg":"trace[1759102280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28159; }","duration":"142.949623ms","start":"2026-05-11T01:45:04.844941Z","end":"2026-05-11T01:45:04.987891Z","steps":["trace[1759102280] 'agreement among raft nodes before linearized reading' (duration: 142.857062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:04.98799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.801011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:04.988022Z","caller":"traceutil/trace.go:171","msg":"trace[1229985786] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:28159; }","duration":"149.890433ms","start":"2026-05-11T01:45:04.838123Z","end":"2026-05-11T01:45:04.988013Z","steps":["trace[1229985786] 'agreement among raft nodes before linearized reading' (duration: 149.833342ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:05.29687Z","caller":"traceutil/trace.go:171","msg":"trace[655093257] transaction","detail":"{read_only:false; response_revision:28161; number_of_response:1; }","duration":"313.577994ms","start":"2026-05-11T01:45:04.983254Z","end":"2026-05-11T01:45:05.296832Z","steps":["trace[655093257] 'process raft request' (duration: 313.420631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:05.297087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.983245Z","time spent":"313.720846ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:05.297145Z","caller":"traceutil/trace.go:171","msg":"trace[281218715] transaction","detail":"{read_only:false; response_revision:28160; number_of_response:1; }","duration":"314.091154ms","start":"2026-05-11T01:45:04.983017Z","end":"2026-05-11T01:45:05.297108Z","steps":["trace[281218715] 'process raft request' (duration: 259.088848ms)","trace[281218715] 'compare' (duration: 54.162278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:05.297416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.982995Z","time spent":"314.237447ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:05.299596Z","caller":"traceutil/trace.go:171","msg":"trace[2060340148] transaction","detail":"{read_only:false; response_revision:28162; number_of_response:1; }","duration":"307.978712ms","start":"2026-05-11T01:45:04.991579Z","end":"2026-05-11T01:45:05.299558Z","steps":["trace[2060340148] 'process raft request' (duration: 307.807768ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:05.299771Z","caller":"traceutil/trace.go:171","msg":"trace[664218918] linearizableReadLoop","detail":"{readStateIndex:29449; appliedIndex:29445; }","duration":"153.20491ms","start":"2026-05-11T01:45:05.146548Z","end":"2026-05-11T01:45:05.299753Z","steps":["trace[664218918] 'read index received' (duration: 95.593872ms)","trace[664218918] 'applied index is now lower than readState.Index' (duration: 57.609988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:05.299979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.991548Z","time spent":"308.224687ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:05.300085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.535927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:05.300151Z","caller":"traceutil/trace.go:171","msg":"trace[233715289] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:28163; }","duration":"153.666089ms","start":"2026-05-11T01:45:05.146473Z","end":"2026-05-11T01:45:05.300139Z","steps":["trace[233715289] 'agreement among raft nodes before linearized reading' (duration: 153.557517ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:05.300006Z","caller":"traceutil/trace.go:171","msg":"trace[1894182456] transaction","detail":"{read_only:false; response_revision:28163; number_of_response:1; }","duration":"308.047053ms","start":"2026-05-11T01:45:04.991945Z","end":"2026-05-11T01:45:05.299992Z","steps":["trace[1894182456] 'process raft request' (duration: 307.570244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:05.300453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.240005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-11T01:45:05.300434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:04.991932Z","time spent":"308.376159ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:05.300512Z","caller":"traceutil/trace.go:171","msg":"trace[352324422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28163; }","duration":"101.343517ms","start":"2026-05-11T01:45:05.199152Z","end":"2026-05-11T01:45:05.300496Z","steps":["trace[352324422] 'agreement among raft nodes before linearized reading' (duration: 101.182734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:05.594768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.027442ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:05.594935Z","caller":"traceutil/trace.go:171","msg":"trace[1018540871] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:28164; }","duration":"117.234846ms","start":"2026-05-11T01:45:05.477677Z","end":"2026-05-11T01:45:05.594912Z","steps":["trace[1018540871] 'range keys from in-memory index tree' (duration: 116.997662ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:05.634657Z","caller":"traceutil/trace.go:171","msg":"trace[945092639] transaction","detail":"{read_only:false; response_revision:28166; number_of_response:1; }","duration":"325.657657ms","start":"2026-05-11T01:45:05.308976Z","end":"2026-05-11T01:45:05.634633Z","steps":["trace[945092639] 'process raft request' (duration: 325.612116ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:05.634751Z","caller":"traceutil/trace.go:171","msg":"trace[1489625898] transaction","detail":"{read_only:false; response_revision:28165; number_of_response:1; }","duration":"326.946662ms","start":"2026-05-11T01:45:05.307771Z","end":"2026-05-11T01:45:05.634718Z","steps":["trace[1489625898] 'process raft request' (duration: 326.661156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:05.634812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:05.308968Z","time spent":"325.757559ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:05.634925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:05.307763Z","time spent":"327.061855ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:05.936326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.950767ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-11T01:45:05.936448Z","caller":"traceutil/trace.go:171","msg":"trace[286727017] linearizableReadLoop","detail":"{readStateIndex:29453; appliedIndex:29450; }","duration":"523.27387ms","start":"2026-05-11T01:45:05.413154Z","end":"2026-05-11T01:45:05.936428Z","steps":["trace[286727017] 'read index received' (duration: 221.395211ms)","trace[286727017] 'applied index is now lower than readState.Index' (duration: 301.877089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:05.936964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.80669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:45:05.937001Z","caller":"traceutil/trace.go:171","msg":"trace[559875802] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:28166; }","duration":"523.915992ms","start":"2026-05-11T01:45:05.413074Z","end":"2026-05-11T01:45:05.93699Z","steps":["trace[559875802] 'agreement among raft nodes before linearized reading' (duration: 523.736148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:05.937042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:05.413033Z","time spent":"523.994864ms","remote":"127.0.0.1:45838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":88,"response size":32,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:45:05.937166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.374453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:45:05.937195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.801546ms","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":"warn","ts":"2026-05-11T01:45:05.937194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.617718ms","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:45:05.937216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.055408ms","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:05.937252Z","caller":"traceutil/trace.go:171","msg":"trace[125713260] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:28166; }","duration":"246.481455ms","start":"2026-05-11T01:45:05.690748Z","end":"2026-05-11T01:45:05.937229Z","steps":["trace[125713260] 'agreement among raft nodes before linearized reading' (duration: 246.307161ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:05.937264Z","caller":"traceutil/trace.go:171","msg":"trace[66796773] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28166; }","duration":"298.880058ms","start":"2026-05-11T01:45:05.638364Z","end":"2026-05-11T01:45:05.937244Z","steps":["trace[66796773] 'agreement among raft nodes before linearized reading' (duration: 298.725225ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:05.937267Z","caller":"traceutil/trace.go:171","msg":"trace[1521788746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28166; }","duration":"449.74291ms","start":"2026-05-11T01:45:05.487508Z","end":"2026-05-11T01:45:05.937251Z","steps":["trace[1521788746] 'agreement among raft nodes before linearized reading' (duration: 449.515486ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:05.937276Z","caller":"traceutil/trace.go:171","msg":"trace[2044222996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28166; }","duration":"157.19815ms","start":"2026-05-11T01:45:05.780062Z","end":"2026-05-11T01:45:05.937261Z","steps":["trace[2044222996] 'agreement among raft nodes before linearized reading' (duration: 157.025427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:05.937308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:05.487486Z","time spent":"449.813252ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T01:45:05.937312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.637324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:45:05.93739Z","caller":"traceutil/trace.go:171","msg":"trace[861597769] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:28166; }","duration":"210.765916ms","start":"2026-05-11T01:45:05.726601Z","end":"2026-05-11T01:45:05.937367Z","steps":["trace[861597769] 'agreement among raft nodes before linearized reading' (duration: 210.577693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:05.937505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.530021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T01:45:05.937617Z","caller":"traceutil/trace.go:171","msg":"trace[1649660676] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28166; }","duration":"299.666934ms","start":"2026-05-11T01:45:05.637922Z","end":"2026-05-11T01:45:05.937589Z","steps":["trace[1649660676] 'agreement among raft nodes before linearized reading' (duration: 299.300046ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:06.049214Z","caller":"traceutil/trace.go:171","msg":"trace[1911232401] transaction","detail":"{read_only:false; response_revision:28167; number_of_response:1; }","duration":"107.763436ms","start":"2026-05-11T01:45:05.941419Z","end":"2026-05-11T01:45:06.049183Z","steps":["trace[1911232401] 'process raft request' (duration: 107.503861ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:06.103365Z","caller":"traceutil/trace.go:171","msg":"trace[1439944097] transaction","detail":"{read_only:false; response_revision:28169; number_of_response:1; }","duration":"161.064568ms","start":"2026-05-11T01:45:05.942259Z","end":"2026-05-11T01:45:06.103323Z","steps":["trace[1439944097] 'process raft request' (duration: 160.988377ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:06.103861Z","caller":"traceutil/trace.go:171","msg":"trace[1588678586] transaction","detail":"{read_only:false; response_revision:28168; number_of_response:1; }","duration":"162.343693ms","start":"2026-05-11T01:45:05.941487Z","end":"2026-05-11T01:45:06.103831Z","steps":["trace[1588678586] 'process raft request' (duration: 161.515356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:06.340481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.632666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:45:06.340609Z","caller":"traceutil/trace.go:171","msg":"trace[801582769] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:28169; }","duration":"131.851231ms","start":"2026-05-11T01:45:06.20873Z","end":"2026-05-11T01:45:06.340581Z","steps":["trace[801582769] 'count revisions from in-memory index tree' (duration: 131.514134ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:06.350471Z","caller":"traceutil/trace.go:171","msg":"trace[2017601895] linearizableReadLoop","detail":"{readStateIndex:29457; appliedIndex:29456; }","duration":"105.844338ms","start":"2026-05-11T01:45:06.244604Z","end":"2026-05-11T01:45:06.350449Z","steps":["trace[2017601895] 'read index received' (duration: 105.765236ms)","trace[2017601895] 'applied index is now lower than readState.Index' (duration: 77.602µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:06.35061Z","caller":"traceutil/trace.go:171","msg":"trace[1743964460] transaction","detail":"{read_only:false; response_revision:28170; number_of_response:1; }","duration":"138.692788ms","start":"2026-05-11T01:45:06.211871Z","end":"2026-05-11T01:45:06.350564Z","steps":["trace[1743964460] 'process raft request' (duration: 138.392022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:06.350669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.051182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:06.350706Z","caller":"traceutil/trace.go:171","msg":"trace[1705171966] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:28170; }","duration":"106.159814ms","start":"2026-05-11T01:45:06.244533Z","end":"2026-05-11T01:45:06.350693Z","steps":["trace[1705171966] 'agreement among raft nodes before linearized reading' (duration: 106.00085ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:06.497982Z","caller":"traceutil/trace.go:171","msg":"trace[1804401731] linearizableReadLoop","detail":"{readStateIndex:29458; appliedIndex:29457; }","duration":"145.084695ms","start":"2026-05-11T01:45:06.352868Z","end":"2026-05-11T01:45:06.497953Z","steps":["trace[1804401731] 'read index received' (duration: 129.015633ms)","trace[1804401731] 'applied index is now lower than readState.Index' (duration: 16.067812ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:06.498048Z","caller":"traceutil/trace.go:171","msg":"trace[920551469] transaction","detail":"{read_only:false; response_revision:28171; number_of_response:1; }","duration":"240.167457ms","start":"2026-05-11T01:45:06.257837Z","end":"2026-05-11T01:45:06.498005Z","steps":["trace[920551469] 'process raft request' (duration: 224.115496ms)","trace[920551469] 'compare' (duration: 15.821187ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:06.49815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.23942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:06.498202Z","caller":"traceutil/trace.go:171","msg":"trace[1029653404] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:28171; }","duration":"145.370623ms","start":"2026-05-11T01:45:06.352817Z","end":"2026-05-11T01:45:06.498188Z","steps":["trace[1029653404] 'agreement among raft nodes before linearized reading' (duration: 145.246159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:07.590615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.933939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11492"} {"level":"info","ts":"2026-05-11T01:45:07.59072Z","caller":"traceutil/trace.go:171","msg":"trace[473208327] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28174; }","duration":"394.103322ms","start":"2026-05-11T01:45:07.196593Z","end":"2026-05-11T01:45:07.590697Z","steps":["trace[473208327] 'range keys from in-memory index tree' (duration: 393.464719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:07.590776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:07.196568Z","time spent":"394.194685ms","remote":"127.0.0.1:45830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11516,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-11T01:45:07.591753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.435688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T01:45:07.591903Z","caller":"traceutil/trace.go:171","msg":"trace[966544703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28174; }","duration":"288.572451ms","start":"2026-05-11T01:45:07.303239Z","end":"2026-05-11T01:45:07.591811Z","steps":["trace[966544703] 'range keys from in-memory index tree' (duration: 288.178853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:07.592609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.19202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:07.592707Z","caller":"traceutil/trace.go:171","msg":"trace[1283236736] linearizableReadLoop","detail":"{readStateIndex:29462; appliedIndex:29461; }","duration":"288.325036ms","start":"2026-05-11T01:45:07.304365Z","end":"2026-05-11T01:45:07.59269Z","steps":["trace[1283236736] 'read index received' (duration: 124.8561ms)","trace[1283236736] 'applied index is now lower than readState.Index' (duration: 163.467356ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:07.592814Z","caller":"traceutil/trace.go:171","msg":"trace[117560867] transaction","detail":"{read_only:false; response_revision:28175; number_of_response:1; }","duration":"288.754825ms","start":"2026-05-11T01:45:07.304043Z","end":"2026-05-11T01:45:07.592798Z","steps":["trace[117560867] 'process raft request' (duration: 125.295399ms)","trace[117560867] 'compare' (duration: 162.222591ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:07.593083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.979178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-11T01:45:07.593128Z","caller":"traceutil/trace.go:171","msg":"trace[1416943239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28175; }","duration":"288.07486ms","start":"2026-05-11T01:45:07.305041Z","end":"2026-05-11T01:45:07.593115Z","steps":["trace[1416943239] 'agreement among raft nodes before linearized reading' (duration: 287.961258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:07.593489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.120993ms","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:45:07.593537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.601802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:07.593577Z","caller":"traceutil/trace.go:171","msg":"trace[649104675] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:28175; }","duration":"183.714814ms","start":"2026-05-11T01:45:07.409853Z","end":"2026-05-11T01:45:07.593568Z","steps":["trace[649104675] 'agreement among raft nodes before linearized reading' (duration: 183.618432ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:07.593536Z","caller":"traceutil/trace.go:171","msg":"trace[99565323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28175; }","duration":"289.188564ms","start":"2026-05-11T01:45:07.304334Z","end":"2026-05-11T01:45:07.593523Z","steps":["trace[99565323] 'agreement among raft nodes before linearized reading' (duration: 289.071921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:07.593736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.728903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T01:45:07.593759Z","caller":"traceutil/trace.go:171","msg":"trace[516486292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28175; }","duration":"266.803875ms","start":"2026-05-11T01:45:07.326947Z","end":"2026-05-11T01:45:07.593751Z","steps":["trace[516486292] 'agreement among raft nodes before linearized reading' (duration: 266.734953ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:07.809529Z","caller":"traceutil/trace.go:171","msg":"trace[493001811] linearizableReadLoop","detail":"{readStateIndex:29465; appliedIndex:29462; }","duration":"202.445271ms","start":"2026-05-11T01:45:07.607055Z","end":"2026-05-11T01:45:07.8095Z","steps":["trace[493001811] 'read index received' (duration: 202.286407ms)","trace[493001811] 'applied index is now lower than readState.Index' (duration: 157.474µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:07.809782Z","caller":"traceutil/trace.go:171","msg":"trace[1451645477] transaction","detail":"{read_only:false; response_revision:28176; number_of_response:1; }","duration":"204.831308ms","start":"2026-05-11T01:45:07.604939Z","end":"2026-05-11T01:45:07.80977Z","steps":["trace[1451645477] 'process raft request' (duration: 204.345178ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:07.809955Z","caller":"traceutil/trace.go:171","msg":"trace[1542853888] transaction","detail":"{read_only:false; response_revision:28177; number_of_response:1; }","duration":"205.007981ms","start":"2026-05-11T01:45:07.604939Z","end":"2026-05-11T01:45:07.809947Z","steps":["trace[1542853888] 'process raft request' (duration: 204.485571ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:07.810032Z","caller":"traceutil/trace.go:171","msg":"trace[1444633208] transaction","detail":"{read_only:false; response_revision:28178; number_of_response:1; }","duration":"205.054972ms","start":"2026-05-11T01:45:07.604972Z","end":"2026-05-11T01:45:07.810027Z","steps":["trace[1444633208] 'process raft request' (duration: 204.485631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:07.810221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.144074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17234"} {"level":"info","ts":"2026-05-11T01:45:07.810252Z","caller":"traceutil/trace.go:171","msg":"trace[1506352066] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:28178; }","duration":"203.195845ms","start":"2026-05-11T01:45:07.607048Z","end":"2026-05-11T01:45:07.810243Z","steps":["trace[1506352066] 'agreement among raft nodes before linearized reading' (duration: 203.050432ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:07.810535Z","caller":"traceutil/trace.go:171","msg":"trace[256172284] transaction","detail":"{read_only:false; response_revision:28179; number_of_response:1; }","duration":"203.436489ms","start":"2026-05-11T01:45:07.607068Z","end":"2026-05-11T01:45:07.810504Z","steps":["trace[256172284] 'process raft request' (duration: 203.299557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:07.810671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.294088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-11T01:45:07.810722Z","caller":"traceutil/trace.go:171","msg":"trace[1627848825] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:28179; }","duration":"114.357329ms","start":"2026-05-11T01:45:07.696347Z","end":"2026-05-11T01:45:07.810704Z","steps":["trace[1627848825] 'agreement among raft nodes before linearized reading' (duration: 114.156445ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:11.715404Z","caller":"traceutil/trace.go:171","msg":"trace[1497990790] transaction","detail":"{read_only:false; response_revision:28225; number_of_response:1; }","duration":"101.975809ms","start":"2026-05-11T01:45:11.6134Z","end":"2026-05-11T01:45:11.715375Z","steps":["trace[1497990790] 'process raft request' (duration: 101.787086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:12.107309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.770824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:12.107578Z","caller":"traceutil/trace.go:171","msg":"trace[1824391431] transaction","detail":"{read_only:false; response_revision:28228; number_of_response:1; }","duration":"268.11852ms","start":"2026-05-11T01:45:11.839438Z","end":"2026-05-11T01:45:12.107556Z","steps":["trace[1824391431] 'process raft request' (duration: 268.006788ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:12.107889Z","caller":"traceutil/trace.go:171","msg":"trace[53770946] transaction","detail":"{read_only:false; response_revision:28227; number_of_response:1; }","duration":"268.649401ms","start":"2026-05-11T01:45:11.839202Z","end":"2026-05-11T01:45:12.107852Z","steps":["trace[53770946] 'process raft request' (duration: 96.178393ms)","trace[53770946] 'compare' (duration: 171.57403ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:12.108065Z","caller":"traceutil/trace.go:171","msg":"trace[1002965340] transaction","detail":"{read_only:false; response_revision:28229; number_of_response:1; }","duration":"268.58961ms","start":"2026-05-11T01:45:11.839459Z","end":"2026-05-11T01:45:12.108048Z","steps":["trace[1002965340] 'process raft request' (duration: 268.051989ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:12.455136Z","caller":"traceutil/trace.go:171","msg":"trace[1638498478] transaction","detail":"{read_only:false; response_revision:28230; number_of_response:1; }","duration":"335.76107ms","start":"2026-05-11T01:45:12.119344Z","end":"2026-05-11T01:45:12.455105Z","steps":["trace[1638498478] 'process raft request' (duration: 335.590646ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:12.455138Z","caller":"traceutil/trace.go:171","msg":"trace[594905799] linearizableReadLoop","detail":"{readStateIndex:29519; appliedIndex:29519; }","duration":"326.666078ms","start":"2026-05-11T01:45:12.128435Z","end":"2026-05-11T01:45:12.455101Z","steps":["trace[594905799] 'read index received' (duration: 326.645027ms)","trace[594905799] 'applied index is now lower than readState.Index' (duration: 17.301µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:12.455309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:12.119319Z","time spent":"335.896473ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:12.455468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.009724ms","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:45:12.455501Z","caller":"traceutil/trace.go:171","msg":"trace[317516663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28230; }","duration":"327.100185ms","start":"2026-05-11T01:45:12.128391Z","end":"2026-05-11T01:45:12.455492Z","steps":["trace[317516663] 'agreement among raft nodes before linearized reading' (duration: 326.871451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:12.455532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:12.128376Z","time spent":"327.149327ms","remote":"127.0.0.1:45920","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:45:12.474176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.701848ms","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:45:12.474255Z","caller":"traceutil/trace.go:171","msg":"trace[89542286] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28232; }","duration":"323.85378ms","start":"2026-05-11T01:45:12.150383Z","end":"2026-05-11T01:45:12.474237Z","steps":["trace[89542286] 'agreement among raft nodes before linearized reading' (duration: 323.664707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:12.474288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:12.15036Z","time spent":"323.920162ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":686,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T01:45:12.674225Z","caller":"traceutil/trace.go:171","msg":"trace[2023886226] transaction","detail":"{read_only:false; response_revision:28235; number_of_response:1; }","duration":"157.68063ms","start":"2026-05-11T01:45:12.516516Z","end":"2026-05-11T01:45:12.674197Z","steps":["trace[2023886226] 'process raft request' (duration: 157.331143ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:12.674476Z","caller":"traceutil/trace.go:171","msg":"trace[1684471246] transaction","detail":"{read_only:false; response_revision:28234; number_of_response:1; }","duration":"193.859317ms","start":"2026-05-11T01:45:12.48056Z","end":"2026-05-11T01:45:12.674419Z","steps":["trace[1684471246] 'process raft request' (duration: 143.339822ms)","trace[1684471246] 'compare' (duration: 49.805451ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:15.004267Z","caller":"traceutil/trace.go:171","msg":"trace[1481166370] transaction","detail":"{read_only:false; response_revision:28260; number_of_response:1; }","duration":"126.319099ms","start":"2026-05-11T01:45:14.877919Z","end":"2026-05-11T01:45:15.004238Z","steps":["trace[1481166370] 'process raft request' (duration: 126.142285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:15.274147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.815457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:15.274325Z","caller":"traceutil/trace.go:171","msg":"trace[2134468986] transaction","detail":"{read_only:false; response_revision:28261; number_of_response:1; }","duration":"212.787807ms","start":"2026-05-11T01:45:15.061518Z","end":"2026-05-11T01:45:15.274306Z","steps":["trace[2134468986] 'process raft request' (duration: 105.728235ms)","trace[2134468986] 'compare' (duration: 106.711776ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:15.274618Z","caller":"traceutil/trace.go:171","msg":"trace[781671138] transaction","detail":"{read_only:false; response_revision:28262; number_of_response:1; }","duration":"152.263181ms","start":"2026-05-11T01:45:15.122314Z","end":"2026-05-11T01:45:15.274577Z","steps":["trace[781671138] 'process raft request' (duration: 151.924644ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:23.973031Z","caller":"traceutil/trace.go:171","msg":"trace[1985847399] transaction","detail":"{read_only:false; response_revision:28407; number_of_response:1; }","duration":"189.770224ms","start":"2026-05-11T01:45:23.783229Z","end":"2026-05-11T01:45:23.973Z","steps":["trace[1985847399] 'process raft request' (duration: 189.56066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.342744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.852893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:24.342888Z","caller":"traceutil/trace.go:171","msg":"trace[68793945] linearizableReadLoop","detail":"{readStateIndex:29703; appliedIndex:29702; }","duration":"278.722573ms","start":"2026-05-11T01:45:24.064145Z","end":"2026-05-11T01:45:24.342867Z","steps":["trace[68793945] 'read index received' (duration: 114.587533ms)","trace[68793945] 'applied index is now lower than readState.Index' (duration: 164.13348ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:24.34309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.138001ms","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:45:24.343083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.910968ms","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:24.343128Z","caller":"traceutil/trace.go:171","msg":"trace[1580415281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28409; }","duration":"174.174472ms","start":"2026-05-11T01:45:24.168942Z","end":"2026-05-11T01:45:24.343117Z","steps":["trace[1580415281] 'agreement among raft nodes before linearized reading' (duration: 174.0397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.343114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.058008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:24.34317Z","caller":"traceutil/trace.go:171","msg":"trace[1184036022] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:28409; }","duration":"279.067731ms","start":"2026-05-11T01:45:24.064081Z","end":"2026-05-11T01:45:24.343148Z","steps":["trace[1184036022] 'agreement among raft nodes before linearized reading' (duration: 278.885367ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:24.343203Z","caller":"traceutil/trace.go:171","msg":"trace[1111314256] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:28409; }","duration":"158.218651ms","start":"2026-05-11T01:45:24.184962Z","end":"2026-05-11T01:45:24.343181Z","steps":["trace[1111314256] 'agreement among raft nodes before linearized reading' (duration: 158.051918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.343247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.708302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T01:45:24.343336Z","caller":"traceutil/trace.go:171","msg":"trace[985298738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28409; }","duration":"169.871955ms","start":"2026-05-11T01:45:24.173442Z","end":"2026-05-11T01:45:24.343314Z","steps":["trace[985298738] 'agreement among raft nodes before linearized reading' (duration: 169.586729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.343335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.886176ms","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:24.343451Z","caller":"traceutil/trace.go:171","msg":"trace[999516184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28409; }","duration":"170.043888ms","start":"2026-05-11T01:45:24.173387Z","end":"2026-05-11T01:45:24.34343Z","steps":["trace[999516184] 'agreement among raft nodes before linearized reading' (duration: 169.700942ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:24.348681Z","caller":"traceutil/trace.go:171","msg":"trace[2062092979] transaction","detail":"{read_only:false; response_revision:28409; number_of_response:1; }","duration":"290.093762ms","start":"2026-05-11T01:45:24.052813Z","end":"2026-05-11T01:45:24.342907Z","steps":["trace[2062092979] 'process raft request' (duration: 125.956802ms)","trace[2062092979] 'compare' (duration: 163.722341ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:24.641494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.294181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:45:24.641653Z","caller":"traceutil/trace.go:171","msg":"trace[1093921858] transaction","detail":"{read_only:false; response_revision:28410; number_of_response:1; }","duration":"292.690103ms","start":"2026-05-11T01:45:24.348937Z","end":"2026-05-11T01:45:24.641627Z","steps":["trace[1093921858] 'process raft request' (duration: 146.116027ms)","trace[1093921858] 'compare' (duration: 145.955434ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:24.680118Z","caller":"traceutil/trace.go:171","msg":"trace[385428565] transaction","detail":"{read_only:false; response_revision:28412; number_of_response:1; }","duration":"320.562465ms","start":"2026-05-11T01:45:24.359526Z","end":"2026-05-11T01:45:24.680089Z","steps":["trace[385428565] 'process raft request' (duration: 320.443423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.680315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:24.359514Z","time spent":"320.695957ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:45:24.680316Z","caller":"traceutil/trace.go:171","msg":"trace[2054619920] linearizableReadLoop","detail":"{readStateIndex:29705; appliedIndex:29703; }","duration":"325.245688ms","start":"2026-05-11T01:45:24.355045Z","end":"2026-05-11T01:45:24.680291Z","steps":["trace[2054619920] 'read index received' (duration: 140.032455ms)","trace[2054619920] 'applied index is now lower than readState.Index' (duration: 185.212203ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:24.680479Z","caller":"traceutil/trace.go:171","msg":"trace[1327245406] transaction","detail":"{read_only:false; response_revision:28413; number_of_response:1; }","duration":"206.682526ms","start":"2026-05-11T01:45:24.473788Z","end":"2026-05-11T01:45:24.680471Z","steps":["trace[1327245406] 'process raft request' (duration: 206.247487ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:24.680576Z","caller":"traceutil/trace.go:171","msg":"trace[1139713849] transaction","detail":"{read_only:false; response_revision:28411; number_of_response:1; }","duration":"331.230208ms","start":"2026-05-11T01:45:24.349333Z","end":"2026-05-11T01:45:24.680563Z","steps":["trace[1139713849] 'process raft request' (duration: 330.441453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.680638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:24.349311Z","time spent":"331.295309ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:24.680724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.658206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98ded663-db21-4309-824b-ee0fd08a6349\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-11T01:45:24.680754Z","caller":"traceutil/trace.go:171","msg":"trace[1367743086] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-98ded663-db21-4309-824b-ee0fd08a6349; range_end:; response_count:1; response_revision:28413; }","duration":"325.717598ms","start":"2026-05-11T01:45:24.355026Z","end":"2026-05-11T01:45:24.680744Z","steps":["trace[1367743086] 'agreement among raft nodes before linearized reading' (duration: 325.595046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.680796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:24.355018Z","time spent":"325.767779ms","remote":"127.0.0.1:45830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98ded663-db21-4309-824b-ee0fd08a6349\" "} {"level":"info","ts":"2026-05-11T01:45:24.925446Z","caller":"traceutil/trace.go:171","msg":"trace[2087245458] linearizableReadLoop","detail":"{readStateIndex:29708; appliedIndex:29707; }","duration":"245.053136ms","start":"2026-05-11T01:45:24.680367Z","end":"2026-05-11T01:45:24.92542Z","steps":["trace[2087245458] 'read index received' (duration: 243.063037ms)","trace[2087245458] 'applied index is now lower than readState.Index' (duration: 1.988779ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:24.925648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.989923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T01:45:24.925614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.481728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:24.925685Z","caller":"traceutil/trace.go:171","msg":"trace[1530320235] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:28414; }","duration":"312.065174ms","start":"2026-05-11T01:45:24.613609Z","end":"2026-05-11T01:45:24.925674Z","steps":["trace[1530320235] 'agreement among raft nodes before linearized reading' (duration: 311.932302ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:24.925712Z","caller":"traceutil/trace.go:171","msg":"trace[84990476] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:28414; }","duration":"274.652012ms","start":"2026-05-11T01:45:24.651038Z","end":"2026-05-11T01:45:24.92569Z","steps":["trace[84990476] 'agreement among raft nodes before linearized reading' (duration: 274.467288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.925719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:24.613582Z","time spent":"312.127975ms","remote":"127.0.0.1:49822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:45:24.925831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.405278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T01:45:24.925934Z","caller":"traceutil/trace.go:171","msg":"trace[640627970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28414; }","duration":"382.537781ms","start":"2026-05-11T01:45:24.543374Z","end":"2026-05-11T01:45:24.925912Z","steps":["trace[640627970] 'agreement among raft nodes before linearized reading' (duration: 382.284296ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:24.925916Z","caller":"traceutil/trace.go:171","msg":"trace[224873011] transaction","detail":"{read_only:false; response_revision:28414; number_of_response:1; }","duration":"395.93495ms","start":"2026-05-11T01:45:24.529931Z","end":"2026-05-11T01:45:24.925866Z","steps":["trace[224873011] 'process raft request' (duration: 393.485401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.925992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:24.543366Z","time spent":"382.612402ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T01:45:24.926115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:24.529917Z","time spent":"396.086793ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:45:24.926122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.260543ms","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:45:24.92623Z","caller":"traceutil/trace.go:171","msg":"trace[369678848] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:28414; }","duration":"560.399146ms","start":"2026-05-11T01:45:24.365808Z","end":"2026-05-11T01:45:24.926207Z","steps":["trace[369678848] 'agreement among raft nodes before linearized reading' (duration: 560.070849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:24.926298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:24.365801Z","time spent":"560.486297ms","remote":"127.0.0.1:45838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1282,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"warn","ts":"2026-05-11T01:45:25.000675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.221397ms","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:25.000763Z","caller":"traceutil/trace.go:171","msg":"trace[1754423533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28415; }","duration":"224.319719ms","start":"2026-05-11T01:45:24.776422Z","end":"2026-05-11T01:45:25.000742Z","steps":["trace[1754423533] 'agreement among raft nodes before linearized reading' (duration: 224.158416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.000741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.707146ms","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:25.000838Z","caller":"traceutil/trace.go:171","msg":"trace[1022108992] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28415; }","duration":"115.851469ms","start":"2026-05-11T01:45:24.884964Z","end":"2026-05-11T01:45:25.000816Z","steps":["trace[1022108992] 'agreement among raft nodes before linearized reading' (duration: 115.587764ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:25.000917Z","caller":"traceutil/trace.go:171","msg":"trace[844333711] transaction","detail":"{read_only:false; response_revision:28415; number_of_response:1; }","duration":"283.495808ms","start":"2026-05-11T01:45:24.717393Z","end":"2026-05-11T01:45:25.000889Z","steps":["trace[844333711] 'process raft request' (duration: 282.925487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.001078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.522872ms","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:25.001138Z","caller":"traceutil/trace.go:171","msg":"trace[1295163076] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28415; }","duration":"303.606654ms","start":"2026-05-11T01:45:24.697511Z","end":"2026-05-11T01:45:25.001118Z","steps":["trace[1295163076] 'agreement among raft nodes before linearized reading' (duration: 303.426979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.001173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:45:24.697495Z","time spent":"303.669976ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":671,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T01:45:25.135872Z","caller":"traceutil/trace.go:171","msg":"trace[1332075886] transaction","detail":"{read_only:false; response_revision:28416; number_of_response:1; }","duration":"205.237517ms","start":"2026-05-11T01:45:24.930593Z","end":"2026-05-11T01:45:25.13583Z","steps":["trace[1332075886] 'process raft request' (duration: 119.155266ms)","trace[1332075886] 'compare' (duration: 85.658211ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:25.136047Z","caller":"traceutil/trace.go:171","msg":"trace[29791964] transaction","detail":"{read_only:false; response_revision:28417; number_of_response:1; }","duration":"129.434643ms","start":"2026-05-11T01:45:25.006589Z","end":"2026-05-11T01:45:25.136023Z","steps":["trace[29791964] 'process raft request' (duration: 128.999834ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:25.135874Z","caller":"traceutil/trace.go:171","msg":"trace[280750084] linearizableReadLoop","detail":"{readStateIndex:29710; appliedIndex:29709; }","duration":"135.317811ms","start":"2026-05-11T01:45:25.000507Z","end":"2026-05-11T01:45:25.135824Z","steps":["trace[280750084] 'read index received' (duration: 49.260821ms)","trace[280750084] 'applied index is now lower than readState.Index' (duration: 86.05472ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:45:25.136345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.79555ms","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:25.136393Z","caller":"traceutil/trace.go:171","msg":"trace[1061943898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28418; }","duration":"133.908662ms","start":"2026-05-11T01:45:25.002472Z","end":"2026-05-11T01:45:25.13638Z","steps":["trace[1061943898] 'agreement among raft nodes before linearized reading' (duration: 133.79859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.136613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.036622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:25.136649Z","caller":"traceutil/trace.go:171","msg":"trace[1149750150] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:28418; }","duration":"112.096374ms","start":"2026-05-11T01:45:25.024542Z","end":"2026-05-11T01:45:25.136638Z","steps":["trace[1149750150] 'agreement among raft nodes before linearized reading' (duration: 112.031863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.137035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.880351ms","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:45:25.137095Z","caller":"traceutil/trace.go:171","msg":"trace[1866160165] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28418; }","duration":"131.935372ms","start":"2026-05-11T01:45:25.005127Z","end":"2026-05-11T01:45:25.137063Z","steps":["trace[1866160165] 'agreement among raft nodes before linearized reading' (duration: 131.539414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.13707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.854838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18ae5f83c0ae1cc6\" ","response":"range_response_count:1 size:690"} {"level":"info","ts":"2026-05-11T01:45:25.137216Z","caller":"traceutil/trace.go:171","msg":"trace[2015797018] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18ae5f83c0ae1cc6; range_end:; response_count:1; response_revision:28418; }","duration":"206.042402ms","start":"2026-05-11T01:45:24.931147Z","end":"2026-05-11T01:45:25.13719Z","steps":["trace[2015797018] 'agreement among raft nodes before linearized reading' (duration: 204.791337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.137278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.552762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9726"} {"level":"info","ts":"2026-05-11T01:45:25.137315Z","caller":"traceutil/trace.go:171","msg":"trace[1849660430] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:28418; }","duration":"205.609413ms","start":"2026-05-11T01:45:24.931696Z","end":"2026-05-11T01:45:25.137306Z","steps":["trace[1849660430] 'agreement among raft nodes before linearized reading' (duration: 204.995401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.1375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.843561ms","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:25.137551Z","caller":"traceutil/trace.go:171","msg":"trace[315881020] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28418; }","duration":"132.913442ms","start":"2026-05-11T01:45:25.004624Z","end":"2026-05-11T01:45:25.137538Z","steps":["trace[315881020] 'agreement among raft nodes before linearized reading' (duration: 132.003784ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:25.343858Z","caller":"traceutil/trace.go:171","msg":"trace[1177471633] transaction","detail":"{read_only:false; response_revision:28420; number_of_response:1; }","duration":"199.690244ms","start":"2026-05-11T01:45:25.144147Z","end":"2026-05-11T01:45:25.343837Z","steps":["trace[1177471633] 'process raft request' (duration: 155.860402ms)","trace[1177471633] 'compare' (duration: 43.672179ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:25.344408Z","caller":"traceutil/trace.go:171","msg":"trace[471751451] linearizableReadLoop","detail":"{readStateIndex:29715; appliedIndex:29712; }","duration":"199.119073ms","start":"2026-05-11T01:45:25.145261Z","end":"2026-05-11T01:45:25.344381Z","steps":["trace[471751451] 'read index received' (duration: 36.003084ms)","trace[471751451] 'applied index is now lower than readState.Index' (duration: 163.113009ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:25.344478Z","caller":"traceutil/trace.go:171","msg":"trace[378101190] transaction","detail":"{read_only:false; response_revision:28421; number_of_response:1; }","duration":"199.453129ms","start":"2026-05-11T01:45:25.144971Z","end":"2026-05-11T01:45:25.344424Z","steps":["trace[378101190] 'process raft request' (duration: 198.829217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.344572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.304876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-05-11T01:45:25.344609Z","caller":"traceutil/trace.go:171","msg":"trace[598065628] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:28422; }","duration":"199.373218ms","start":"2026-05-11T01:45:25.145225Z","end":"2026-05-11T01:45:25.344598Z","steps":["trace[598065628] 'agreement among raft nodes before linearized reading' (duration: 199.244745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.395857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.32735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:45:25.395906Z","caller":"traceutil/trace.go:171","msg":"trace[194413247] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:28423; }","duration":"227.417782ms","start":"2026-05-11T01:45:25.168476Z","end":"2026-05-11T01:45:25.395893Z","steps":["trace[194413247] 'agreement among raft nodes before linearized reading' (duration: 227.33682ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:25.535824Z","caller":"traceutil/trace.go:171","msg":"trace[1591717121] linearizableReadLoop","detail":"{readStateIndex:29718; appliedIndex:29717; }","duration":"135.356821ms","start":"2026-05-11T01:45:25.400431Z","end":"2026-05-11T01:45:25.535788Z","steps":["trace[1591717121] 'read index received' (duration: 135.179208ms)","trace[1591717121] 'applied index is now lower than readState.Index' (duration: 175.763µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:25.535886Z","caller":"traceutil/trace.go:171","msg":"trace[1304793078] transaction","detail":"{read_only:false; response_revision:28424; number_of_response:1; }","duration":"136.090046ms","start":"2026-05-11T01:45:25.399729Z","end":"2026-05-11T01:45:25.535819Z","steps":["trace[1304793078] 'process raft request' (duration: 135.79599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:25.536119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.655047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18ae5f83c26d872c\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-05-11T01:45:25.536177Z","caller":"traceutil/trace.go:171","msg":"trace[1576818326] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18ae5f83c26d872c; range_end:; response_count:1; response_revision:28424; }","duration":"135.777249ms","start":"2026-05-11T01:45:25.400382Z","end":"2026-05-11T01:45:25.536159Z","steps":["trace[1576818326] 'agreement among raft nodes before linearized reading' (duration: 135.555985ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:25.564321Z","caller":"traceutil/trace.go:171","msg":"trace[1791875096] transaction","detail":"{read_only:false; response_revision:28425; number_of_response:1; }","duration":"163.745453ms","start":"2026-05-11T01:45:25.400477Z","end":"2026-05-11T01:45:25.564222Z","steps":["trace[1791875096] 'process raft request' (duration: 163.488327ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:25.564704Z","caller":"traceutil/trace.go:171","msg":"trace[1896503358] transaction","detail":"{read_only:false; response_revision:28426; number_of_response:1; }","duration":"162.751552ms","start":"2026-05-11T01:45:25.401773Z","end":"2026-05-11T01:45:25.564525Z","steps":["trace[1896503358] 'process raft request' (duration: 162.341493ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:26.170629Z","caller":"traceutil/trace.go:171","msg":"trace[534196216] linearizableReadLoop","detail":"{readStateIndex:29723; appliedIndex:29722; }","duration":"124.096125ms","start":"2026-05-11T01:45:26.046506Z","end":"2026-05-11T01:45:26.170602Z","steps":["trace[534196216] 'read index received' (duration: 123.83243ms)","trace[534196216] 'applied index is now lower than readState.Index' (duration: 261.665µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:26.170703Z","caller":"traceutil/trace.go:171","msg":"trace[108020747] transaction","detail":"{read_only:false; response_revision:28428; number_of_response:1; }","duration":"190.722154ms","start":"2026-05-11T01:45:25.979937Z","end":"2026-05-11T01:45:26.170659Z","steps":["trace[108020747] 'process raft request' (duration: 190.481079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:26.17084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.307659ms","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:26.170885Z","caller":"traceutil/trace.go:171","msg":"trace[1238498353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28428; }","duration":"124.41221ms","start":"2026-05-11T01:45:26.046457Z","end":"2026-05-11T01:45:26.170869Z","steps":["trace[1238498353] 'agreement among raft nodes before linearized reading' (duration: 124.244247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:27.28461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.02727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98ded663-db21-4309-824b-ee0fd08a6349\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-05-11T01:45:27.284634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.414533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T01:45:27.284716Z","caller":"traceutil/trace.go:171","msg":"trace[1984681779] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-98ded663-db21-4309-824b-ee0fd08a6349; range_end:; response_count:1; response_revision:28436; }","duration":"180.178683ms","start":"2026-05-11T01:45:27.104511Z","end":"2026-05-11T01:45:27.28469Z","steps":["trace[1984681779] 'range keys from in-memory index tree' (duration: 179.802265ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:27.284738Z","caller":"traceutil/trace.go:171","msg":"trace[553905172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28436; }","duration":"142.570076ms","start":"2026-05-11T01:45:27.142144Z","end":"2026-05-11T01:45:27.284714Z","steps":["trace[553905172] 'range keys from in-memory index tree' (duration: 141.472863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:27.284659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.072227ms","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:45:27.285143Z","caller":"traceutil/trace.go:171","msg":"trace[1452552205] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:28436; }","duration":"146.674719ms","start":"2026-05-11T01:45:27.138441Z","end":"2026-05-11T01:45:27.285116Z","steps":["trace[1452552205] 'range keys from in-memory index tree' (duration: 145.816501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:45:27.284766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.080556ms","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:27.285332Z","caller":"traceutil/trace.go:171","msg":"trace[933804320] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28436; }","duration":"143.759489ms","start":"2026-05-11T01:45:27.141542Z","end":"2026-05-11T01:45:27.285301Z","steps":["trace[933804320] 'range keys from in-memory index tree' (duration: 142.73946ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:28.327493Z","caller":"traceutil/trace.go:171","msg":"trace[306541203] transaction","detail":"{read_only:false; response_revision:28447; number_of_response:1; }","duration":"131.348771ms","start":"2026-05-11T01:45:28.196118Z","end":"2026-05-11T01:45:28.327467Z","steps":["trace[306541203] 'process raft request' (duration: 91.810566ms)","trace[306541203] 'compare' (duration: 39.29562ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:31.830858Z","caller":"traceutil/trace.go:171","msg":"trace[1908043133] transaction","detail":"{read_only:false; response_revision:28495; number_of_response:1; }","duration":"171.750122ms","start":"2026-05-11T01:45:31.659077Z","end":"2026-05-11T01:45:31.830827Z","steps":["trace[1908043133] 'process raft request' (duration: 124.665426ms)","trace[1908043133] 'compare' (duration: 46.670048ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:45:31.831768Z","caller":"traceutil/trace.go:171","msg":"trace[391593630] transaction","detail":"{read_only:false; response_revision:28496; number_of_response:1; }","duration":"171.060868ms","start":"2026-05-11T01:45:31.660681Z","end":"2026-05-11T01:45:31.831742Z","steps":["trace[391593630] 'process raft request' (duration: 170.089508ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:32.054637Z","caller":"traceutil/trace.go:171","msg":"trace[1307909420] transaction","detail":"{read_only:false; response_revision:28497; number_of_response:1; }","duration":"210.240656ms","start":"2026-05-11T01:45:31.844366Z","end":"2026-05-11T01:45:32.054606Z","steps":["trace[1307909420] 'process raft request' (duration: 210.032492ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:45:52.29696Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":30007,"local-member-snapshot-index":20006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T01:45:52.302791Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30007} {"level":"info","ts":"2026-05-11T01:45:52.302909Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25007} {"level":"info","ts":"2026-05-11T01:46:03.296016Z","caller":"traceutil/trace.go:171","msg":"trace[18448863] transaction","detail":"{read_only:false; response_revision:28795; number_of_response:1; }","duration":"151.282469ms","start":"2026-05-11T01:46:03.144712Z","end":"2026-05-11T01:46:03.295994Z","steps":["trace[18448863] 'process raft request' (duration: 151.148496ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:03.770059Z","caller":"traceutil/trace.go:171","msg":"trace[917075390] transaction","detail":"{read_only:false; response_revision:28797; number_of_response:1; }","duration":"258.663368ms","start":"2026-05-11T01:46:03.511354Z","end":"2026-05-11T01:46:03.770018Z","steps":["trace[917075390] 'process raft request' (duration: 219.833948ms)","trace[917075390] 'compare' (duration: 38.651346ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:03.774481Z","caller":"traceutil/trace.go:171","msg":"trace[1814590973] transaction","detail":"{read_only:false; response_revision:28799; number_of_response:1; }","duration":"228.016901ms","start":"2026-05-11T01:46:03.546447Z","end":"2026-05-11T01:46:03.774464Z","steps":["trace[1814590973] 'process raft request' (duration: 227.96867ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:03.77459Z","caller":"traceutil/trace.go:171","msg":"trace[834280488] transaction","detail":"{read_only:false; response_revision:28798; number_of_response:1; }","duration":"229.163793ms","start":"2026-05-11T01:46:03.545354Z","end":"2026-05-11T01:46:03.774518Z","steps":["trace[834280488] 'process raft request' (duration: 228.969999ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:04.306355Z","caller":"traceutil/trace.go:171","msg":"trace[571312643] linearizableReadLoop","detail":"{readStateIndex:30111; appliedIndex:30110; }","duration":"314.460467ms","start":"2026-05-11T01:46:03.991869Z","end":"2026-05-11T01:46:04.30633Z","steps":["trace[571312643] 'read index received' (duration: 314.256703ms)","trace[571312643] 'applied index is now lower than readState.Index' (duration: 202.644µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:04.306473Z","caller":"traceutil/trace.go:171","msg":"trace[535666923] transaction","detail":"{read_only:false; response_revision:28800; number_of_response:1; }","duration":"382.786639ms","start":"2026-05-11T01:46:03.923648Z","end":"2026-05-11T01:46:04.306435Z","steps":["trace[535666923] 'process raft request' (duration: 382.508994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:04.306588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.708002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-11T01:46:04.306625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:03.923597Z","time spent":"382.950313ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:04.30663Z","caller":"traceutil/trace.go:171","msg":"trace[2049730055] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:28800; }","duration":"314.802804ms","start":"2026-05-11T01:46:03.991817Z","end":"2026-05-11T01:46:04.30662Z","steps":["trace[2049730055] 'agreement among raft nodes before linearized reading' (duration: 314.60928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:04.306654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:03.991794Z","time spent":"314.856476ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-05-11T01:46:04.306971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.747154ms","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:04.307115Z","caller":"traceutil/trace.go:171","msg":"trace[1840164264] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28800; }","duration":"102.960019ms","start":"2026-05-11T01:46:04.204122Z","end":"2026-05-11T01:46:04.307082Z","steps":["trace[1840164264] 'agreement among raft nodes before linearized reading' (duration: 102.55774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:04.572922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.966371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:04.573062Z","caller":"traceutil/trace.go:171","msg":"trace[906563969] linearizableReadLoop","detail":"{readStateIndex:30114; appliedIndex:30113; }","duration":"170.829122ms","start":"2026-05-11T01:46:04.402207Z","end":"2026-05-11T01:46:04.573036Z","steps":["trace[906563969] 'read index received' (duration: 40.581046ms)","trace[906563969] 'applied index is now lower than readState.Index' (duration: 130.246646ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:04.573268Z","caller":"traceutil/trace.go:171","msg":"trace[8053204] transaction","detail":"{read_only:false; response_revision:28803; number_of_response:1; }","duration":"233.089534ms","start":"2026-05-11T01:46:04.340146Z","end":"2026-05-11T01:46:04.573235Z","steps":["trace[8053204] 'process raft request' (duration: 102.706274ms)","trace[8053204] 'compare' (duration: 129.810428ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:04.573421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.218058ms","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:04.573463Z","caller":"traceutil/trace.go:171","msg":"trace[787814920] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28803; }","duration":"154.280649ms","start":"2026-05-11T01:46:04.419166Z","end":"2026-05-11T01:46:04.573447Z","steps":["trace[787814920] 'agreement among raft nodes before linearized reading' (duration: 154.148697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:04.573478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.229771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11492"} {"level":"info","ts":"2026-05-11T01:46:04.573584Z","caller":"traceutil/trace.go:171","msg":"trace[1040832114] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28803; }","duration":"171.389654ms","start":"2026-05-11T01:46:04.402158Z","end":"2026-05-11T01:46:04.573548Z","steps":["trace[1040832114] 'agreement among raft nodes before linearized reading' (duration: 171.071028ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:04.79727Z","caller":"traceutil/trace.go:171","msg":"trace[118604552] linearizableReadLoop","detail":"{readStateIndex:30116; appliedIndex:30115; }","duration":"211.752523ms","start":"2026-05-11T01:46:04.58549Z","end":"2026-05-11T01:46:04.797243Z","steps":["trace[118604552] 'read index received' (duration: 194.064198ms)","trace[118604552] 'applied index is now lower than readState.Index' (duration: 17.687025ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:04.79741Z","caller":"traceutil/trace.go:171","msg":"trace[1065728541] transaction","detail":"{read_only:false; response_revision:28805; number_of_response:1; }","duration":"216.365027ms","start":"2026-05-11T01:46:04.581033Z","end":"2026-05-11T01:46:04.797398Z","steps":["trace[1065728541] 'process raft request' (duration: 198.471077ms)","trace[1065728541] 'compare' (duration: 17.249727ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:04.797673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.156902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17234"} {"level":"info","ts":"2026-05-11T01:46:04.79771Z","caller":"traceutil/trace.go:171","msg":"trace[2032266754] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:28805; }","duration":"212.236484ms","start":"2026-05-11T01:46:04.585461Z","end":"2026-05-11T01:46:04.797698Z","steps":["trace[2032266754] 'agreement among raft nodes before linearized reading' (duration: 212.071131ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:05.205017Z","caller":"traceutil/trace.go:171","msg":"trace[476154111] linearizableReadLoop","detail":"{readStateIndex:30119; appliedIndex:30118; }","duration":"337.283185ms","start":"2026-05-11T01:46:04.867693Z","end":"2026-05-11T01:46:05.204976Z","steps":["trace[476154111] 'read index received' (duration: 337.178433ms)","trace[476154111] 'applied index is now lower than readState.Index' (duration: 103.012µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:05.205167Z","caller":"traceutil/trace.go:171","msg":"trace[675098250] transaction","detail":"{read_only:false; response_revision:28808; number_of_response:1; }","duration":"355.092664ms","start":"2026-05-11T01:46:04.850021Z","end":"2026-05-11T01:46:05.205113Z","steps":["trace[675098250] 'process raft request' (duration: 354.723327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:05.205278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.568432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-11T01:46:05.205354Z","caller":"traceutil/trace.go:171","msg":"trace[1652863681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28808; }","duration":"337.703084ms","start":"2026-05-11T01:46:04.867635Z","end":"2026-05-11T01:46:05.205338Z","steps":["trace[1652863681] 'agreement among raft nodes before linearized reading' (duration: 337.470209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:05.205367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:04.849999Z","time spent":"355.267487ms","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16424,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:05.205395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:04.867594Z","time spent":"337.789325ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T01:46:05.242936Z","caller":"traceutil/trace.go:171","msg":"trace[1816928047] transaction","detail":"{read_only:false; response_revision:28809; number_of_response:1; }","duration":"159.044926ms","start":"2026-05-11T01:46:05.083866Z","end":"2026-05-11T01:46:05.242911Z","steps":["trace[1816928047] 'process raft request' (duration: 158.673908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:05.243244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.704058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T01:46:05.243286Z","caller":"traceutil/trace.go:171","msg":"trace[164107208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28809; }","duration":"367.80249ms","start":"2026-05-11T01:46:04.875472Z","end":"2026-05-11T01:46:05.243274Z","steps":["trace[164107208] 'agreement among raft nodes before linearized reading' (duration: 367.670388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:05.243321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:04.875444Z","time spent":"367.869591ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T01:46:05.243516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.853721ms","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:05.243552Z","caller":"traceutil/trace.go:171","msg":"trace[918703060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28809; }","duration":"372.931343ms","start":"2026-05-11T01:46:04.870612Z","end":"2026-05-11T01:46:05.243543Z","steps":["trace[918703060] 'agreement among raft nodes before linearized reading' (duration: 372.825341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:05.243579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:04.870583Z","time spent":"372.990003ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T01:46:05.517694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.83027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:05.517838Z","caller":"traceutil/trace.go:171","msg":"trace[1027734804] transaction","detail":"{read_only:false; response_revision:28810; number_of_response:1; }","duration":"306.689782ms","start":"2026-05-11T01:46:05.211109Z","end":"2026-05-11T01:46:05.517799Z","steps":["trace[1027734804] 'process raft request' (duration: 159.662328ms)","trace[1027734804] 'compare' (duration: 146.657417ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:05.517962Z","caller":"traceutil/trace.go:171","msg":"trace[1028703062] transaction","detail":"{read_only:false; response_revision:28813; number_of_response:1; }","duration":"215.602811ms","start":"2026-05-11T01:46:05.302345Z","end":"2026-05-11T01:46:05.517948Z","steps":["trace[1028703062] 'process raft request' (duration: 215.563941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:05.517985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:05.211087Z","time spent":"306.817345ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:05.518134Z","caller":"traceutil/trace.go:171","msg":"trace[948927168] transaction","detail":"{read_only:false; response_revision:28811; number_of_response:1; }","duration":"270.127388ms","start":"2026-05-11T01:46:05.247999Z","end":"2026-05-11T01:46:05.518127Z","steps":["trace[948927168] 'process raft request' (duration: 269.798001ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:05.51816Z","caller":"traceutil/trace.go:171","msg":"trace[1571801220] transaction","detail":"{read_only:false; response_revision:28812; number_of_response:1; }","duration":"267.604266ms","start":"2026-05-11T01:46:05.250548Z","end":"2026-05-11T01:46:05.518152Z","steps":["trace[1571801220] 'process raft request' (duration: 267.327201ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:05.518148Z","caller":"traceutil/trace.go:171","msg":"trace[1611399108] linearizableReadLoop","detail":"{readStateIndex:30121; appliedIndex:30120; }","duration":"275.424864ms","start":"2026-05-11T01:46:05.242699Z","end":"2026-05-11T01:46:05.518124Z","steps":["trace[1611399108] 'read index received' (duration: 128.062693ms)","trace[1611399108] 'applied index is now lower than readState.Index' (duration: 147.360021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:05.518252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.928127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:05.5183Z","caller":"traceutil/trace.go:171","msg":"trace[1102299997] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:28813; }","duration":"307.005068ms","start":"2026-05-11T01:46:05.21128Z","end":"2026-05-11T01:46:05.518285Z","steps":["trace[1102299997] 'agreement among raft nodes before linearized reading' (duration: 306.920316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:05.518335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:05.211272Z","time spent":"307.054619ms","remote":"127.0.0.1:45740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":30,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"warn","ts":"2026-05-11T01:46:05.669935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.847659ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-11T01:46:05.697066Z","caller":"traceutil/trace.go:171","msg":"trace[1295987609] linearizableReadLoop","detail":"{readStateIndex:30126; appliedIndex:30124; }","duration":"168.379313ms","start":"2026-05-11T01:46:05.52866Z","end":"2026-05-11T01:46:05.697039Z","steps":["trace[1295987609] 'read index received' (duration: 10.772186ms)","trace[1295987609] 'applied index is now lower than readState.Index' (duration: 157.605747ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:05.697111Z","caller":"traceutil/trace.go:171","msg":"trace[447238210] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28813; }","duration":"176.621378ms","start":"2026-05-11T01:46:05.520439Z","end":"2026-05-11T01:46:05.69706Z","steps":["trace[447238210] 'process raft request' (duration: 176.483625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:05.697321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.638417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T01:46:05.697392Z","caller":"traceutil/trace.go:171","msg":"trace[6205306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28813; }","duration":"168.77259ms","start":"2026-05-11T01:46:05.528604Z","end":"2026-05-11T01:46:05.697376Z","steps":["trace[6205306] 'agreement among raft nodes before linearized reading' (duration: 168.523415ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:05.812352Z","caller":"traceutil/trace.go:171","msg":"trace[1991596415] transaction","detail":"{read_only:false; response_revision:28814; number_of_response:1; }","duration":"109.994579ms","start":"2026-05-11T01:46:05.702332Z","end":"2026-05-11T01:46:05.812326Z","steps":["trace[1991596415] 'process raft request' (duration: 109.795365ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:06.038025Z","caller":"traceutil/trace.go:171","msg":"trace[2053355624] transaction","detail":"{read_only:false; response_revision:28815; number_of_response:1; }","duration":"329.55947ms","start":"2026-05-11T01:46:05.708426Z","end":"2026-05-11T01:46:06.037985Z","steps":["trace[2053355624] 'process raft request' (duration: 258.436732ms)","trace[2053355624] 'compare' (duration: 70.799072ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:06.038031Z","caller":"traceutil/trace.go:171","msg":"trace[2053152565] linearizableReadLoop","detail":"{readStateIndex:30128; appliedIndex:30127; }","duration":"263.109856ms","start":"2026-05-11T01:46:05.774889Z","end":"2026-05-11T01:46:06.037999Z","steps":["trace[2053152565] 'read index received' (duration: 38.227978ms)","trace[2053152565] 'applied index is now lower than readState.Index' (duration: 224.879988ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:06.038123Z","caller":"traceutil/trace.go:171","msg":"trace[1878903057] transaction","detail":"{read_only:false; response_revision:28816; number_of_response:1; }","duration":"258.632106ms","start":"2026-05-11T01:46:05.779472Z","end":"2026-05-11T01:46:06.038104Z","steps":["trace[1878903057] 'process raft request' (duration: 258.374021ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:06.038216Z","caller":"traceutil/trace.go:171","msg":"trace[1011648341] transaction","detail":"{read_only:false; response_revision:28817; number_of_response:1; }","duration":"258.700617ms","start":"2026-05-11T01:46:05.7795Z","end":"2026-05-11T01:46:06.038201Z","steps":["trace[1011648341] 'process raft request' (duration: 258.434501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.038223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:05.708384Z","time spent":"329.734344ms","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16838,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:06.038319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.288508ms","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:06.038372Z","caller":"traceutil/trace.go:171","msg":"trace[1118327527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28817; }","duration":"260.385491ms","start":"2026-05-11T01:46:05.777975Z","end":"2026-05-11T01:46:06.03836Z","steps":["trace[1118327527] 'agreement among raft nodes before linearized reading' (duration: 260.276648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.038645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.751219ms","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:46:06.038678Z","caller":"traceutil/trace.go:171","msg":"trace[1750259422] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28817; }","duration":"263.808251ms","start":"2026-05-11T01:46:05.774861Z","end":"2026-05-11T01:46:06.038669Z","steps":["trace[1750259422] 'agreement among raft nodes before linearized reading' (duration: 263.668288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.038676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.514646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:06.038727Z","caller":"traceutil/trace.go:171","msg":"trace[1611581875] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:28817; }","duration":"169.608568ms","start":"2026-05-11T01:46:05.869102Z","end":"2026-05-11T01:46:06.038711Z","steps":["trace[1611581875] 'agreement among raft nodes before linearized reading' (duration: 169.470105ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:06.239208Z","caller":"traceutil/trace.go:171","msg":"trace[1061340173] transaction","detail":"{read_only:false; response_revision:28818; number_of_response:1; }","duration":"183.16573ms","start":"2026-05-11T01:46:06.056005Z","end":"2026-05-11T01:46:06.239171Z","steps":["trace[1061340173] 'process raft request' (duration: 138.177366ms)","trace[1061340173] 'compare' (duration: 44.78511ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:06.31265Z","caller":"traceutil/trace.go:171","msg":"trace[860144710] linearizableReadLoop","detail":"{readStateIndex:30132; appliedIndex:30130; }","duration":"225.403428ms","start":"2026-05-11T01:46:06.087201Z","end":"2026-05-11T01:46:06.312605Z","steps":["trace[860144710] 'read index received' (duration: 106.997549ms)","trace[860144710] 'applied index is now lower than readState.Index' (duration: 118.404809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:06.313065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.98318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:46:06.313133Z","caller":"traceutil/trace.go:171","msg":"trace[1853751032] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:28819; }","duration":"219.076622ms","start":"2026-05-11T01:46:06.094025Z","end":"2026-05-11T01:46:06.313102Z","steps":["trace[1853751032] 'agreement among raft nodes before linearized reading' (duration: 218.956329ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:06.31312Z","caller":"traceutil/trace.go:171","msg":"trace[1795067425] transaction","detail":"{read_only:false; response_revision:28819; number_of_response:1; }","duration":"254.616235ms","start":"2026-05-11T01:46:06.058453Z","end":"2026-05-11T01:46:06.31307Z","steps":["trace[1795067425] 'process raft request' (duration: 254.020342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.313285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.015822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11492"} {"level":"info","ts":"2026-05-11T01:46:06.313439Z","caller":"traceutil/trace.go:171","msg":"trace[2097394978] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28819; }","duration":"226.250266ms","start":"2026-05-11T01:46:06.087144Z","end":"2026-05-11T01:46:06.313394Z","steps":["trace[2097394978] 'agreement among raft nodes before linearized reading' (duration: 225.740796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.601338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.882029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:06.60146Z","caller":"traceutil/trace.go:171","msg":"trace[418081912] linearizableReadLoop","detail":"{readStateIndex:30133; appliedIndex:30132; }","duration":"181.508526ms","start":"2026-05-11T01:46:06.419931Z","end":"2026-05-11T01:46:06.601439Z","steps":["trace[418081912] 'read index received' (duration: 47.415882ms)","trace[418081912] 'applied index is now lower than readState.Index' (duration: 134.090994ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:06.601562Z","caller":"traceutil/trace.go:171","msg":"trace[1306488245] transaction","detail":"{read_only:false; response_revision:28820; number_of_response:1; }","duration":"263.656557ms","start":"2026-05-11T01:46:06.337893Z","end":"2026-05-11T01:46:06.601549Z","steps":["trace[1306488245] 'process raft request' (duration: 129.438401ms)","trace[1306488245] 'compare' (duration: 133.718646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:06.601712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.829901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T01:46:06.601832Z","caller":"traceutil/trace.go:171","msg":"trace[305717824] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:28820; }","duration":"154.016925ms","start":"2026-05-11T01:46:06.447787Z","end":"2026-05-11T01:46:06.601804Z","steps":["trace[305717824] 'agreement among raft nodes before linearized reading' (duration: 153.821391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.602198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.273032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:06.602249Z","caller":"traceutil/trace.go:171","msg":"trace[43152798] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:28820; }","duration":"182.378094ms","start":"2026-05-11T01:46:06.419855Z","end":"2026-05-11T01:46:06.602233Z","steps":["trace[43152798] 'agreement among raft nodes before linearized reading' (duration: 182.300082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.602814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.828606ms","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:06.602986Z","caller":"traceutil/trace.go:171","msg":"trace[1826762550] 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:28820; }","duration":"106.172233ms","start":"2026-05-11T01:46:06.496776Z","end":"2026-05-11T01:46:06.602949Z","steps":["trace[1826762550] 'agreement among raft nodes before linearized reading' (duration: 105.808576ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:06.962021Z","caller":"traceutil/trace.go:171","msg":"trace[555076088] linearizableReadLoop","detail":"{readStateIndex:30135; appliedIndex:30133; }","duration":"336.571771ms","start":"2026-05-11T01:46:06.625419Z","end":"2026-05-11T01:46:06.961991Z","steps":["trace[555076088] 'read index received' (duration: 4.511951ms)","trace[555076088] 'applied index is now lower than readState.Index' (duration: 332.05817ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:06.96218Z","caller":"traceutil/trace.go:171","msg":"trace[1072506623] transaction","detail":"{read_only:false; response_revision:28822; number_of_response:1; }","duration":"347.589553ms","start":"2026-05-11T01:46:06.614581Z","end":"2026-05-11T01:46:06.96217Z","steps":["trace[1072506623] 'process raft request' (duration: 347.227186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.962311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:06.614541Z","time spent":"347.662374ms","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:06.962403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.060213ms","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:06.962484Z","caller":"traceutil/trace.go:171","msg":"trace[775925853] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28822; }","duration":"134.188565ms","start":"2026-05-11T01:46:06.828272Z","end":"2026-05-11T01:46:06.96246Z","steps":["trace[775925853] 'agreement among raft nodes before linearized reading' (duration: 133.995522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.962682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.259535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T01:46:06.962722Z","caller":"traceutil/trace.go:171","msg":"trace[457996585] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:28822; }","duration":"337.339896ms","start":"2026-05-11T01:46:06.62537Z","end":"2026-05-11T01:46:06.96271Z","steps":["trace[457996585] 'agreement among raft nodes before linearized reading' (duration: 337.225204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:06.96275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:06.625351Z","time spent":"337.392318ms","remote":"127.0.0.1:49778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:46:07.977197Z","caller":"traceutil/trace.go:171","msg":"trace[352547624] transaction","detail":"{read_only:false; response_revision:28831; number_of_response:1; }","duration":"157.523265ms","start":"2026-05-11T01:46:07.819655Z","end":"2026-05-11T01:46:07.977178Z","steps":["trace[352547624] 'process raft request' (duration: 157.362902ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:08.1504Z","caller":"traceutil/trace.go:171","msg":"trace[1357690199] transaction","detail":"{read_only:false; response_revision:28832; number_of_response:1; }","duration":"106.664683ms","start":"2026-05-11T01:46:08.043706Z","end":"2026-05-11T01:46:08.150371Z","steps":["trace[1357690199] 'process raft request' (duration: 85.794764ms)","trace[1357690199] 'compare' (duration: 20.761297ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:08.151965Z","caller":"traceutil/trace.go:171","msg":"trace[458408783] transaction","detail":"{read_only:false; response_revision:28833; number_of_response:1; }","duration":"106.768865ms","start":"2026-05-11T01:46:08.045158Z","end":"2026-05-11T01:46:08.151927Z","steps":["trace[458408783] 'process raft request' (duration: 106.602002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.917661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.130582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:13.917888Z","caller":"traceutil/trace.go:171","msg":"trace[1224270504] linearizableReadLoop","detail":"{readStateIndex:30198; appliedIndex:30196; }","duration":"118.559041ms","start":"2026-05-11T01:46:13.799308Z","end":"2026-05-11T01:46:13.917867Z","steps":["trace[1224270504] 'read index received' (duration: 18.148734ms)","trace[1224270504] 'applied index is now lower than readState.Index' (duration: 100.408557ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:13.917931Z","caller":"traceutil/trace.go:171","msg":"trace[515016135] transaction","detail":"{read_only:false; response_revision:28882; number_of_response:1; }","duration":"265.126826ms","start":"2026-05-11T01:46:13.65276Z","end":"2026-05-11T01:46:13.917887Z","steps":["trace[515016135] 'process raft request' (duration: 164.657597ms)","trace[515016135] 'compare' (duration: 99.957139ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:13.918007Z","caller":"traceutil/trace.go:171","msg":"trace[909319729] transaction","detail":"{read_only:false; response_revision:28883; number_of_response:1; }","duration":"265.080485ms","start":"2026-05-11T01:46:13.65291Z","end":"2026-05-11T01:46:13.91799Z","steps":["trace[909319729] 'process raft request' (duration: 264.887181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:13.918195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.879488ms","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":"info","ts":"2026-05-11T01:46:13.918231Z","caller":"traceutil/trace.go:171","msg":"trace[1732104991] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:28883; }","duration":"118.946469ms","start":"2026-05-11T01:46:13.799268Z","end":"2026-05-11T01:46:13.918215Z","steps":["trace[1732104991] 'agreement among raft nodes before linearized reading' (duration: 118.883398ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:22.827716Z","caller":"traceutil/trace.go:171","msg":"trace[1681060436] transaction","detail":"{read_only:false; response_revision:28961; number_of_response:1; }","duration":"103.322226ms","start":"2026-05-11T01:46:22.724363Z","end":"2026-05-11T01:46:22.827685Z","steps":["trace[1681060436] 'process raft request' (duration: 101.261475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:23.488339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.795899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:23.488467Z","caller":"traceutil/trace.go:171","msg":"trace[128905768] transaction","detail":"{read_only:false; response_revision:28963; number_of_response:1; }","duration":"209.297965ms","start":"2026-05-11T01:46:23.279148Z","end":"2026-05-11T01:46:23.488446Z","steps":["trace[128905768] 'process raft request' (duration: 70.267212ms)","trace[128905768] 'compare' (duration: 138.428121ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:23.529101Z","caller":"traceutil/trace.go:171","msg":"trace[2004076010] transaction","detail":"{read_only:false; response_revision:28964; number_of_response:1; }","duration":"249.728467ms","start":"2026-05-11T01:46:23.279358Z","end":"2026-05-11T01:46:23.529086Z","steps":["trace[2004076010] 'process raft request' (duration: 249.483422ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:23.529088Z","caller":"traceutil/trace.go:171","msg":"trace[1282816491] transaction","detail":"{read_only:false; response_revision:28965; number_of_response:1; }","duration":"249.36441ms","start":"2026-05-11T01:46:23.279695Z","end":"2026-05-11T01:46:23.52906Z","steps":["trace[1282816491] 'process raft request' (duration: 249.309409ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:23.793023Z","caller":"traceutil/trace.go:171","msg":"trace[1660001544] linearizableReadLoop","detail":"{readStateIndex:30285; appliedIndex:30282; }","duration":"427.733953ms","start":"2026-05-11T01:46:23.36525Z","end":"2026-05-11T01:46:23.792983Z","steps":["trace[1660001544] 'read index received' (duration: 163.611837ms)","trace[1660001544] 'applied index is now lower than readState.Index' (duration: 264.115836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:23.793152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.880745ms","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:46:23.793233Z","caller":"traceutil/trace.go:171","msg":"trace[957700106] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:28966; }","duration":"428.015968ms","start":"2026-05-11T01:46:23.365197Z","end":"2026-05-11T01:46:23.793213Z","steps":["trace[957700106] 'agreement among raft nodes before linearized reading' (duration: 427.874216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:23.793267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:23.365173Z","time spent":"428.087289ms","remote":"127.0.0.1:38258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:46:23.793064Z","caller":"traceutil/trace.go:171","msg":"trace[1944368930] transaction","detail":"{read_only:false; response_revision:28966; number_of_response:1; }","duration":"442.753295ms","start":"2026-05-11T01:46:23.35027Z","end":"2026-05-11T01:46:23.793023Z","steps":["trace[1944368930] 'process raft request' (duration: 416.342375ms)","trace[1944368930] 'compare' (duration: 26.119514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:23.793518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:23.350233Z","time spent":"443.150474ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:46:23.793824Z","caller":"traceutil/trace.go:171","msg":"trace[1732148010] transaction","detail":"{read_only:false; response_revision:28967; number_of_response:1; }","duration":"108.593562ms","start":"2026-05-11T01:46:23.685194Z","end":"2026-05-11T01:46:23.793788Z","steps":["trace[1732148010] 'process raft request' (duration: 108.018659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:23.794101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.373113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:35 size:282505"} {"level":"warn","ts":"2026-05-11T01:46:23.794103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.716832ms","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:23.794133Z","caller":"traceutil/trace.go:171","msg":"trace[2078847587] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:35; response_revision:28967; }","duration":"389.454874ms","start":"2026-05-11T01:46:23.40467Z","end":"2026-05-11T01:46:23.794125Z","steps":["trace[2078847587] 'agreement among raft nodes before linearized reading' (duration: 389.057597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:23.794159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:23.404654Z","time spent":"389.498675ms","remote":"127.0.0.1:46140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":35,"response size":282529,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-05-11T01:46:23.794159Z","caller":"traceutil/trace.go:171","msg":"trace[610724460] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28967; }","duration":"300.820915ms","start":"2026-05-11T01:46:23.493317Z","end":"2026-05-11T01:46:23.794138Z","steps":["trace[610724460] 'agreement among raft nodes before linearized reading' (duration: 300.643831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:23.794197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:23.493294Z","time spent":"300.896116ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T01:46:24.058825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.676474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:24.058966Z","caller":"traceutil/trace.go:171","msg":"trace[1308169419] transaction","detail":"{read_only:false; response_revision:28968; number_of_response:1; }","duration":"260.711728ms","start":"2026-05-11T01:46:23.798228Z","end":"2026-05-11T01:46:24.058939Z","steps":["trace[1308169419] 'process raft request' (duration: 137.809889ms)","trace[1308169419] 'compare' (duration: 122.43981ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:24.381364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.156071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:24.381667Z","caller":"traceutil/trace.go:171","msg":"trace[1519449020] transaction","detail":"{read_only:false; response_revision:28970; number_of_response:1; }","duration":"171.23566ms","start":"2026-05-11T01:46:24.210405Z","end":"2026-05-11T01:46:24.38164Z","steps":["trace[1519449020] 'process raft request' (duration: 171.134978ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:24.381714Z","caller":"traceutil/trace.go:171","msg":"trace[2089357743] linearizableReadLoop","detail":"{readStateIndex:30288; appliedIndex:30287; }","duration":"409.213782ms","start":"2026-05-11T01:46:23.972467Z","end":"2026-05-11T01:46:24.381681Z","steps":["trace[2089357743] 'read index received' (duration: 200.635761ms)","trace[2089357743] 'applied index is now lower than readState.Index' (duration: 208.575831ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:24.381793Z","caller":"traceutil/trace.go:171","msg":"trace[540442207] transaction","detail":"{read_only:false; response_revision:28969; number_of_response:1; }","duration":"583.32925ms","start":"2026-05-11T01:46:23.798424Z","end":"2026-05-11T01:46:24.381753Z","steps":["trace[540442207] 'process raft request' (duration: 374.670118ms)","trace[540442207] 'compare' (duration: 208.002678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:24.381964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.477137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-11T01:46:24.382011Z","caller":"traceutil/trace.go:171","msg":"trace[1967564128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28970; }","duration":"409.581729ms","start":"2026-05-11T01:46:23.972415Z","end":"2026-05-11T01:46:24.381997Z","steps":["trace[1967564128] 'agreement among raft nodes before linearized reading' (duration: 409.412386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:24.382008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:23.798397Z","time spent":"583.478543ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:46:24.382049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:23.972389Z","time spent":"409.649881ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T01:46:24.405246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.527759ms","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:24.405322Z","caller":"traceutil/trace.go:171","msg":"trace[845457689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28971; }","duration":"425.972908ms","start":"2026-05-11T01:46:23.979328Z","end":"2026-05-11T01:46:24.405301Z","steps":["trace[845457689] 'agreement among raft nodes before linearized reading' (duration: 425.749344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:24.405363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:46:23.979303Z","time spent":"426.051159ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T01:46:24.40541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.046675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-11T01:46:24.405399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.893992ms","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:24.405476Z","caller":"traceutil/trace.go:171","msg":"trace[1362973353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28971; }","duration":"266.126776ms","start":"2026-05-11T01:46:24.139332Z","end":"2026-05-11T01:46:24.405458Z","steps":["trace[1362973353] 'agreement among raft nodes before linearized reading' (duration: 265.914062ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:24.405509Z","caller":"traceutil/trace.go:171","msg":"trace[1293951806] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:28971; }","duration":"270.081006ms","start":"2026-05-11T01:46:24.135402Z","end":"2026-05-11T01:46:24.405483Z","steps":["trace[1293951806] 'agreement among raft nodes before linearized reading' (duration: 269.857211ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:24.405936Z","caller":"traceutil/trace.go:171","msg":"trace[1465343848] transaction","detail":"{read_only:false; response_revision:28971; number_of_response:1; }","duration":"179.790852ms","start":"2026-05-11T01:46:24.226125Z","end":"2026-05-11T01:46:24.405916Z","steps":["trace[1465343848] 'process raft request' (duration: 178.744611ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:24.691725Z","caller":"traceutil/trace.go:171","msg":"trace[1369079414] transaction","detail":"{read_only:false; response_revision:28973; number_of_response:1; }","duration":"280.610207ms","start":"2026-05-11T01:46:24.411078Z","end":"2026-05-11T01:46:24.691688Z","steps":["trace[1369079414] 'process raft request' (duration: 280.040606ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:24.692041Z","caller":"traceutil/trace.go:171","msg":"trace[77497959] transaction","detail":"{read_only:false; response_revision:28974; number_of_response:1; }","duration":"280.839462ms","start":"2026-05-11T01:46:24.411165Z","end":"2026-05-11T01:46:24.692005Z","steps":["trace[77497959] 'process raft request' (duration: 280.439684ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:24.692467Z","caller":"traceutil/trace.go:171","msg":"trace[1999327223] transaction","detail":"{read_only:false; response_revision:28975; number_of_response:1; }","duration":"278.616177ms","start":"2026-05-11T01:46:24.413843Z","end":"2026-05-11T01:46:24.692459Z","steps":["trace[1999327223] 'process raft request' (duration: 278.050795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:25.036744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.708716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:25.0369Z","caller":"traceutil/trace.go:171","msg":"trace[1490692354] linearizableReadLoop","detail":"{readStateIndex:30296; appliedIndex:30295; }","duration":"203.960517ms","start":"2026-05-11T01:46:24.832918Z","end":"2026-05-11T01:46:25.036878Z","steps":["trace[1490692354] 'read index received' (duration: 71.980436ms)","trace[1490692354] 'applied index is now lower than readState.Index' (duration: 131.978061ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:25.036983Z","caller":"traceutil/trace.go:171","msg":"trace[1977793301] transaction","detail":"{read_only:false; response_revision:28977; number_of_response:1; }","duration":"206.11428ms","start":"2026-05-11T01:46:24.83083Z","end":"2026-05-11T01:46:25.036945Z","steps":["trace[1977793301] 'process raft request' (duration: 74.082528ms)","trace[1977793301] 'compare' (duration: 131.523812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:25.037079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.165951ms","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:46:25.037118Z","caller":"traceutil/trace.go:171","msg":"trace[963856397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28977; }","duration":"204.236853ms","start":"2026-05-11T01:46:24.83287Z","end":"2026-05-11T01:46:25.037107Z","steps":["trace[963856397] 'agreement among raft nodes before linearized reading' (duration: 204.067059ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:25.824592Z","caller":"traceutil/trace.go:171","msg":"trace[1999898876] transaction","detail":"{read_only:false; response_revision:28980; number_of_response:1; }","duration":"231.693135ms","start":"2026-05-11T01:46:25.59287Z","end":"2026-05-11T01:46:25.824563Z","steps":["trace[1999898876] 'process raft request' (duration: 231.338647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:26.071659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.438842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T01:46:26.0719Z","caller":"traceutil/trace.go:171","msg":"trace[1401625481] linearizableReadLoop","detail":"{readStateIndex:30302; appliedIndex:30300; }","duration":"238.100813ms","start":"2026-05-11T01:46:25.833775Z","end":"2026-05-11T01:46:26.071876Z","steps":["trace[1401625481] 'read index received' (duration: 102.202133ms)","trace[1401625481] 'applied index is now lower than readState.Index' (duration: 135.89677ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:26.072032Z","caller":"traceutil/trace.go:171","msg":"trace[1886446834] transaction","detail":"{read_only:false; response_revision:28982; number_of_response:1; }","duration":"265.317061ms","start":"2026-05-11T01:46:25.806699Z","end":"2026-05-11T01:46:26.072016Z","steps":["trace[1886446834] 'process raft request' (duration: 265.079006ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:26.072298Z","caller":"traceutil/trace.go:171","msg":"trace[1720152157] transaction","detail":"{read_only:false; response_revision:28981; number_of_response:1; }","duration":"274.142038ms","start":"2026-05-11T01:46:25.798125Z","end":"2026-05-11T01:46:26.072267Z","steps":["trace[1720152157] 'process raft request' (duration: 137.946621ms)","trace[1720152157] 'compare' (duration: 134.556384ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:46:26.072323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.540392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T01:46:26.072566Z","caller":"traceutil/trace.go:171","msg":"trace[658214545] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28982; }","duration":"238.781157ms","start":"2026-05-11T01:46:25.833767Z","end":"2026-05-11T01:46:26.072548Z","steps":["trace[658214545] 'agreement among raft nodes before linearized reading' (duration: 238.414759ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:26.253391Z","caller":"traceutil/trace.go:171","msg":"trace[523809057] transaction","detail":"{read_only:false; response_revision:28984; number_of_response:1; }","duration":"175.094138ms","start":"2026-05-11T01:46:26.07826Z","end":"2026-05-11T01:46:26.253354Z","steps":["trace[523809057] 'process raft request' (duration: 143.813179ms)","trace[523809057] 'compare' (duration: 31.106196ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:42.92811Z","caller":"traceutil/trace.go:171","msg":"trace[850803638] linearizableReadLoop","detail":"{readStateIndex:30616; appliedIndex:30615; }","duration":"151.209517ms","start":"2026-05-11T01:46:42.776872Z","end":"2026-05-11T01:46:42.928082Z","steps":["trace[850803638] 'read index received' (duration: 150.996703ms)","trace[850803638] 'applied index is now lower than readState.Index' (duration: 211.354µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:42.928165Z","caller":"traceutil/trace.go:171","msg":"trace[2052629598] transaction","detail":"{read_only:false; response_revision:29287; number_of_response:1; }","duration":"160.33451ms","start":"2026-05-11T01:46:42.7678Z","end":"2026-05-11T01:46:42.928135Z","steps":["trace[2052629598] 'process raft request' (duration: 160.062215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:42.928421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.509664ms","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:42.928554Z","caller":"traceutil/trace.go:171","msg":"trace[509466445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29287; }","duration":"151.717598ms","start":"2026-05-11T01:46:42.776804Z","end":"2026-05-11T01:46:42.928521Z","steps":["trace[509466445] 'agreement among raft nodes before linearized reading' (duration: 151.465583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:42.928607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.85873ms","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:46:42.928658Z","caller":"traceutil/trace.go:171","msg":"trace[1599790341] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29287; }","duration":"133.964021ms","start":"2026-05-11T01:46:42.794682Z","end":"2026-05-11T01:46:42.928646Z","steps":["trace[1599790341] 'agreement among raft nodes before linearized reading' (duration: 133.766728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:42.928829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.090494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T01:46:42.928947Z","caller":"traceutil/trace.go:171","msg":"trace[516393481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29287; }","duration":"140.238908ms","start":"2026-05-11T01:46:42.78868Z","end":"2026-05-11T01:46:42.928919Z","steps":["trace[516393481] 'agreement among raft nodes before linearized reading' (duration: 139.907951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:46:42.929356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.980482ms","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:42.92943Z","caller":"traceutil/trace.go:171","msg":"trace[1314032932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29287; }","duration":"141.094775ms","start":"2026-05-11T01:46:42.788321Z","end":"2026-05-11T01:46:42.929416Z","steps":["trace[1314032932] 'agreement among raft nodes before linearized reading' (duration: 139.995303ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:43.062942Z","caller":"traceutil/trace.go:171","msg":"trace[105778865] transaction","detail":"{read_only:false; response_revision:29288; number_of_response:1; }","duration":"126.983641ms","start":"2026-05-11T01:46:42.935934Z","end":"2026-05-11T01:46:43.062918Z","steps":["trace[105778865] 'process raft request' (duration: 59.155579ms)","trace[105778865] 'compare' (duration: 67.529156ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:46:43.100529Z","caller":"traceutil/trace.go:171","msg":"trace[65867572] transaction","detail":"{read_only:false; response_revision:29289; number_of_response:1; }","duration":"163.18205ms","start":"2026-05-11T01:46:42.937312Z","end":"2026-05-11T01:46:43.100494Z","steps":["trace[65867572] 'process raft request' (duration: 162.926323ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:46:43.100774Z","caller":"traceutil/trace.go:171","msg":"trace[693668575] transaction","detail":"{read_only:false; response_revision:29290; number_of_response:1; }","duration":"161.78674ms","start":"2026-05-11T01:46:42.938957Z","end":"2026-05-11T01:46:43.100744Z","steps":["trace[693668575] 'process raft request' (duration: 161.471494ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:48:47.097407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27250} {"level":"info","ts":"2026-05-11T01:48:47.263778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27250,"took":"160.834931ms","hash":1322480955,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":36487168,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-11T01:48:47.263857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1322480955,"revision":27250,"compact-revision":23667} {"level":"info","ts":"2026-05-11T01:53:47.104764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30589} {"level":"info","ts":"2026-05-11T01:53:47.265083Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30589,"took":"153.868512ms","hash":2720279280,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":36118528,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-11T01:53:47.265131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2720279280,"revision":30589,"compact-revision":27250} {"level":"info","ts":"2026-05-11T01:58:47.11389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33535} {"level":"info","ts":"2026-05-11T01:58:47.255842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33535,"took":"137.309911ms","hash":3720108653,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":34017280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T01:58:47.25592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3720108653,"revision":33535,"compact-revision":30589} {"level":"info","ts":"2026-05-11T02:02:26.41699Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":40008,"local-member-snapshot-index":30007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T02:02:26.423064Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40008} {"level":"info","ts":"2026-05-11T02:02:26.423199Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35008} {"level":"info","ts":"2026-05-11T02:03:47.120571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36203} {"level":"info","ts":"2026-05-11T02:03:47.258071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36203,"took":"132.724456ms","hash":3712774591,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":34578432,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-11T02:03:47.258167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3712774591,"revision":36203,"compact-revision":33535} {"level":"info","ts":"2026-05-11T02:04:08.208401Z","caller":"traceutil/trace.go:171","msg":"trace[1867609951] transaction","detail":"{read_only:false; response_revision:39225; number_of_response:1; }","duration":"106.080789ms","start":"2026-05-11T02:04:08.102287Z","end":"2026-05-11T02:04:08.208368Z","steps":["trace[1867609951] 'process raft request' (duration: 105.874085ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T02:08:47.128285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39021} {"level":"info","ts":"2026-05-11T02:08:47.274273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39021,"took":"141.822708ms","hash":1425136291,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":34873344,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-11T02:08:47.274345Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1425136291,"revision":39021,"compact-revision":36203} {"level":"info","ts":"2026-05-11T02:13:47.134271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41758} {"level":"info","ts":"2026-05-11T02:13:47.279981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41758,"took":"140.557703ms","hash":1678176514,"current-db-size-bytes":61038592,"current-db-size":"61 MB","current-db-size-in-use-bytes":33644544,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T02:13:47.280135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1678176514,"revision":41758,"compact-revision":39021} {"level":"warn","ts":"2026-05-11T02:17:18.661539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.696166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T02:17:18.661688Z","caller":"traceutil/trace.go:171","msg":"trace[964035793] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:46412; }","duration":"147.930341ms","start":"2026-05-11T02:17:18.51373Z","end":"2026-05-11T02:17:18.66166Z","steps":["trace[964035793] 'count revisions from in-memory index tree' (duration: 147.560833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T02:17:18.662008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.670864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-nova\" ","response":"range_response_count:1 size:1432"} {"level":"info","ts":"2026-05-11T02:17:18.662115Z","caller":"traceutil/trace.go:171","msg":"trace[1704815869] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-nova; range_end:; response_count:1; response_revision:46412; }","duration":"239.868548ms","start":"2026-05-11T02:17:18.422222Z","end":"2026-05-11T02:17:18.662091Z","steps":["trace[1704815869] 'range keys from in-memory index tree' (duration: 239.534992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T02:17:18.662414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.759429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-11T02:17:18.662471Z","caller":"traceutil/trace.go:171","msg":"trace[2088638741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:46412; }","duration":"285.854591ms","start":"2026-05-11T02:17:18.376607Z","end":"2026-05-11T02:17:18.662462Z","steps":["trace[2088638741] 'range keys from in-memory index tree' (duration: 285.550725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T02:17:18.662479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.618345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T02:17:18.662532Z","caller":"traceutil/trace.go:171","msg":"trace[841200736] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:46412; }","duration":"274.726487ms","start":"2026-05-11T02:17:18.387789Z","end":"2026-05-11T02:17:18.662515Z","steps":["trace[841200736] 'count revisions from in-memory index tree' (duration: 274.511923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T02:17:18.66517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.002936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/keepalived-b7t5z.18ae5fc5465933fe\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:46412 ","response":"range_response_count:1000 size:770049"} {"level":"info","ts":"2026-05-11T02:17:18.665262Z","caller":"traceutil/trace.go:171","msg":"trace[1124415434] range","detail":"{range_begin:/registry/events/openstack/keepalived-b7t5z.18ae5fc5465933fe\u0000; range_end:/registry/events/openstack0; response_count:1000; response_revision:46412; }","duration":"311.174449ms","start":"2026-05-11T02:17:18.354068Z","end":"2026-05-11T02:17:18.665242Z","steps":["trace[1124415434] 'range keys from in-memory index tree' (duration: 308.2071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T02:17:18.665327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T02:17:18.354049Z","time spent":"311.244111ms","remote":"127.0.0.1:45734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1419,"response size":770073,"request content":"key:\"/registry/events/openstack/keepalived-b7t5z.18ae5fc5465933fe\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:46412 "}