{"level":"warn","ts":"2026-05-25T00:45:31.831752Z","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-25T00:45:31.831884Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-25T00:45:31.831965Z","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-25T00:45:31.831978Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-25T00:45:31.832003Z","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-25T00:45:31.832689Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-05-25T00:45:31.832788Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-25T00:45:31.836345Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.297883ms"} {"level":"info","ts":"2026-05-25T00:45:31.84116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-05-25T00:45:31.841241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T00:45:31.841269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-05-25T00:45:31.84129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T00:45:31.841307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-05-25T00:45:31.841353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-05-25T00:45:31.846689Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T00:45:31.848012Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T00:45:31.84926Z","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-25T00:45:31.851998Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T00:45:31.852262Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T00:45:31.852369Z","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-25T00:45:31.852509Z","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-25T00:45:31.852523Z","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-25T00:45:31.853225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-05-25T00:45:31.854211Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-25T00:45:31.857586Z","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-25T00:45:31.857783Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-25T00:45:31.857876Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-25T00:45:31.858259Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T00:45:31.858344Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T00:45:32.342234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T00:45:32.342295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T00:45:32.342344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-05-25T00:45:32.342364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-05-25T00:45:32.342375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-25T00:45:32.34239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-05-25T00:45:32.34259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-25T00:45:32.379396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:45:32.380979Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T00:45:32.381393Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:45:32.381534Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:45:32.381566Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:45:32.381584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T00:45:32.383876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T00:45:32.384433Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T00:45:32.385894Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T00:45:32.386047Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T00:45:32.386382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-05-25T00:45:37.785808Z","caller":"traceutil/trace.go:171","msg":"trace[270235252] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"134.540689ms","start":"2026-05-25T00:45:37.651238Z","end":"2026-05-25T00:45:37.785779Z","steps":["trace[270235252] 'process raft request' (duration: 134.361674ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:37.840194Z","caller":"traceutil/trace.go:171","msg":"trace[28192397] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"188.364275ms","start":"2026-05-25T00:45:37.651789Z","end":"2026-05-25T00:45:37.840153Z","steps":["trace[28192397] 'process raft request' (duration: 188.123998ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:37.840225Z","caller":"traceutil/trace.go:171","msg":"trace[1679113270] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"188.303273ms","start":"2026-05-25T00:45:37.65188Z","end":"2026-05-25T00:45:37.840183Z","steps":["trace[1679113270] 'process raft request' (duration: 188.160199ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:37.840252Z","caller":"traceutil/trace.go:171","msg":"trace[294044923] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"166.983172ms","start":"2026-05-25T00:45:37.673234Z","end":"2026-05-25T00:45:37.840217Z","steps":["trace[294044923] 'process raft request' (duration: 166.863949ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:37.840541Z","caller":"traceutil/trace.go:171","msg":"trace[1474401763] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"113.572498ms","start":"2026-05-25T00:45:37.726919Z","end":"2026-05-25T00:45:37.840491Z","steps":["trace[1474401763] 'process raft request' (duration: 113.363132ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:37.840384Z","caller":"traceutil/trace.go:171","msg":"trace[1383698828] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"164.73427ms","start":"2026-05-25T00:45:37.675595Z","end":"2026-05-25T00:45:37.840329Z","steps":["trace[1383698828] 'process raft request' (duration: 164.656407ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:37.840385Z","caller":"traceutil/trace.go:171","msg":"trace[461151220] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"165.365957ms","start":"2026-05-25T00:45:37.674946Z","end":"2026-05-25T00:45:37.840312Z","steps":["trace[461151220] 'process raft request' (duration: 165.203613ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:37.840442Z","caller":"traceutil/trace.go:171","msg":"trace[8922999] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"165.730498ms","start":"2026-05-25T00:45:37.674704Z","end":"2026-05-25T00:45:37.840435Z","steps":["trace[8922999] 'process raft request' (duration: 165.415379ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:37.840403Z","caller":"traceutil/trace.go:171","msg":"trace[579671056] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"165.006496ms","start":"2026-05-25T00:45:37.675353Z","end":"2026-05-25T00:45:37.84036Z","steps":["trace[579671056] 'process raft request' (duration: 164.857852ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:45:46.620566Z","caller":"traceutil/trace.go:171","msg":"trace[1869617516] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"121.82949ms","start":"2026-05-25T00:45:46.49871Z","end":"2026-05-25T00:45:46.62054Z","steps":["trace[1869617516] 'process raft request' (duration: 121.666526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:17.027997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.150842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T00:46:17.028193Z","caller":"traceutil/trace.go:171","msg":"trace[1602512397] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"252.666055ms","start":"2026-05-25T00:46:16.775503Z","end":"2026-05-25T00:46:17.028169Z","steps":["trace[1602512397] 'read index received' (duration: 60.452µs)","trace[1602512397] 'applied index is now lower than readState.Index' (duration: 252.603993ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:46:17.028223Z","caller":"traceutil/trace.go:171","msg":"trace[557150539] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"313.702634ms","start":"2026-05-25T00:46:16.714478Z","end":"2026-05-25T00:46:17.028181Z","steps":["trace[557150539] 'process raft request' (duration: 28.434121ms)","trace[557150539] 'compare' (duration: 283.875953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:46:17.028386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:46:16.714452Z","time spent":"313.857978ms","remote":"127.0.0.1:35720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T00:46:17.028524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.000574ms","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-25T00:46:17.028614Z","caller":"traceutil/trace.go:171","msg":"trace[41272291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"253.139488ms","start":"2026-05-25T00:46:16.775454Z","end":"2026-05-25T00:46:17.028594Z","steps":["trace[41272291] 'agreement among raft nodes before linearized reading' (duration: 252.826939ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:21.952955Z","caller":"traceutil/trace.go:171","msg":"trace[2121380466] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"105.563319ms","start":"2026-05-25T00:46:21.847374Z","end":"2026-05-25T00:46:21.952937Z","steps":["trace[2121380466] 'process raft request' (duration: 105.434396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:47.118326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.570429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-25T00:47:47.11841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.113091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-25T00:47:47.118433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.798115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-189dd8eb-d851-4eb5-9c73-0701147d8fe1\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-25T00:47:47.118452Z","caller":"traceutil/trace.go:171","msg":"trace[851169025] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1819; }","duration":"245.726364ms","start":"2026-05-25T00:47:46.872704Z","end":"2026-05-25T00:47:47.11843Z","steps":["trace[851169025] 'range keys from in-memory index tree' (duration: 245.444786ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:47:47.118485Z","caller":"traceutil/trace.go:171","msg":"trace[1006027735] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-189dd8eb-d851-4eb5-9c73-0701147d8fe1; range_end:; response_count:1; response_revision:1819; }","duration":"214.894857ms","start":"2026-05-25T00:47:46.903575Z","end":"2026-05-25T00:47:47.11847Z","steps":["trace[1006027735] 'range keys from in-memory index tree' (duration: 214.668281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:47.118559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.09767ms","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-25T00:47:47.118592Z","caller":"traceutil/trace.go:171","msg":"trace[417017762] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1819; }","duration":"228.145831ms","start":"2026-05-25T00:47:46.890435Z","end":"2026-05-25T00:47:47.118581Z","steps":["trace[417017762] 'range keys from in-memory index tree' (duration: 228.004817ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:47:47.118483Z","caller":"traceutil/trace.go:171","msg":"trace[1985110596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1819; }","duration":"228.233554ms","start":"2026-05-25T00:47:46.890232Z","end":"2026-05-25T00:47:47.118466Z","steps":["trace[1985110596] 'range keys from in-memory index tree' (duration: 227.971086ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:43.186169Z","caller":"traceutil/trace.go:171","msg":"trace[1810713238] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"137.716591ms","start":"2026-05-25T00:48:43.048417Z","end":"2026-05-25T00:48:43.186133Z","steps":["trace[1810713238] 'process raft request' (duration: 137.384872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:43.562665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.584306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-25T00:48:43.562686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.603506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T00:48:43.562789Z","caller":"traceutil/trace.go:171","msg":"trace[1089401742] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2176; }","duration":"110.72969ms","start":"2026-05-25T00:48:43.452008Z","end":"2026-05-25T00:48:43.562737Z","steps":["trace[1089401742] 'range keys from in-memory index tree' (duration: 110.412472ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:43.562855Z","caller":"traceutil/trace.go:171","msg":"trace[847068233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2176; }","duration":"169.837693ms","start":"2026-05-25T00:48:43.392976Z","end":"2026-05-25T00:48:43.562814Z","steps":["trace[847068233] 'range keys from in-memory index tree' (duration: 169.306718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:43.562678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.44445ms","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-25T00:48:43.563018Z","caller":"traceutil/trace.go:171","msg":"trace[1799088574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2176; }","duration":"154.81124ms","start":"2026-05-25T00:48:43.408171Z","end":"2026-05-25T00:48:43.562982Z","steps":["trace[1799088574] 'range keys from in-memory index tree' (duration: 154.285445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:52:16.241933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.083772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T00:52:16.242002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.676433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbd8c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T00:52:16.242046Z","caller":"traceutil/trace.go:171","msg":"trace[2104087771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3705; }","duration":"150.253177ms","start":"2026-05-25T00:52:16.091774Z","end":"2026-05-25T00:52:16.242028Z","steps":["trace[2104087771] 'range keys from in-memory index tree' (duration: 149.930048ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:52:16.242083Z","caller":"traceutil/trace.go:171","msg":"trace[572939634] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbd8c; range_end:; response_count:1; response_revision:3705; }","duration":"228.814367ms","start":"2026-05-25T00:52:16.013249Z","end":"2026-05-25T00:52:16.242064Z","steps":["trace[572939634] 'range keys from in-memory index tree' (duration: 228.482838ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:53:38.417793Z","caller":"traceutil/trace.go:171","msg":"trace[1615901015] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"112.794399ms","start":"2026-05-25T00:53:38.304959Z","end":"2026-05-25T00:53:38.417753Z","steps":["trace[1615901015] 'process raft request' (duration: 112.602044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:53:49.742138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.267934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-05-25T00:53:49.742243Z","caller":"traceutil/trace.go:171","msg":"trace[1333925748] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5108; }","duration":"133.422818ms","start":"2026-05-25T00:53:49.608798Z","end":"2026-05-25T00:53:49.742221Z","steps":["trace[1333925748] 'range keys from in-memory index tree' (duration: 133.110199ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:53:49.926399Z","caller":"traceutil/trace.go:171","msg":"trace[1727855687] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"115.677368ms","start":"2026-05-25T00:53:49.810697Z","end":"2026-05-25T00:53:49.926374Z","steps":["trace[1727855687] 'process raft request' (duration: 115.626157ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:53:49.926411Z","caller":"traceutil/trace.go:171","msg":"trace[153597462] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"116.504621ms","start":"2026-05-25T00:53:49.809872Z","end":"2026-05-25T00:53:49.926376Z","steps":["trace[153597462] 'process raft request' (duration: 44.845346ms)","trace[153597462] 'compare' (duration: 71.428229ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:53:50.209172Z","caller":"traceutil/trace.go:171","msg":"trace[210524006] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"101.498118ms","start":"2026-05-25T00:53:50.107652Z","end":"2026-05-25T00:53:50.20915Z","steps":["trace[210524006] 'process raft request' (duration: 100.824469ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:53:50.209408Z","caller":"traceutil/trace.go:171","msg":"trace[1565585303] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"100.214912ms","start":"2026-05-25T00:53:50.109181Z","end":"2026-05-25T00:53:50.209395Z","steps":["trace[1565585303] 'process raft request' (duration: 99.477681ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:55:32.503215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-05-25T00:55:32.567802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"63.052376ms","hash":1890514863,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":34492416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T00:55:32.567875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1890514863,"revision":2969,"compact-revision":-1} {"level":"info","ts":"2026-05-25T01:00:02.9749Z","caller":"traceutil/trace.go:171","msg":"trace[147432220] transaction","detail":"{read_only:false; response_revision:8128; number_of_response:1; }","duration":"292.673994ms","start":"2026-05-25T01:00:02.682204Z","end":"2026-05-25T01:00:02.974878Z","steps":["trace[147432220] 'process raft request' (duration: 292.54017ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:03.003011Z","caller":"traceutil/trace.go:171","msg":"trace[316331902] linearizableReadLoop","detail":"{readStateIndex:8416; appliedIndex:8414; }","duration":"245.959953ms","start":"2026-05-25T01:00:02.757029Z","end":"2026-05-25T01:00:03.002989Z","steps":["trace[316331902] 'read index received' (duration: 217.792625ms)","trace[316331902] 'applied index is now lower than readState.Index' (duration: 28.166028ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:00:03.003185Z","caller":"traceutil/trace.go:171","msg":"trace[106558358] transaction","detail":"{read_only:false; response_revision:8129; number_of_response:1; }","duration":"315.255178ms","start":"2026-05-25T01:00:02.687891Z","end":"2026-05-25T01:00:03.003146Z","steps":["trace[106558358] 'process raft request' (duration: 294.251788ms)","trace[106558358] 'compare' (duration: 20.734112ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:00:03.003262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.21385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbd8c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T01:00:03.003306Z","caller":"traceutil/trace.go:171","msg":"trace[1208047913] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbd8c; range_end:; response_count:1; response_revision:8129; }","duration":"246.306583ms","start":"2026-05-25T01:00:02.756988Z","end":"2026-05-25T01:00:03.003295Z","steps":["trace[1208047913] 'agreement among raft nodes before linearized reading' (duration: 246.137878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:03.003512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:02.687865Z","time spent":"315.397132ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:00:03.003519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.314463ms","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-25T01:00:03.003599Z","caller":"traceutil/trace.go:171","msg":"trace[1878732095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8129; }","duration":"204.430177ms","start":"2026-05-25T01:00:02.799145Z","end":"2026-05-25T01:00:03.003575Z","steps":["trace[1878732095] 'agreement among raft nodes before linearized reading' (duration: 204.216111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:03.003689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.333842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T01:00:03.003764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.763117ms","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-25T01:00:03.003799Z","caller":"traceutil/trace.go:171","msg":"trace[541497581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8129; }","duration":"170.837219ms","start":"2026-05-25T01:00:02.832952Z","end":"2026-05-25T01:00:03.003789Z","steps":["trace[541497581] 'agreement among raft nodes before linearized reading' (duration: 170.741896ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:03.003799Z","caller":"traceutil/trace.go:171","msg":"trace[127805786] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8129; }","duration":"167.490106ms","start":"2026-05-25T01:00:02.836283Z","end":"2026-05-25T01:00:03.003773Z","steps":["trace[127805786] 'agreement among raft nodes before linearized reading' (duration: 167.2665ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:03.879256Z","caller":"traceutil/trace.go:171","msg":"trace[913775449] transaction","detail":"{read_only:false; response_revision:8135; number_of_response:1; }","duration":"221.170539ms","start":"2026-05-25T01:00:03.658062Z","end":"2026-05-25T01:00:03.879232Z","steps":["trace[913775449] 'process raft request' (duration: 220.981563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:04.782988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.287223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T01:00:04.783078Z","caller":"traceutil/trace.go:171","msg":"trace[1919074980] transaction","detail":"{read_only:false; response_revision:8138; number_of_response:1; }","duration":"181.997547ms","start":"2026-05-25T01:00:04.601065Z","end":"2026-05-25T01:00:04.783063Z","steps":["trace[1919074980] 'process raft request' (duration: 78.56957ms)","trace[1919074980] 'compare' (duration: 103.18023ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:00:05.143158Z","caller":"traceutil/trace.go:171","msg":"trace[811313407] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"160.562924ms","start":"2026-05-25T01:00:04.982563Z","end":"2026-05-25T01:00:05.143126Z","steps":["trace[811313407] 'process raft request' (duration: 160.365719ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:05.14638Z","caller":"traceutil/trace.go:171","msg":"trace[1872719713] linearizableReadLoop","detail":"{readStateIndex:8428; appliedIndex:8427; }","duration":"127.228935ms","start":"2026-05-25T01:00:05.019125Z","end":"2026-05-25T01:00:05.146354Z","steps":["trace[1872719713] 'read index received' (duration: 124.659333ms)","trace[1872719713] 'applied index is now lower than readState.Index' (duration: 2.568742ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:00:05.146484Z","caller":"traceutil/trace.go:171","msg":"trace[1103713603] transaction","detail":"{read_only:false; response_revision:8140; number_of_response:1; }","duration":"136.342906ms","start":"2026-05-25T01:00:05.010112Z","end":"2026-05-25T01:00:05.146455Z","steps":["trace[1103713603] 'process raft request' (duration: 136.1224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:05.146571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.43631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-25T01:00:05.1466Z","caller":"traceutil/trace.go:171","msg":"trace[982559410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8140; }","duration":"127.525582ms","start":"2026-05-25T01:00:05.019068Z","end":"2026-05-25T01:00:05.146593Z","steps":["trace[982559410] 'agreement among raft nodes before linearized reading' (duration: 127.393729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:05.146739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.859062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-25T01:00:05.146889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.340213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbd8c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T01:00:05.146917Z","caller":"traceutil/trace.go:171","msg":"trace[230859581] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbd8c; range_end:; response_count:1; response_revision:8140; }","duration":"108.389924ms","start":"2026-05-25T01:00:05.03852Z","end":"2026-05-25T01:00:05.14691Z","steps":["trace[230859581] 'agreement among raft nodes before linearized reading' (duration: 108.317742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:05.147012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.975416ms","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-25T01:00:05.147082Z","caller":"traceutil/trace.go:171","msg":"trace[698870372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8140; }","duration":"111.103299ms","start":"2026-05-25T01:00:05.035964Z","end":"2026-05-25T01:00:05.147068Z","steps":["trace[698870372] 'agreement among raft nodes before linearized reading' (duration: 110.892113ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:05.146832Z","caller":"traceutil/trace.go:171","msg":"trace[1357212540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8140; }","duration":"110.999626ms","start":"2026-05-25T01:00:05.035813Z","end":"2026-05-25T01:00:05.146812Z","steps":["trace[1357212540] 'agreement among raft nodes before linearized reading' (duration: 110.75904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.577824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.846498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T01:00:17.577896Z","caller":"traceutil/trace.go:171","msg":"trace[707480133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8208; }","duration":"486.938651ms","start":"2026-05-25T01:00:17.090943Z","end":"2026-05-25T01:00:17.577882Z","steps":["trace[707480133] 'range keys from in-memory index tree' (duration: 486.798927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.577927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:17.090905Z","time spent":"487.016903ms","remote":"127.0.0.1:35194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-25T01:00:17.578037Z","caller":"traceutil/trace.go:171","msg":"trace[1142081429] transaction","detail":"{read_only:false; response_revision:8209; number_of_response:1; }","duration":"391.654528ms","start":"2026-05-25T01:00:17.186356Z","end":"2026-05-25T01:00:17.57801Z","steps":["trace[1142081429] 'process raft request' (duration: 390.922158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.57818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:17.186329Z","time spent":"391.781102ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:00:17.5807Z","caller":"traceutil/trace.go:171","msg":"trace[1959878916] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"394.283281ms","start":"2026-05-25T01:00:17.186405Z","end":"2026-05-25T01:00:17.580688Z","steps":["trace[1959878916] 'process raft request' (duration: 394.139437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.580786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:17.18638Z","time spent":"394.356372ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:00:17.581054Z","caller":"traceutil/trace.go:171","msg":"trace[944433518] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"288.588032ms","start":"2026-05-25T01:00:17.292442Z","end":"2026-05-25T01:00:17.58103Z","steps":["trace[944433518] 'process raft request' (duration: 288.206941ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:17.581027Z","caller":"traceutil/trace.go:171","msg":"trace[1306957219] linearizableReadLoop","detail":"{readStateIndex:8501; appliedIndex:8499; }","duration":"377.23023ms","start":"2026-05-25T01:00:17.203746Z","end":"2026-05-25T01:00:17.580976Z","steps":["trace[1306957219] 'read index received' (duration: 373.698133ms)","trace[1306957219] 'applied index is now lower than readState.Index' (duration: 3.528237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:00:17.581549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.941446ms","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-25T01:00:17.581608Z","caller":"traceutil/trace.go:171","msg":"trace[787633511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8211; }","duration":"375.021959ms","start":"2026-05-25T01:00:17.206567Z","end":"2026-05-25T01:00:17.581589Z","steps":["trace[787633511] 'agreement among raft nodes before linearized reading' (duration: 374.865875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.581629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.132284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-25T01:00:17.581644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:17.206554Z","time spent":"375.0813ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-25T01:00:17.581667Z","caller":"traceutil/trace.go:171","msg":"trace[696552904] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8211; }","duration":"370.187995ms","start":"2026-05-25T01:00:17.211467Z","end":"2026-05-25T01:00:17.581655Z","steps":["trace[696552904] 'agreement among raft nodes before linearized reading' (duration: 370.073712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.581689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:17.211447Z","time spent":"370.236267ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-25T01:00:17.581746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.001671ms","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":"warn","ts":"2026-05-25T01:00:17.581731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.690896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbd8c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T01:00:17.581845Z","caller":"traceutil/trace.go:171","msg":"trace[632790428] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbd8c; range_end:; response_count:1; response_revision:8211; }","duration":"367.874752ms","start":"2026-05-25T01:00:17.213954Z","end":"2026-05-25T01:00:17.581829Z","steps":["trace[632790428] 'agreement among raft nodes before linearized reading' (duration: 367.497791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.581853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.679399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-25T01:00:17.581844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.547772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T01:00:17.581912Z","caller":"traceutil/trace.go:171","msg":"trace[231153369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8211; }","duration":"374.744861ms","start":"2026-05-25T01:00:17.207148Z","end":"2026-05-25T01:00:17.581893Z","steps":["trace[231153369] 'agreement among raft nodes before linearized reading' (duration: 374.593327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.5819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:17.213948Z","time spent":"367.938253ms","remote":"127.0.0.1:35358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-rbd8c\" "} {"level":"info","ts":"2026-05-25T01:00:17.581932Z","caller":"traceutil/trace.go:171","msg":"trace[1907995772] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8211; }","duration":"123.717967ms","start":"2026-05-25T01:00:17.458195Z","end":"2026-05-25T01:00:17.581913Z","steps":["trace[1907995772] 'agreement among raft nodes before linearized reading' (duration: 123.529542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.58196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:17.207144Z","time spent":"374.807813ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T01:00:17.581787Z","caller":"traceutil/trace.go:171","msg":"trace[137841463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8211; }","duration":"378.081603ms","start":"2026-05-25T01:00:17.203696Z","end":"2026-05-25T01:00:17.581778Z","steps":["trace[137841463] 'agreement among raft nodes before linearized reading' (duration: 377.476546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:17.582734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:00:17.203672Z","time spent":"379.02644ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T01:00:32.29073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.531942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T01:00:32.290848Z","caller":"traceutil/trace.go:171","msg":"trace[759503038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8340; }","duration":"199.660305ms","start":"2026-05-25T01:00:32.09117Z","end":"2026-05-25T01:00:32.29083Z","steps":["trace[759503038] 'range keys from in-memory index tree' (duration: 199.448289ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:32.509169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6124} {"level":"info","ts":"2026-05-25T01:00:32.614289Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6124,"took":"103.18893ms","hash":2851094515,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":23384064,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-25T01:00:32.614359Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2851094515,"revision":6124,"compact-revision":2969} {"level":"info","ts":"2026-05-25T01:00:34.457079Z","caller":"traceutil/trace.go:171","msg":"trace[149827608] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"129.210539ms","start":"2026-05-25T01:00:34.327842Z","end":"2026-05-25T01:00:34.457053Z","steps":["trace[149827608] 'process raft request' (duration: 129.041864ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:44.864363Z","caller":"traceutil/trace.go:171","msg":"trace[677914094] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"105.154515ms","start":"2026-05-25T01:00:44.759176Z","end":"2026-05-25T01:00:44.86433Z","steps":["trace[677914094] 'process raft request' (duration: 104.99935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:45.13368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.22462ms","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-25T01:00:45.133749Z","caller":"traceutil/trace.go:171","msg":"trace[1142445352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8450; }","duration":"150.347723ms","start":"2026-05-25T01:00:44.983387Z","end":"2026-05-25T01:00:45.133735Z","steps":["trace[1142445352] 'range keys from in-memory index tree' (duration: 149.992583ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:45.800863Z","caller":"traceutil/trace.go:171","msg":"trace[391673731] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"128.428417ms","start":"2026-05-25T01:00:45.672392Z","end":"2026-05-25T01:00:45.80082Z","steps":["trace[391673731] 'process raft request' (duration: 80.780571ms)","trace[391673731] 'compare' (duration: 47.464501ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:00:45.800899Z","caller":"traceutil/trace.go:171","msg":"trace[254417972] linearizableReadLoop","detail":"{readStateIndex:8756; appliedIndex:8755; }","duration":"127.221314ms","start":"2026-05-25T01:00:45.673652Z","end":"2026-05-25T01:00:45.800873Z","steps":["trace[254417972] 'read index received' (duration: 79.534306ms)","trace[254417972] 'applied index is now lower than readState.Index' (duration: 47.684288ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:00:45.80102Z","caller":"traceutil/trace.go:171","msg":"trace[1352968991] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"100.829935ms","start":"2026-05-25T01:00:45.70016Z","end":"2026-05-25T01:00:45.800989Z","steps":["trace[1352968991] 'process raft request' (duration: 100.716192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:00:45.801095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.43717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T01:00:45.801142Z","caller":"traceutil/trace.go:171","msg":"trace[1512307079] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-config; range_end:; response_count:0; response_revision:8456; }","duration":"127.547993ms","start":"2026-05-25T01:00:45.673581Z","end":"2026-05-25T01:00:45.801129Z","steps":["trace[1512307079] 'agreement among raft nodes before linearized reading' (duration: 127.424529ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:00:45.801357Z","caller":"traceutil/trace.go:171","msg":"trace[1940577660] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"102.910932ms","start":"2026-05-25T01:00:45.698419Z","end":"2026-05-25T01:00:45.80133Z","steps":["trace[1940577660] 'process raft request' (duration: 102.390908ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:01:34.25216Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002401.wal"} {"level":"info","ts":"2026-05-25T01:01:58.951954Z","caller":"traceutil/trace.go:171","msg":"trace[918339112] transaction","detail":"{read_only:false; response_revision:9185; number_of_response:1; }","duration":"153.10824ms","start":"2026-05-25T01:01:58.798817Z","end":"2026-05-25T01:01:58.951925Z","steps":["trace[918339112] 'process raft request' (duration: 152.935975ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:03:05.959841Z","caller":"traceutil/trace.go:171","msg":"trace[1988970614] linearizableReadLoop","detail":"{readStateIndex:9984; appliedIndex:9983; }","duration":"290.430632ms","start":"2026-05-25T01:03:05.669384Z","end":"2026-05-25T01:03:05.959815Z","steps":["trace[1988970614] 'read index received' (duration: 290.152695ms)","trace[1988970614] 'applied index is now lower than readState.Index' (duration: 276.967µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:03:05.959901Z","caller":"traceutil/trace.go:171","msg":"trace[424592420] transaction","detail":"{read_only:false; response_revision:9649; number_of_response:1; }","duration":"347.855169ms","start":"2026-05-25T01:03:05.612011Z","end":"2026-05-25T01:03:05.959866Z","steps":["trace[424592420] 'process raft request' (duration: 347.627722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:03:05.960073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:03:05.611989Z","time spent":"347.995062ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:03:05.960593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.177363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-25T01:03:05.960685Z","caller":"traceutil/trace.go:171","msg":"trace[1775418753] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:9649; }","duration":"291.312877ms","start":"2026-05-25T01:03:05.669347Z","end":"2026-05-25T01:03:05.96066Z","steps":["trace[1775418753] 'agreement among raft nodes before linearized reading' (duration: 290.594287ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:03:08.572251Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1686e9b1ea717edf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T01:03:08.577511Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-25T01:03:08.577634Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-25T01:04:20.525846Z","caller":"traceutil/trace.go:171","msg":"trace[275114952] transaction","detail":"{read_only:false; response_revision:10247; number_of_response:1; }","duration":"102.799485ms","start":"2026-05-25T01:04:20.423013Z","end":"2026-05-25T01:04:20.525813Z","steps":["trace[275114952] 'process raft request' (duration: 102.596609ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:04:30.002655Z","caller":"traceutil/trace.go:171","msg":"trace[1569575925] linearizableReadLoop","detail":"{readStateIndex:10690; appliedIndex:10689; }","duration":"411.391283ms","start":"2026-05-25T01:04:29.591241Z","end":"2026-05-25T01:04:30.002632Z","steps":["trace[1569575925] 'read index received' (duration: 411.161157ms)","trace[1569575925] 'applied index is now lower than readState.Index' (duration: 229.226µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:04:30.002792Z","caller":"traceutil/trace.go:171","msg":"trace[960939427] transaction","detail":"{read_only:false; response_revision:10332; number_of_response:1; }","duration":"477.931618ms","start":"2026-05-25T01:04:29.524789Z","end":"2026-05-25T01:04:30.00272Z","steps":["trace[960939427] 'process raft request' (duration: 477.696962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:04:30.00288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.048974ms","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-25T01:04:30.002882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.61277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T01:04:30.002943Z","caller":"traceutil/trace.go:171","msg":"trace[673349301] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:10332; }","duration":"283.152577ms","start":"2026-05-25T01:04:29.719774Z","end":"2026-05-25T01:04:30.002927Z","steps":["trace[673349301] 'agreement among raft nodes before linearized reading' (duration: 283.049504ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:04:30.002957Z","caller":"traceutil/trace.go:171","msg":"trace[1589732239] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:10332; }","duration":"411.751373ms","start":"2026-05-25T01:04:29.591187Z","end":"2026-05-25T01:04:30.002938Z","steps":["trace[1589732239] 'agreement among raft nodes before linearized reading' (duration: 411.602479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:04:30.003003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:04:29.591169Z","time spent":"411.817785ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-05-25T01:04:30.003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:04:29.524768Z","time spent":"478.122743ms","remote":"127.0.0.1:35358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6851,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:05:32.522388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8341} {"level":"info","ts":"2026-05-25T01:05:32.607752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8341,"took":"83.140122ms","hash":3428693989,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":26669056,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-25T01:05:32.607825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3428693989,"revision":8341,"compact-revision":6124} {"level":"info","ts":"2026-05-25T01:05:42.86056Z","caller":"traceutil/trace.go:171","msg":"trace[862422421] transaction","detail":"{read_only:false; response_revision:11031; number_of_response:1; }","duration":"106.464255ms","start":"2026-05-25T01:05:42.754053Z","end":"2026-05-25T01:05:42.860517Z","steps":["trace[862422421] 'process raft request' (duration: 61.6641ms)","trace[862422421] 'compare' (duration: 44.527708ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:10:32.529535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10972} {"level":"info","ts":"2026-05-25T01:10:32.609658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10972,"took":"77.589938ms","hash":3284727621,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":27938816,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-25T01:10:32.60972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3284727621,"revision":10972,"compact-revision":8341} {"level":"info","ts":"2026-05-25T01:11:48.298103Z","caller":"traceutil/trace.go:171","msg":"trace[283324476] transaction","detail":"{read_only:false; response_revision:13513; number_of_response:1; }","duration":"143.197176ms","start":"2026-05-25T01:11:48.154878Z","end":"2026-05-25T01:11:48.298075Z","steps":["trace[283324476] 'process raft request' (duration: 143.021141ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:11:48.366099Z","caller":"traceutil/trace.go:171","msg":"trace[1761212285] transaction","detail":"{read_only:false; response_revision:13514; number_of_response:1; }","duration":"144.897163ms","start":"2026-05-25T01:11:48.221182Z","end":"2026-05-25T01:11:48.366079Z","steps":["trace[1761212285] 'process raft request' (duration: 143.587926ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:12:12.605141Z","caller":"traceutil/trace.go:171","msg":"trace[257818314] linearizableReadLoop","detail":"{readStateIndex:14239; appliedIndex:14238; }","duration":"154.085136ms","start":"2026-05-25T01:12:12.451036Z","end":"2026-05-25T01:12:12.605121Z","steps":["trace[257818314] 'read index received' (duration: 153.997774ms)","trace[257818314] 'applied index is now lower than readState.Index' (duration: 86.382µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:12:12.605291Z","caller":"traceutil/trace.go:171","msg":"trace[507763712] transaction","detail":"{read_only:false; response_revision:13769; number_of_response:1; }","duration":"160.669258ms","start":"2026-05-25T01:12:12.444577Z","end":"2026-05-25T01:12:12.605246Z","steps":["trace[507763712] 'process raft request' (duration: 160.431941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:12:12.605318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.264951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da841a14-904a-4a80-b9cc-6942b3e2f92d\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-05-25T01:12:12.605478Z","caller":"traceutil/trace.go:171","msg":"trace[1673008279] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da841a14-904a-4a80-b9cc-6942b3e2f92d; range_end:; response_count:1; response_revision:13769; }","duration":"154.464277ms","start":"2026-05-25T01:12:12.451001Z","end":"2026-05-25T01:12:12.605465Z","steps":["trace[1673008279] 'agreement among raft nodes before linearized reading' (duration: 154.19846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:12:12.850968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.224553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T01:12:12.85107Z","caller":"traceutil/trace.go:171","msg":"trace[1447131863] linearizableReadLoop","detail":"{readStateIndex:14240; appliedIndex:14239; }","duration":"216.97996ms","start":"2026-05-25T01:12:12.634076Z","end":"2026-05-25T01:12:12.851056Z","steps":["trace[1447131863] 'read index received' (duration: 31.59µs)","trace[1447131863] 'applied index is now lower than readState.Index' (duration: 216.94733ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:12:12.851131Z","caller":"traceutil/trace.go:171","msg":"trace[1460640732] transaction","detail":"{read_only:false; response_revision:13770; number_of_response:1; }","duration":"387.86986ms","start":"2026-05-25T01:12:12.463207Z","end":"2026-05-25T01:12:12.851077Z","steps":["trace[1460640732] 'process raft request' (duration: 160.461423ms)","trace[1460640732] 'compare' (duration: 227.100989ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:12:12.851206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.127875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T01:12:12.851229Z","caller":"traceutil/trace.go:171","msg":"trace[141889306] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:13770; }","duration":"217.188266ms","start":"2026-05-25T01:12:12.634034Z","end":"2026-05-25T01:12:12.851222Z","steps":["trace[141889306] 'agreement among raft nodes before linearized reading' (duration: 217.077553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:12:12.851275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:12:12.463188Z","time spent":"388.014404ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:12:12.904963Z","caller":"traceutil/trace.go:171","msg":"trace[1385367522] transaction","detail":"{read_only:false; response_revision:13771; number_of_response:1; }","duration":"178.373185ms","start":"2026-05-25T01:12:12.726557Z","end":"2026-05-25T01:12:12.90493Z","steps":["trace[1385367522] 'process raft request' (duration: 178.17391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:12:12.905026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.842649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-25T01:12:12.905026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.010632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbd8c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-25T01:12:12.905092Z","caller":"traceutil/trace.go:171","msg":"trace[124454701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13771; }","duration":"235.950823ms","start":"2026-05-25T01:12:12.669125Z","end":"2026-05-25T01:12:12.905076Z","steps":["trace[124454701] 'agreement among raft nodes before linearized reading' (duration: 235.749027ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:12:12.905097Z","caller":"traceutil/trace.go:171","msg":"trace[564036899] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbd8c; range_end:; response_count:1; response_revision:13771; }","duration":"122.123686ms","start":"2026-05-25T01:12:12.78296Z","end":"2026-05-25T01:12:12.905083Z","steps":["trace[564036899] 'agreement among raft nodes before linearized reading' (duration: 121.91702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:12:12.905258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.069802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-25T01:12:12.905371Z","caller":"traceutil/trace.go:171","msg":"trace[1521637259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13771; }","duration":"238.266057ms","start":"2026-05-25T01:12:12.667077Z","end":"2026-05-25T01:12:12.905343Z","steps":["trace[1521637259] 'agreement among raft nodes before linearized reading' (duration: 237.944958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:13:41.407365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.260896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-05-25T01:13:41.40741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.446921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T01:13:41.40746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.582745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-25T01:13:41.407481Z","caller":"traceutil/trace.go:171","msg":"trace[1728281123] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14803; }","duration":"148.41548ms","start":"2026-05-25T01:13:41.259043Z","end":"2026-05-25T01:13:41.407458Z","steps":["trace[1728281123] 'range keys from in-memory index tree' (duration: 148.108531ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:13:41.407513Z","caller":"traceutil/trace.go:171","msg":"trace[779041953] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14803; }","duration":"148.636066ms","start":"2026-05-25T01:13:41.258863Z","end":"2026-05-25T01:13:41.407499Z","steps":["trace[779041953] 'range keys from in-memory index tree' (duration: 148.389249ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:13:41.407531Z","caller":"traceutil/trace.go:171","msg":"trace[1239521612] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14803; }","duration":"148.627546ms","start":"2026-05-25T01:13:41.258867Z","end":"2026-05-25T01:13:41.407495Z","steps":["trace[1239521612] 'range keys from in-memory index tree' (duration: 148.315877ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:13:41.723848Z","caller":"traceutil/trace.go:171","msg":"trace[756129837] linearizableReadLoop","detail":"{readStateIndex:15311; appliedIndex:15309; }","duration":"308.619995ms","start":"2026-05-25T01:13:41.415204Z","end":"2026-05-25T01:13:41.723824Z","steps":["trace[756129837] 'read index received' (duration: 62.246695ms)","trace[756129837] 'applied index is now lower than readState.Index' (duration: 246.37188ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:13:41.723883Z","caller":"traceutil/trace.go:171","msg":"trace[1505851027] transaction","detail":"{read_only:false; response_revision:14805; number_of_response:1; }","duration":"311.378802ms","start":"2026-05-25T01:13:41.41249Z","end":"2026-05-25T01:13:41.723869Z","steps":["trace[1505851027] 'process raft request' (duration: 311.196447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:13:41.724006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.79107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-25T01:13:41.724038Z","caller":"traceutil/trace.go:171","msg":"trace[317498005] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14805; }","duration":"308.844772ms","start":"2026-05-25T01:13:41.415186Z","end":"2026-05-25T01:13:41.724031Z","steps":["trace[317498005] 'agreement among raft nodes before linearized reading' (duration: 308.726779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:13:41.724033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:13:41.412478Z","time spent":"311.489204ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:13:41.724063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:13:41.415177Z","time spent":"308.880893ms","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-05-25T01:13:41.724148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.680812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T01:13:41.724187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.960074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-tsp67\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-05-25T01:13:41.724234Z","caller":"traceutil/trace.go:171","msg":"trace[1655739560] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:14805; }","duration":"294.795005ms","start":"2026-05-25T01:13:41.429412Z","end":"2026-05-25T01:13:41.724207Z","steps":["trace[1655739560] 'agreement among raft nodes before linearized reading' (duration: 294.636061ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:13:41.724232Z","caller":"traceutil/trace.go:171","msg":"trace[2065822436] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-tsp67; range_end:; response_count:1; response_revision:14805; }","duration":"121.058156ms","start":"2026-05-25T01:13:41.603163Z","end":"2026-05-25T01:13:41.724221Z","steps":["trace[2065822436] 'agreement among raft nodes before linearized reading' (duration: 120.928283ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:15:32.537282Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13014} {"level":"info","ts":"2026-05-25T01:15:32.616026Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13014,"took":"75.871781ms","hash":1112557255,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T01:15:32.616114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1112557255,"revision":13014,"compact-revision":10972} {"level":"info","ts":"2026-05-25T01:19:30.159297Z","caller":"traceutil/trace.go:171","msg":"trace[299660827] transaction","detail":"{read_only:false; response_revision:17596; number_of_response:1; }","duration":"101.203637ms","start":"2026-05-25T01:19:30.058057Z","end":"2026-05-25T01:19:30.159261Z","steps":["trace[299660827] 'process raft request' (duration: 76.991941ms)","trace[299660827] 'compare' (duration: 23.96442ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:19:35.266743Z","caller":"traceutil/trace.go:171","msg":"trace[387224630] transaction","detail":"{read_only:false; response_revision:17649; number_of_response:1; }","duration":"115.396979ms","start":"2026-05-25T01:19:35.151318Z","end":"2026-05-25T01:19:35.266715Z","steps":["trace[387224630] 'process raft request' (duration: 115.254875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:19:35.572218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.628747ms","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-25T01:19:35.572291Z","caller":"traceutil/trace.go:171","msg":"trace[1479886693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17649; }","duration":"163.713629ms","start":"2026-05-25T01:19:35.408564Z","end":"2026-05-25T01:19:35.572278Z","steps":["trace[1479886693] 'range keys from in-memory index tree' (duration: 163.487613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:19:35.572488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.76726ms","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-25T01:19:35.572516Z","caller":"traceutil/trace.go:171","msg":"trace[563887097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17649; }","duration":"167.816482ms","start":"2026-05-25T01:19:35.404691Z","end":"2026-05-25T01:19:35.572508Z","steps":["trace[563887097] 'range keys from in-memory index tree' (duration: 167.688988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:19:35.572599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.536784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-25T01:19:35.572623Z","caller":"traceutil/trace.go:171","msg":"trace[1907465730] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17649; }","duration":"167.600835ms","start":"2026-05-25T01:19:35.405014Z","end":"2026-05-25T01:19:35.572614Z","steps":["trace[1907465730] 'range keys from in-memory index tree' (duration: 167.39071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:19:35.572664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.353769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-25T01:19:35.572691Z","caller":"traceutil/trace.go:171","msg":"trace[1013615696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17649; }","duration":"167.387049ms","start":"2026-05-25T01:19:35.405296Z","end":"2026-05-25T01:19:35.572683Z","steps":["trace[1013615696] 'range keys from in-memory index tree' (duration: 167.263466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:19:35.572716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.916854ms","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-25T01:19:35.572731Z","caller":"traceutil/trace.go:171","msg":"trace[1473907952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17649; }","duration":"167.932315ms","start":"2026-05-25T01:19:35.404794Z","end":"2026-05-25T01:19:35.572726Z","steps":["trace[1473907952] 'range keys from in-memory index tree' (duration: 167.831502ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:19:35.758434Z","caller":"traceutil/trace.go:171","msg":"trace[1267297809] transaction","detail":"{read_only:false; response_revision:17650; number_of_response:1; }","duration":"181.311175ms","start":"2026-05-25T01:19:35.577104Z","end":"2026-05-25T01:19:35.758415Z","steps":["trace[1267297809] 'process raft request' (duration: 181.197112ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:19:35.816002Z","caller":"traceutil/trace.go:171","msg":"trace[1675479978] transaction","detail":"{read_only:false; response_revision:17651; number_of_response:1; }","duration":"236.944287ms","start":"2026-05-25T01:19:35.579009Z","end":"2026-05-25T01:19:35.815954Z","steps":["trace[1675479978] 'process raft request' (duration: 236.755671ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:19:35.816139Z","caller":"traceutil/trace.go:171","msg":"trace[184240187] transaction","detail":"{read_only:false; response_revision:17652; number_of_response:1; }","duration":"237.05904ms","start":"2026-05-25T01:19:35.579066Z","end":"2026-05-25T01:19:35.816125Z","steps":["trace[184240187] 'process raft request' (duration: 236.818754ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:19:35.816305Z","caller":"traceutil/trace.go:171","msg":"trace[912022965] transaction","detail":"{read_only:false; response_revision:17654; number_of_response:1; }","duration":"237.118512ms","start":"2026-05-25T01:19:35.579177Z","end":"2026-05-25T01:19:35.816295Z","steps":["trace[912022965] 'process raft request' (duration: 236.966288ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:19:35.816294Z","caller":"traceutil/trace.go:171","msg":"trace[79527296] transaction","detail":"{read_only:false; response_revision:17653; number_of_response:1; }","duration":"237.151423ms","start":"2026-05-25T01:19:35.579107Z","end":"2026-05-25T01:19:35.816258Z","steps":["trace[79527296] 'process raft request' (duration: 236.954788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:19:35.816547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.476226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-tsp67\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-05-25T01:19:35.816602Z","caller":"traceutil/trace.go:171","msg":"trace[517740908] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-tsp67; range_end:; response_count:1; response_revision:17654; }","duration":"174.583659ms","start":"2026-05-25T01:19:35.642007Z","end":"2026-05-25T01:19:35.81659Z","steps":["trace[517740908] 'agreement among raft nodes before linearized reading' (duration: 174.367193ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:19:35.816217Z","caller":"traceutil/trace.go:171","msg":"trace[552170656] linearizableReadLoop","detail":"{readStateIndex:18247; appliedIndex:18243; }","duration":"174.151097ms","start":"2026-05-25T01:19:35.642045Z","end":"2026-05-25T01:19:35.816196Z","steps":["trace[552170656] 'read index received' (duration: 116.605572ms)","trace[552170656] 'applied index is now lower than readState.Index' (duration: 57.544385ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:19:44.512658Z","caller":"traceutil/trace.go:171","msg":"trace[20797777] transaction","detail":"{read_only:false; response_revision:17780; number_of_response:1; }","duration":"104.945971ms","start":"2026-05-25T01:19:44.407675Z","end":"2026-05-25T01:19:44.512621Z","steps":["trace[20797777] 'process raft request' (duration: 104.779986ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:20:32.543145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15516} {"level":"info","ts":"2026-05-25T01:20:32.636745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15516,"took":"91.154922ms","hash":1989284880,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":34304000,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T01:20:32.636824Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1989284880,"revision":15516,"compact-revision":13014} {"level":"info","ts":"2026-05-25T01:23:10.71316Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1686e9b1ea717edf","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T01:23:10.719148Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-25T01:23:10.719243Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-25T01:24:16.125376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.66036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:24:16.125668Z","caller":"traceutil/trace.go:171","msg":"trace[762204465] transaction","detail":"{read_only:false; response_revision:19716; number_of_response:1; }","duration":"274.1719ms","start":"2026-05-25T01:24:15.851439Z","end":"2026-05-25T01:24:16.125611Z","steps":["trace[762204465] 'process raft request' (duration: 22.196961ms)","trace[762204465] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/masterleases/199.204.45.244; req_size:119; } (duration: 251.413554ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:24:17.136058Z","caller":"traceutil/trace.go:171","msg":"trace[439675604] transaction","detail":"{read_only:false; response_revision:19719; number_of_response:1; }","duration":"113.084525ms","start":"2026-05-25T01:24:17.02293Z","end":"2026-05-25T01:24:17.136015Z","steps":["trace[439675604] 'process raft request' (duration: 100.834007ms)","trace[439675604] 'compare' (duration: 11.496437ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:24:17.457836Z","caller":"traceutil/trace.go:171","msg":"trace[1904354124] transaction","detail":"{read_only:false; response_revision:19720; number_of_response:1; }","duration":"315.006085ms","start":"2026-05-25T01:24:17.142803Z","end":"2026-05-25T01:24:17.457809Z","steps":["trace[1904354124] 'process raft request' (duration: 314.854841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:24:17.457989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:24:17.142791Z","time spent":"315.117259ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:24:17.556797Z","caller":"traceutil/trace.go:171","msg":"trace[2004327888] linearizableReadLoop","detail":"{readStateIndex:20386; appliedIndex:20384; }","duration":"354.519374ms","start":"2026-05-25T01:24:17.202251Z","end":"2026-05-25T01:24:17.55677Z","steps":["trace[2004327888] 'read index received' (duration: 255.417394ms)","trace[2004327888] 'applied index is now lower than readState.Index' (duration: 99.1009ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:24:17.556815Z","caller":"traceutil/trace.go:171","msg":"trace[1475315719] transaction","detail":"{read_only:false; response_revision:19721; number_of_response:1; }","duration":"411.667728ms","start":"2026-05-25T01:24:17.145118Z","end":"2026-05-25T01:24:17.556785Z","steps":["trace[1475315719] 'process raft request' (duration: 405.263472ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:24:17.556876Z","caller":"traceutil/trace.go:171","msg":"trace[724028914] transaction","detail":"{read_only:false; response_revision:19722; number_of_response:1; }","duration":"306.599404ms","start":"2026-05-25T01:24:17.250255Z","end":"2026-05-25T01:24:17.556854Z","steps":["trace[724028914] 'process raft request' (duration: 306.431789ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:24:17.556923Z","caller":"traceutil/trace.go:171","msg":"trace[1914415738] transaction","detail":"{read_only:false; response_revision:19723; number_of_response:1; }","duration":"106.225256ms","start":"2026-05-25T01:24:17.450688Z","end":"2026-05-25T01:24:17.556914Z","steps":["trace[1914415738] 'process raft request' (duration: 106.03846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:24:17.557001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:24:17.250222Z","time spent":"306.702347ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:24:17.557106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:24:17.145098Z","time spent":"411.785631ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:24:17.557153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.147619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T01:24:17.55724Z","caller":"traceutil/trace.go:171","msg":"trace[1972547241] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19723; }","duration":"303.305393ms","start":"2026-05-25T01:24:17.253913Z","end":"2026-05-25T01:24:17.557218Z","steps":["trace[1972547241] 'agreement among raft nodes before linearized reading' (duration: 303.124268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:24:17.557296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:24:17.253892Z","time spent":"303.392866ms","remote":"127.0.0.1:53422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-25T01:24:17.557251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.495995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-25T01:24:17.557378Z","caller":"traceutil/trace.go:171","msg":"trace[276762214] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19723; }","duration":"353.695891ms","start":"2026-05-25T01:24:17.203667Z","end":"2026-05-25T01:24:17.557363Z","steps":["trace[276762214] 'agreement among raft nodes before linearized reading' (duration: 353.327131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:24:17.557443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:24:17.203651Z","time spent":"353.783263ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-25T01:24:17.55748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.208332ms","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-25T01:24:17.557561Z","caller":"traceutil/trace.go:171","msg":"trace[630641568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19723; }","duration":"355.346777ms","start":"2026-05-25T01:24:17.202195Z","end":"2026-05-25T01:24:17.557542Z","steps":["trace[630641568] 'agreement among raft nodes before linearized reading' (duration: 354.997337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:24:17.557611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:24:17.202176Z","time spent":"355.427269ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-25T01:25:32.548723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18174} {"level":"info","ts":"2026-05-25T01:25:32.658071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18174,"took":"106.480423ms","hash":3910603379,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":30593024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-25T01:25:32.658125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3910603379,"revision":18174,"compact-revision":15516} {"level":"warn","ts":"2026-05-25T01:28:03.274121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.241158ms","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-25T01:28:03.274339Z","caller":"traceutil/trace.go:171","msg":"trace[1845632012] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:22113; }","duration":"326.461334ms","start":"2026-05-25T01:28:02.947845Z","end":"2026-05-25T01:28:03.274306Z","steps":["trace[1845632012] 'range keys from in-memory index tree' (duration: 326.223138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:28:03.274466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.060725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:28:03.274594Z","caller":"traceutil/trace.go:171","msg":"trace[1994242416] transaction","detail":"{read_only:false; response_revision:22114; number_of_response:1; }","duration":"471.467809ms","start":"2026-05-25T01:28:02.803107Z","end":"2026-05-25T01:28:03.274574Z","steps":["trace[1994242416] 'process raft request' (duration: 199.177557ms)","trace[1994242416] 'compare' (duration: 271.820119ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:28:03.274661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:28:02.803086Z","time spent":"471.544301ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:28:03.295948Z","caller":"traceutil/trace.go:171","msg":"trace[1329649213] linearizableReadLoop","detail":"{readStateIndex:22854; appliedIndex:22853; }","duration":"205.897792ms","start":"2026-05-25T01:28:03.089996Z","end":"2026-05-25T01:28:03.295894Z","steps":["trace[1329649213] 'read index received' (duration: 205.576313ms)","trace[1329649213] 'applied index is now lower than readState.Index' (duration: 320.099µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:28:03.296064Z","caller":"traceutil/trace.go:171","msg":"trace[1923452106] transaction","detail":"{read_only:false; response_revision:22115; number_of_response:1; }","duration":"378.885808ms","start":"2026-05-25T01:28:02.917119Z","end":"2026-05-25T01:28:03.296005Z","steps":["trace[1923452106] 'process raft request' (duration: 378.60408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:28:03.296149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.127888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:28:03.296198Z","caller":"traceutil/trace.go:171","msg":"trace[713540662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22115; }","duration":"206.222301ms","start":"2026-05-25T01:28:03.089962Z","end":"2026-05-25T01:28:03.296184Z","steps":["trace[713540662] 'agreement among raft nodes before linearized reading' (duration: 206.120298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:28:03.29623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:28:02.917097Z","time spent":"379.047543ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:30:32.554826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20437} {"level":"info","ts":"2026-05-25T01:30:32.667428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20437,"took":"108.498679ms","hash":3769069829,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":43040768,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-25T01:30:32.667485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3769069829,"revision":20437,"compact-revision":18174} {"level":"info","ts":"2026-05-25T01:31:16.746702Z","caller":"traceutil/trace.go:171","msg":"trace[41184636] linearizableReadLoop","detail":"{readStateIndex:24876; appliedIndex:24875; }","duration":"217.732969ms","start":"2026-05-25T01:31:16.528946Z","end":"2026-05-25T01:31:16.746679Z","steps":["trace[41184636] 'read index received' (duration: 217.635096ms)","trace[41184636] 'applied index is now lower than readState.Index' (duration: 96.983µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:16.746833Z","caller":"traceutil/trace.go:171","msg":"trace[2124152006] transaction","detail":"{read_only:false; response_revision:24088; number_of_response:1; }","duration":"273.537646ms","start":"2026-05-25T01:31:16.473256Z","end":"2026-05-25T01:31:16.746794Z","steps":["trace[2124152006] 'process raft request' (duration: 273.30039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:16.746954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.002656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-25T01:31:16.746997Z","caller":"traceutil/trace.go:171","msg":"trace[322553775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24088; }","duration":"218.092499ms","start":"2026-05-25T01:31:16.528894Z","end":"2026-05-25T01:31:16.746987Z","steps":["trace[322553775] 'agreement among raft nodes before linearized reading' (duration: 217.929644ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:16.747781Z","caller":"traceutil/trace.go:171","msg":"trace[413943503] transaction","detail":"{read_only:false; response_revision:24089; number_of_response:1; }","duration":"131.895874ms","start":"2026-05-25T01:31:16.615871Z","end":"2026-05-25T01:31:16.747767Z","steps":["trace[413943503] 'process raft request' (duration: 131.790111ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:16.747946Z","caller":"traceutil/trace.go:171","msg":"trace[453317660] transaction","detail":"{read_only:false; response_revision:24090; number_of_response:1; }","duration":"130.787994ms","start":"2026-05-25T01:31:16.617149Z","end":"2026-05-25T01:31:16.747937Z","steps":["trace[453317660] 'process raft request' (duration: 130.582728ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:20.911981Z","caller":"traceutil/trace.go:171","msg":"trace[711709065] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"152.84628ms","start":"2026-05-25T01:31:20.759109Z","end":"2026-05-25T01:31:20.911955Z","steps":["trace[711709065] 'process raft request' (duration: 152.685586ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.026168Z","caller":"traceutil/trace.go:171","msg":"trace[1558059875] transaction","detail":"{read_only:false; response_revision:24123; number_of_response:1; }","duration":"266.929233ms","start":"2026-05-25T01:31:20.759211Z","end":"2026-05-25T01:31:21.026141Z","steps":["trace[1558059875] 'process raft request' (duration: 247.640722ms)","trace[1558059875] 'compare' (duration: 19.094716ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:21.026579Z","caller":"traceutil/trace.go:171","msg":"trace[1441812830] transaction","detail":"{read_only:false; response_revision:24124; number_of_response:1; }","duration":"264.302511ms","start":"2026-05-25T01:31:20.762262Z","end":"2026-05-25T01:31:21.026565Z","steps":["trace[1441812830] 'process raft request' (duration: 263.837858ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.026694Z","caller":"traceutil/trace.go:171","msg":"trace[150422870] transaction","detail":"{read_only:false; response_revision:24125; number_of_response:1; }","duration":"263.675354ms","start":"2026-05-25T01:31:20.763009Z","end":"2026-05-25T01:31:21.026684Z","steps":["trace[150422870] 'process raft request' (duration: 263.389146ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.026716Z","caller":"traceutil/trace.go:171","msg":"trace[2124219791] linearizableReadLoop","detail":"{readStateIndex:24913; appliedIndex:24909; }","duration":"259.767786ms","start":"2026-05-25T01:31:20.766921Z","end":"2026-05-25T01:31:21.026689Z","steps":["trace[2124219791] 'read index received' (duration: 144.907382ms)","trace[2124219791] 'applied index is now lower than readState.Index' (duration: 114.859344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:21.027126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.189739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-25T01:31:21.02721Z","caller":"traceutil/trace.go:171","msg":"trace[1008724391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24126; }","duration":"260.327662ms","start":"2026-05-25T01:31:20.766865Z","end":"2026-05-25T01:31:21.027192Z","steps":["trace[1008724391] 'agreement among raft nodes before linearized reading' (duration: 259.8969ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.194344Z","caller":"traceutil/trace.go:171","msg":"trace[768344444] transaction","detail":"{read_only:false; response_revision:24127; number_of_response:1; }","duration":"163.006171ms","start":"2026-05-25T01:31:21.031313Z","end":"2026-05-25T01:31:21.194319Z","steps":["trace[768344444] 'process raft request' (duration: 162.645731ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.322013Z","caller":"traceutil/trace.go:171","msg":"trace[1073512445] transaction","detail":"{read_only:false; response_revision:24129; number_of_response:1; }","duration":"281.217528ms","start":"2026-05-25T01:31:21.04077Z","end":"2026-05-25T01:31:21.321987Z","steps":["trace[1073512445] 'process raft request' (duration: 281.128146ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.322036Z","caller":"traceutil/trace.go:171","msg":"trace[1243080114] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"290.443571ms","start":"2026-05-25T01:31:21.031554Z","end":"2026-05-25T01:31:21.321998Z","steps":["trace[1243080114] 'process raft request' (duration: 287.746637ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.322252Z","caller":"traceutil/trace.go:171","msg":"trace[675026111] transaction","detail":"{read_only:false; response_revision:24130; number_of_response:1; }","duration":"270.208375ms","start":"2026-05-25T01:31:21.052015Z","end":"2026-05-25T01:31:21.322223Z","steps":["trace[675026111] 'process raft request' (duration: 269.932097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:21.473532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.346249ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T01:31:21.473623Z","caller":"traceutil/trace.go:171","msg":"trace[515859140] linearizableReadLoop","detail":"{readStateIndex:24917; appliedIndex:24914; }","duration":"426.801018ms","start":"2026-05-25T01:31:21.046803Z","end":"2026-05-25T01:31:21.473604Z","steps":["trace[515859140] 'read index received' (duration: 147.101842ms)","trace[515859140] 'applied index is now lower than readState.Index' (duration: 279.697686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:21.473987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.173669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-05-25T01:31:21.473992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.490716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:21.474036Z","caller":"traceutil/trace.go:171","msg":"trace[1307689323] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24130; }","duration":"427.283022ms","start":"2026-05-25T01:31:21.04674Z","end":"2026-05-25T01:31:21.474023Z","steps":["trace[1307689323] 'agreement among raft nodes before linearized reading' (duration: 427.117817ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.474056Z","caller":"traceutil/trace.go:171","msg":"trace[643352031] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:24130; }","duration":"240.594879ms","start":"2026-05-25T01:31:21.233441Z","end":"2026-05-25T01:31:21.474036Z","steps":["trace[643352031] 'agreement among raft nodes before linearized reading' (duration: 240.493636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:21.474071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:21.046729Z","time spent":"427.334733ms","remote":"127.0.0.1:35358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T01:31:21.473992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.677313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:21.474143Z","caller":"traceutil/trace.go:171","msg":"trace[1478537694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24130; }","duration":"382.873448ms","start":"2026-05-25T01:31:21.091256Z","end":"2026-05-25T01:31:21.47413Z","steps":["trace[1478537694] 'agreement among raft nodes before linearized reading' (duration: 382.668853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:21.474202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:21.091243Z","time spent":"382.94851ms","remote":"127.0.0.1:35194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T01:31:21.897094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.237364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:31:21.897186Z","caller":"traceutil/trace.go:171","msg":"trace[64263320] linearizableReadLoop","detail":"{readStateIndex:24921; appliedIndex:24920; }","duration":"251.032857ms","start":"2026-05-25T01:31:21.646141Z","end":"2026-05-25T01:31:21.897174Z","steps":["trace[64263320] 'read index received' (duration: 106.585767ms)","trace[64263320] 'applied index is now lower than readState.Index' (duration: 144.44611ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:21.897433Z","caller":"traceutil/trace.go:171","msg":"trace[899989761] transaction","detail":"{read_only:false; response_revision:24132; number_of_response:1; }","duration":"258.734559ms","start":"2026-05-25T01:31:21.63869Z","end":"2026-05-25T01:31:21.897424Z","steps":["trace[899989761] 'process raft request' (duration: 114.087223ms)","trace[899989761] 'compare' (duration: 143.766611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:21.897592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.560933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-25T01:31:21.897629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.046388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:21.897675Z","caller":"traceutil/trace.go:171","msg":"trace[39122998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24132; }","duration":"185.681656ms","start":"2026-05-25T01:31:21.711976Z","end":"2026-05-25T01:31:21.897658Z","steps":["trace[39122998] 'agreement among raft nodes before linearized reading' (duration: 185.45801ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:21.897707Z","caller":"traceutil/trace.go:171","msg":"trace[1305466378] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:24132; }","duration":"181.188992ms","start":"2026-05-25T01:31:21.716499Z","end":"2026-05-25T01:31:21.897688Z","steps":["trace[1305466378] 'agreement among raft nodes before linearized reading' (duration: 181.028608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:21.897789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.621753ms","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-25T01:31:21.897876Z","caller":"traceutil/trace.go:171","msg":"trace[1110513557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24132; }","duration":"251.744736ms","start":"2026-05-25T01:31:21.646112Z","end":"2026-05-25T01:31:21.897857Z","steps":["trace[1110513557] 'agreement among raft nodes before linearized reading' (duration: 251.492879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:21.899087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.926456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-25T01:31:21.899149Z","caller":"traceutil/trace.go:171","msg":"trace[973659901] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24132; }","duration":"156.021829ms","start":"2026-05-25T01:31:21.743112Z","end":"2026-05-25T01:31:21.899134Z","steps":["trace[973659901] 'agreement among raft nodes before linearized reading' (duration: 154.409734ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:22.114809Z","caller":"traceutil/trace.go:171","msg":"trace[1920213196] transaction","detail":"{read_only:false; response_revision:24135; number_of_response:1; }","duration":"209.583534ms","start":"2026-05-25T01:31:21.905206Z","end":"2026-05-25T01:31:22.11479Z","steps":["trace[1920213196] 'process raft request' (duration: 209.508852ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:22.11484Z","caller":"traceutil/trace.go:171","msg":"trace[1141344069] transaction","detail":"{read_only:false; response_revision:24134; number_of_response:1; }","duration":"210.840758ms","start":"2026-05-25T01:31:21.903961Z","end":"2026-05-25T01:31:22.114802Z","steps":["trace[1141344069] 'process raft request' (duration: 175.992748ms)","trace[1141344069] 'compare' (duration: 34.517791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:22.414325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.753419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:22.414416Z","caller":"traceutil/trace.go:171","msg":"trace[775631631] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:24135; }","duration":"119.893593ms","start":"2026-05-25T01:31:22.2945Z","end":"2026-05-25T01:31:22.414394Z","steps":["trace[775631631] 'count revisions from in-memory index tree' (duration: 119.680818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:22.414723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.084039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:22.414866Z","caller":"traceutil/trace.go:171","msg":"trace[1157413073] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24135; }","duration":"181.257404ms","start":"2026-05-25T01:31:22.23359Z","end":"2026-05-25T01:31:22.414848Z","steps":["trace[1157413073] 'count revisions from in-memory index tree' (duration: 181.018307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:22.414783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.40318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-25T01:31:22.415059Z","caller":"traceutil/trace.go:171","msg":"trace[1408949884] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24135; }","duration":"123.735319ms","start":"2026-05-25T01:31:22.29129Z","end":"2026-05-25T01:31:22.415025Z","steps":["trace[1408949884] 'range keys from in-memory index tree' (duration: 123.274537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:22.414804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.166926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-05-25T01:31:22.415342Z","caller":"traceutil/trace.go:171","msg":"trace[1454845726] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24135; }","duration":"134.732312ms","start":"2026-05-25T01:31:22.280594Z","end":"2026-05-25T01:31:22.415326Z","steps":["trace[1454845726] 'range keys from in-memory index tree' (duration: 133.973021ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:23.046036Z","caller":"traceutil/trace.go:171","msg":"trace[1203783019] transaction","detail":"{read_only:false; response_revision:24137; number_of_response:1; }","duration":"132.645505ms","start":"2026-05-25T01:31:22.913362Z","end":"2026-05-25T01:31:23.046008Z","steps":["trace[1203783019] 'process raft request' (duration: 132.47441ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:23.345932Z","caller":"traceutil/trace.go:171","msg":"trace[1375263807] transaction","detail":"{read_only:false; response_revision:24139; number_of_response:1; }","duration":"313.997811ms","start":"2026-05-25T01:31:23.031902Z","end":"2026-05-25T01:31:23.3459Z","steps":["trace[1375263807] 'process raft request' (duration: 313.883438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:23.346093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:23.031874Z","time spent":"314.127335ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:23.346066Z","caller":"traceutil/trace.go:171","msg":"trace[436084270] transaction","detail":"{read_only:false; response_revision:24141; number_of_response:1; }","duration":"313.957899ms","start":"2026-05-25T01:31:23.032067Z","end":"2026-05-25T01:31:23.346025Z","steps":["trace[436084270] 'process raft request' (duration: 313.843956ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:23.346212Z","caller":"traceutil/trace.go:171","msg":"trace[1166472923] transaction","detail":"{read_only:false; response_revision:24138; number_of_response:1; }","duration":"428.191827ms","start":"2026-05-25T01:31:22.91801Z","end":"2026-05-25T01:31:23.346202Z","steps":["trace[1166472923] 'process raft request' (duration: 427.521919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:23.34627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:22.917993Z","time spent":"428.243138ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:23.346323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:23.032057Z","time spent":"314.128404ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:23.346266Z","caller":"traceutil/trace.go:171","msg":"trace[667659797] linearizableReadLoop","detail":"{readStateIndex:24931; appliedIndex:24926; }","duration":"293.063994ms","start":"2026-05-25T01:31:23.05314Z","end":"2026-05-25T01:31:23.346204Z","steps":["trace[667659797] 'read index received' (duration: 292.470948ms)","trace[667659797] 'applied index is now lower than readState.Index' (duration: 591.266µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:23.346495Z","caller":"traceutil/trace.go:171","msg":"trace[1094812147] transaction","detail":"{read_only:false; response_revision:24142; number_of_response:1; }","duration":"314.34666ms","start":"2026-05-25T01:31:23.032133Z","end":"2026-05-25T01:31:23.34648Z","steps":["trace[1094812147] 'process raft request' (duration: 313.835856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:23.346816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:23.032108Z","time spent":"314.582427ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:23.346941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.781514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18572"} {"level":"info","ts":"2026-05-25T01:31:23.348127Z","caller":"traceutil/trace.go:171","msg":"trace[1820508350] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24142; }","duration":"294.997607ms","start":"2026-05-25T01:31:23.053107Z","end":"2026-05-25T01:31:23.348105Z","steps":["trace[1820508350] 'agreement among raft nodes before linearized reading' (duration: 293.68456ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:23.34706Z","caller":"traceutil/trace.go:171","msg":"trace[1412361884] transaction","detail":"{read_only:false; response_revision:24140; number_of_response:1; }","duration":"315.042329ms","start":"2026-05-25T01:31:23.031993Z","end":"2026-05-25T01:31:23.347036Z","steps":["trace[1412361884] 'process raft request' (duration: 313.854696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:23.348498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:23.031967Z","time spent":"316.363066ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:23.351445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.205219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:23.351515Z","caller":"traceutil/trace.go:171","msg":"trace[160154657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24144; }","duration":"260.311622ms","start":"2026-05-25T01:31:23.091189Z","end":"2026-05-25T01:31:23.351501Z","steps":["trace[160154657] 'agreement among raft nodes before linearized reading' (duration: 260.161247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:23.351539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.607567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-25T01:31:23.351579Z","caller":"traceutil/trace.go:171","msg":"trace[260472601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24144; }","duration":"151.648678ms","start":"2026-05-25T01:31:23.19992Z","end":"2026-05-25T01:31:23.351568Z","steps":["trace[260472601] 'agreement among raft nodes before linearized reading' (duration: 151.516725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:23.351681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.374893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:23.351722Z","caller":"traceutil/trace.go:171","msg":"trace[43149127] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24144; }","duration":"244.427124ms","start":"2026-05-25T01:31:23.107285Z","end":"2026-05-25T01:31:23.351712Z","steps":["trace[43149127] 'agreement among raft nodes before linearized reading' (duration: 244.351152ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:23.510983Z","caller":"traceutil/trace.go:171","msg":"trace[922582275] transaction","detail":"{read_only:false; response_revision:24145; number_of_response:1; }","duration":"156.119ms","start":"2026-05-25T01:31:23.354844Z","end":"2026-05-25T01:31:23.510963Z","steps":["trace[922582275] 'process raft request' (duration: 155.890174ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:23.522904Z","caller":"traceutil/trace.go:171","msg":"trace[1200338874] transaction","detail":"{read_only:false; response_revision:24146; number_of_response:1; }","duration":"167.672149ms","start":"2026-05-25T01:31:23.355212Z","end":"2026-05-25T01:31:23.522884Z","steps":["trace[1200338874] 'process raft request' (duration: 167.564996ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:24.014887Z","caller":"traceutil/trace.go:171","msg":"trace[2030834326] transaction","detail":"{read_only:false; response_revision:24147; number_of_response:1; }","duration":"104.699125ms","start":"2026-05-25T01:31:23.910164Z","end":"2026-05-25T01:31:24.014863Z","steps":["trace[2030834326] 'process raft request' (duration: 104.085738ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:24.259283Z","caller":"traceutil/trace.go:171","msg":"trace[1479322738] transaction","detail":"{read_only:false; response_revision:24148; number_of_response:1; }","duration":"228.211576ms","start":"2026-05-25T01:31:24.031045Z","end":"2026-05-25T01:31:24.259257Z","steps":["trace[1479322738] 'process raft request' (duration: 160.878001ms)","trace[1479322738] 'compare' (duration: 67.095278ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:24.51631Z","caller":"traceutil/trace.go:171","msg":"trace[1889046521] linearizableReadLoop","detail":"{readStateIndex:24938; appliedIndex:24936; }","duration":"425.035948ms","start":"2026-05-25T01:31:24.091245Z","end":"2026-05-25T01:31:24.516281Z","steps":["trace[1889046521] 'read index received' (duration: 100.687302ms)","trace[1889046521] 'applied index is now lower than readState.Index' (duration: 324.347396ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:24.516623Z","caller":"traceutil/trace.go:171","msg":"trace[2054317628] transaction","detail":"{read_only:false; response_revision:24149; number_of_response:1; }","duration":"444.051433ms","start":"2026-05-25T01:31:24.072546Z","end":"2026-05-25T01:31:24.516597Z","steps":["trace[2054317628] 'process raft request' (duration: 420.184185ms)","trace[2054317628] 'compare' (duration: 23.404455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:24.516758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:24.072525Z","time spent":"444.142925ms","remote":"127.0.0.1:40256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2569,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:24.516757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.473391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:24.51683Z","caller":"traceutil/trace.go:171","msg":"trace[201243985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24149; }","duration":"425.572064ms","start":"2026-05-25T01:31:24.091241Z","end":"2026-05-25T01:31:24.516813Z","steps":["trace[201243985] 'agreement among raft nodes before linearized reading' (duration: 425.41802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:24.516873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:24.09121Z","time spent":"425.654715ms","remote":"127.0.0.1:35194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T01:31:24.517234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.716995ms","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-25T01:31:24.517285Z","caller":"traceutil/trace.go:171","msg":"trace[89007649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24149; }","duration":"398.789527ms","start":"2026-05-25T01:31:24.118485Z","end":"2026-05-25T01:31:24.517275Z","steps":["trace[89007649] 'agreement among raft nodes before linearized reading' (duration: 398.647033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:24.517285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.588041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-25T01:31:24.517339Z","caller":"traceutil/trace.go:171","msg":"trace[1455406489] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24149; }","duration":"398.665354ms","start":"2026-05-25T01:31:24.118659Z","end":"2026-05-25T01:31:24.517324Z","steps":["trace[1455406489] 'agreement among raft nodes before linearized reading' (duration: 398.51935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:24.517372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:24.118652Z","time spent":"398.713375ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-25T01:31:24.517315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:24.118474Z","time spent":"398.833629ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T01:31:24.737465Z","caller":"traceutil/trace.go:171","msg":"trace[247816274] linearizableReadLoop","detail":"{readStateIndex:24942; appliedIndex:24938; }","duration":"156.248764ms","start":"2026-05-25T01:31:24.58119Z","end":"2026-05-25T01:31:24.737439Z","steps":["trace[247816274] 'read index received' (duration: 14.352885ms)","trace[247816274] 'applied index is now lower than readState.Index' (duration: 141.894889ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:24.737729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.514832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-05-25T01:31:24.737777Z","caller":"traceutil/trace.go:171","msg":"trace[1794868644] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:24153; }","duration":"156.646025ms","start":"2026-05-25T01:31:24.581119Z","end":"2026-05-25T01:31:24.737765Z","steps":["trace[1794868644] 'agreement among raft nodes before linearized reading' (duration: 156.425949ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:24.738182Z","caller":"traceutil/trace.go:171","msg":"trace[1286083468] transaction","detail":"{read_only:false; response_revision:24151; number_of_response:1; }","duration":"216.513965ms","start":"2026-05-25T01:31:24.521654Z","end":"2026-05-25T01:31:24.738168Z","steps":["trace[1286083468] 'process raft request' (duration: 215.488617ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:24.738363Z","caller":"traceutil/trace.go:171","msg":"trace[1949725344] transaction","detail":"{read_only:false; response_revision:24152; number_of_response:1; }","duration":"216.017241ms","start":"2026-05-25T01:31:24.522335Z","end":"2026-05-25T01:31:24.738353Z","steps":["trace[1949725344] 'process raft request' (duration: 214.993313ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:24.739313Z","caller":"traceutil/trace.go:171","msg":"trace[2013997370] transaction","detail":"{read_only:false; response_revision:24153; number_of_response:1; }","duration":"215.901719ms","start":"2026-05-25T01:31:24.523402Z","end":"2026-05-25T01:31:24.739304Z","steps":["trace[2013997370] 'process raft request' (duration: 213.978826ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.166874Z","caller":"traceutil/trace.go:171","msg":"trace[1508303794] transaction","detail":"{read_only:false; response_revision:24155; number_of_response:1; }","duration":"324.104179ms","start":"2026-05-25T01:31:24.842637Z","end":"2026-05-25T01:31:25.166741Z","steps":["trace[1508303794] 'process raft request' (duration: 316.652654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:25.167076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:24.842618Z","time spent":"324.371886ms","remote":"127.0.0.1:40256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3853,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:25.169827Z","caller":"traceutil/trace.go:171","msg":"trace[1076709520] transaction","detail":"{read_only:false; response_revision:24156; number_of_response:1; }","duration":"227.427226ms","start":"2026-05-25T01:31:24.942375Z","end":"2026-05-25T01:31:25.169802Z","steps":["trace[1076709520] 'process raft request' (duration: 227.287902ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.444176Z","caller":"traceutil/trace.go:171","msg":"trace[876309140] transaction","detail":"{read_only:false; response_revision:24157; number_of_response:1; }","duration":"269.111634ms","start":"2026-05-25T01:31:25.175038Z","end":"2026-05-25T01:31:25.44415Z","steps":["trace[876309140] 'process raft request' (duration: 233.558355ms)","trace[876309140] 'compare' (duration: 35.369044ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:25.601963Z","caller":"traceutil/trace.go:171","msg":"trace[1961659582] transaction","detail":"{read_only:false; response_revision:24160; number_of_response:1; }","duration":"249.283938ms","start":"2026-05-25T01:31:25.352656Z","end":"2026-05-25T01:31:25.60194Z","steps":["trace[1961659582] 'process raft request' (duration: 249.224397ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.602352Z","caller":"traceutil/trace.go:171","msg":"trace[1206638032] transaction","detail":"{read_only:false; response_revision:24162; number_of_response:1; }","duration":"248.468855ms","start":"2026-05-25T01:31:25.353867Z","end":"2026-05-25T01:31:25.602336Z","steps":["trace[1206638032] 'process raft request' (duration: 248.191418ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.602363Z","caller":"traceutil/trace.go:171","msg":"trace[1718781084] transaction","detail":"{read_only:false; response_revision:24161; number_of_response:1; }","duration":"249.346209ms","start":"2026-05-25T01:31:25.35299Z","end":"2026-05-25T01:31:25.602337Z","steps":["trace[1718781084] 'process raft request' (duration: 248.932318ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.602264Z","caller":"traceutil/trace.go:171","msg":"trace[1209394984] transaction","detail":"{read_only:false; response_revision:24158; number_of_response:1; }","duration":"251.507479ms","start":"2026-05-25T01:31:25.350714Z","end":"2026-05-25T01:31:25.602222Z","steps":["trace[1209394984] 'process raft request' (duration: 250.967454ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.60235Z","caller":"traceutil/trace.go:171","msg":"trace[1718205468] transaction","detail":"{read_only:false; response_revision:24159; number_of_response:1; }","duration":"251.519169ms","start":"2026-05-25T01:31:25.350779Z","end":"2026-05-25T01:31:25.602298Z","steps":["trace[1718205468] 'process raft request' (duration: 251.060147ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.602679Z","caller":"traceutil/trace.go:171","msg":"trace[1293518556] transaction","detail":"{read_only:false; response_revision:24163; number_of_response:1; }","duration":"247.340194ms","start":"2026-05-25T01:31:25.355324Z","end":"2026-05-25T01:31:25.602664Z","steps":["trace[1293518556] 'process raft request' (duration: 246.775459ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.602812Z","caller":"traceutil/trace.go:171","msg":"trace[1036474990] transaction","detail":"{read_only:false; response_revision:24164; number_of_response:1; }","duration":"246.395408ms","start":"2026-05-25T01:31:25.356392Z","end":"2026-05-25T01:31:25.602787Z","steps":["trace[1036474990] 'process raft request' (duration: 245.74081ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.603148Z","caller":"traceutil/trace.go:171","msg":"trace[1014693905] transaction","detail":"{read_only:false; response_revision:24165; number_of_response:1; }","duration":"199.522907ms","start":"2026-05-25T01:31:25.403605Z","end":"2026-05-25T01:31:25.603128Z","steps":["trace[1014693905] 'process raft request' (duration: 199.075865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:25.92088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.669211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:31:25.921048Z","caller":"traceutil/trace.go:171","msg":"trace[635526759] linearizableReadLoop","detail":"{readStateIndex:24955; appliedIndex:24947; }","duration":"412.486884ms","start":"2026-05-25T01:31:25.508546Z","end":"2026-05-25T01:31:25.921033Z","steps":["trace[635526759] 'read index received' (duration: 93.297231ms)","trace[635526759] 'applied index is now lower than readState.Index' (duration: 319.188003ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:25.9212Z","caller":"traceutil/trace.go:171","msg":"trace[1805578042] transaction","detail":"{read_only:false; response_revision:24166; number_of_response:1; }","duration":"468.375024ms","start":"2026-05-25T01:31:25.4528Z","end":"2026-05-25T01:31:25.921175Z","steps":["trace[1805578042] 'process raft request' (duration: 264.331593ms)","trace[1805578042] 'compare' (duration: 203.531657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:25.921296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:25.452784Z","time spent":"468.465216ms","remote":"127.0.0.1:40256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:25.921288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.721511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T01:31:25.921301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.985511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:25.921338Z","caller":"traceutil/trace.go:171","msg":"trace[45472274] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24166; }","duration":"412.815204ms","start":"2026-05-25T01:31:25.508509Z","end":"2026-05-25T01:31:25.921324Z","steps":["trace[45472274] 'agreement among raft nodes before linearized reading' (duration: 412.667749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:25.921368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:25.508496Z","time spent":"412.866555ms","remote":"127.0.0.1:37936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"warn","ts":"2026-05-25T01:31:25.921373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.200602ms","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-25T01:31:25.921406Z","caller":"traceutil/trace.go:171","msg":"trace[586447347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24166; }","duration":"406.270274ms","start":"2026-05-25T01:31:25.515126Z","end":"2026-05-25T01:31:25.921396Z","steps":["trace[586447347] 'agreement among raft nodes before linearized reading' (duration: 406.080918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:25.921398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.461427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-05-25T01:31:25.921434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:25.51511Z","time spent":"406.318514ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-25T01:31:25.921449Z","caller":"traceutil/trace.go:171","msg":"trace[1661185134] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:24166; }","duration":"141.5653ms","start":"2026-05-25T01:31:25.779872Z","end":"2026-05-25T01:31:25.921438Z","steps":["trace[1661185134] 'agreement among raft nodes before linearized reading' (duration: 141.385535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:25.921497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.606112ms","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-25T01:31:25.921578Z","caller":"traceutil/trace.go:171","msg":"trace[1665094326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24166; }","duration":"394.735155ms","start":"2026-05-25T01:31:25.526828Z","end":"2026-05-25T01:31:25.921563Z","steps":["trace[1665094326] 'agreement among raft nodes before linearized reading' (duration: 394.482088ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:25.921366Z","caller":"traceutil/trace.go:171","msg":"trace[2081195137] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24166; }","duration":"269.114325ms","start":"2026-05-25T01:31:25.652218Z","end":"2026-05-25T01:31:25.921333Z","steps":["trace[2081195137] 'agreement among raft nodes before linearized reading' (duration: 268.987231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:25.921644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:25.526808Z","time spent":"394.820908ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T01:31:25.921889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.645836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-25T01:31:25.92208Z","caller":"traceutil/trace.go:171","msg":"trace[1341905079] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24166; }","duration":"307.872272ms","start":"2026-05-25T01:31:25.614195Z","end":"2026-05-25T01:31:25.922067Z","steps":["trace[1341905079] 'agreement among raft nodes before linearized reading' (duration: 307.107571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:25.922142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:25.614181Z","time spent":"307.949134ms","remote":"127.0.0.1:40256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":161,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-25T01:31:26.181997Z","caller":"traceutil/trace.go:171","msg":"trace[1242066091] transaction","detail":"{read_only:false; response_revision:24167; number_of_response:1; }","duration":"257.031031ms","start":"2026-05-25T01:31:25.92494Z","end":"2026-05-25T01:31:26.181971Z","steps":["trace[1242066091] 'process raft request' (duration: 174.034295ms)","trace[1242066091] 'compare' (duration: 82.41411ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:26.182313Z","caller":"traceutil/trace.go:171","msg":"trace[1965689892] transaction","detail":"{read_only:false; response_revision:24168; number_of_response:1; }","duration":"256.345582ms","start":"2026-05-25T01:31:25.925951Z","end":"2026-05-25T01:31:26.182296Z","steps":["trace[1965689892] 'process raft request' (duration: 255.962592ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.182525Z","caller":"traceutil/trace.go:171","msg":"trace[1596932369] transaction","detail":"{read_only:false; response_revision:24169; number_of_response:1; }","duration":"254.80957ms","start":"2026-05-25T01:31:25.927691Z","end":"2026-05-25T01:31:26.1825Z","steps":["trace[1596932369] 'process raft request' (duration: 254.367788ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.182587Z","caller":"traceutil/trace.go:171","msg":"trace[1008787429] linearizableReadLoop","detail":"{readStateIndex:24960; appliedIndex:24956; }","duration":"163.822673ms","start":"2026-05-25T01:31:26.018729Z","end":"2026-05-25T01:31:26.182551Z","steps":["trace[1008787429] 'read index received' (duration: 80.238791ms)","trace[1008787429] 'applied index is now lower than readState.Index' (duration: 83.581942ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:26.182598Z","caller":"traceutil/trace.go:171","msg":"trace[1259253066] transaction","detail":"{read_only:false; response_revision:24170; number_of_response:1; }","duration":"249.039981ms","start":"2026-05-25T01:31:25.93353Z","end":"2026-05-25T01:31:26.18257Z","steps":["trace[1259253066] 'process raft request' (duration: 248.873046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:26.182955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.16877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"warn","ts":"2026-05-25T01:31:26.183019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.280386ms","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-25T01:31:26.18303Z","caller":"traceutil/trace.go:171","msg":"trace[1539613147] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24170; }","duration":"112.308924ms","start":"2026-05-25T01:31:26.070702Z","end":"2026-05-25T01:31:26.183011Z","steps":["trace[1539613147] 'agreement among raft nodes before linearized reading' (duration: 112.010176ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.183065Z","caller":"traceutil/trace.go:171","msg":"trace[1090846004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24170; }","duration":"164.375669ms","start":"2026-05-25T01:31:26.018676Z","end":"2026-05-25T01:31:26.183052Z","steps":["trace[1090846004] 'agreement among raft nodes before linearized reading' (duration: 163.996589ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.29445Z","caller":"traceutil/trace.go:171","msg":"trace[144869061] transaction","detail":"{read_only:false; response_revision:24172; number_of_response:1; }","duration":"105.761114ms","start":"2026-05-25T01:31:26.188658Z","end":"2026-05-25T01:31:26.294419Z","steps":["trace[144869061] 'process raft request' (duration: 95.866722ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.294873Z","caller":"traceutil/trace.go:171","msg":"trace[653664068] transaction","detail":"{read_only:false; response_revision:24173; number_of_response:1; }","duration":"104.741066ms","start":"2026-05-25T01:31:26.190069Z","end":"2026-05-25T01:31:26.29481Z","steps":["trace[653664068] 'process raft request' (duration: 104.276453ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.294931Z","caller":"traceutil/trace.go:171","msg":"trace[1044223225] transaction","detail":"{read_only:false; response_revision:24174; number_of_response:1; }","duration":"104.17547ms","start":"2026-05-25T01:31:26.190714Z","end":"2026-05-25T01:31:26.294889Z","steps":["trace[1044223225] 'process raft request' (duration: 103.898482ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.552996Z","caller":"traceutil/trace.go:171","msg":"trace[698696306] transaction","detail":"{read_only:false; response_revision:24176; number_of_response:1; }","duration":"252.2659ms","start":"2026-05-25T01:31:26.300703Z","end":"2026-05-25T01:31:26.552969Z","steps":["trace[698696306] 'process raft request' (duration: 226.240493ms)","trace[698696306] 'compare' (duration: 25.502153ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:26.553191Z","caller":"traceutil/trace.go:171","msg":"trace[1486282668] transaction","detail":"{read_only:false; response_revision:24177; number_of_response:1; }","duration":"252.095445ms","start":"2026-05-25T01:31:26.301075Z","end":"2026-05-25T01:31:26.553171Z","steps":["trace[1486282668] 'process raft request' (duration: 251.777226ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.55362Z","caller":"traceutil/trace.go:171","msg":"trace[786622556] transaction","detail":"{read_only:false; response_revision:24178; number_of_response:1; }","duration":"239.2103ms","start":"2026-05-25T01:31:26.314397Z","end":"2026-05-25T01:31:26.553608Z","steps":["trace[786622556] 'process raft request' (duration: 239.127108ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.865688Z","caller":"traceutil/trace.go:171","msg":"trace[2120249544] transaction","detail":"{read_only:false; response_revision:24179; number_of_response:1; }","duration":"306.287278ms","start":"2026-05-25T01:31:26.559382Z","end":"2026-05-25T01:31:26.865669Z","steps":["trace[2120249544] 'process raft request' (duration: 306.155525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:26.865809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:26.559357Z","time spent":"306.389562ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:26.884431Z","caller":"traceutil/trace.go:171","msg":"trace[594834548] transaction","detail":"{read_only:false; response_revision:24180; number_of_response:1; }","duration":"324.049477ms","start":"2026-05-25T01:31:26.560353Z","end":"2026-05-25T01:31:26.884402Z","steps":["trace[594834548] 'process raft request' (duration: 323.887933ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:26.884428Z","caller":"traceutil/trace.go:171","msg":"trace[1027886477] linearizableReadLoop","detail":"{readStateIndex:24970; appliedIndex:24968; }","duration":"281.991169ms","start":"2026-05-25T01:31:26.602406Z","end":"2026-05-25T01:31:26.884397Z","steps":["trace[1027886477] 'read index received' (duration: 263.175571ms)","trace[1027886477] 'applied index is now lower than readState.Index' (duration: 18.814318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:26.88452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.103062ms","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":"warn","ts":"2026-05-25T01:31:26.884592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:26.560343Z","time spent":"324.162541ms","remote":"127.0.0.1:40256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:26.884591Z","caller":"traceutil/trace.go:171","msg":"trace[1402016854] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24180; }","duration":"282.191055ms","start":"2026-05-25T01:31:26.602373Z","end":"2026-05-25T01:31:26.884565Z","steps":["trace[1402016854] 'agreement among raft nodes before linearized reading' (duration: 282.067711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:26.884685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.111668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-25T01:31:26.884816Z","caller":"traceutil/trace.go:171","msg":"trace[1678129344] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24180; }","duration":"251.294683ms","start":"2026-05-25T01:31:26.633509Z","end":"2026-05-25T01:31:26.884804Z","steps":["trace[1678129344] 'agreement among raft nodes before linearized reading' (duration: 251.039246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:26.884718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.571222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-25T01:31:26.884996Z","caller":"traceutil/trace.go:171","msg":"trace[640428391] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24180; }","duration":"140.971294ms","start":"2026-05-25T01:31:26.744008Z","end":"2026-05-25T01:31:26.884979Z","steps":["trace[640428391] 'agreement among raft nodes before linearized reading' (duration: 140.552333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:26.884835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.980162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T01:31:26.885129Z","caller":"traceutil/trace.go:171","msg":"trace[1933047249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24180; }","duration":"142.2913ms","start":"2026-05-25T01:31:26.742814Z","end":"2026-05-25T01:31:26.885105Z","steps":["trace[1933047249] 'agreement among raft nodes before linearized reading' (duration: 141.95714ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:32.746006Z","caller":"traceutil/trace.go:171","msg":"trace[1897863343] linearizableReadLoop","detail":"{readStateIndex:25024; appliedIndex:25023; }","duration":"152.399308ms","start":"2026-05-25T01:31:32.593589Z","end":"2026-05-25T01:31:32.745989Z","steps":["trace[1897863343] 'read index received' (duration: 152.248774ms)","trace[1897863343] 'applied index is now lower than readState.Index' (duration: 149.904µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:32.746058Z","caller":"traceutil/trace.go:171","msg":"trace[174672085] transaction","detail":"{read_only:false; response_revision:24233; number_of_response:1; }","duration":"166.683432ms","start":"2026-05-25T01:31:32.579345Z","end":"2026-05-25T01:31:32.746028Z","steps":["trace[174672085] 'process raft request' (duration: 166.532568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:32.746217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.608555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-25T01:31:32.746241Z","caller":"traceutil/trace.go:171","msg":"trace[311513556] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24233; }","duration":"152.664566ms","start":"2026-05-25T01:31:32.59357Z","end":"2026-05-25T01:31:32.746235Z","steps":["trace[311513556] 'agreement among raft nodes before linearized reading' (duration: 152.497131ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:33.194149Z","caller":"traceutil/trace.go:171","msg":"trace[542686164] transaction","detail":"{read_only:false; response_revision:24234; number_of_response:1; }","duration":"177.916881ms","start":"2026-05-25T01:31:33.01621Z","end":"2026-05-25T01:31:33.194127Z","steps":["trace[542686164] 'process raft request' (duration: 177.765517ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:33.216729Z","caller":"traceutil/trace.go:171","msg":"trace[1688711217] transaction","detail":"{read_only:false; response_revision:24235; number_of_response:1; }","duration":"199.442304ms","start":"2026-05-25T01:31:33.017262Z","end":"2026-05-25T01:31:33.216705Z","steps":["trace[1688711217] 'process raft request' (duration: 199.28378ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:33.762461Z","caller":"traceutil/trace.go:171","msg":"trace[837924167] transaction","detail":"{read_only:false; response_revision:24236; number_of_response:1; }","duration":"132.690335ms","start":"2026-05-25T01:31:33.62975Z","end":"2026-05-25T01:31:33.762441Z","steps":["trace[837924167] 'process raft request' (duration: 132.537801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.297627Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199889111304510,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-25T01:31:34.303391Z","caller":"traceutil/trace.go:171","msg":"trace[332645292] transaction","detail":"{read_only:false; response_revision:24237; number_of_response:1; }","duration":"657.250497ms","start":"2026-05-25T01:31:33.646117Z","end":"2026-05-25T01:31:34.303368Z","steps":["trace[332645292] 'process raft request' (duration: 657.057532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.303547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:33.646085Z","time spent":"657.383731ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:34.303599Z","caller":"traceutil/trace.go:171","msg":"trace[1484747807] transaction","detail":"{read_only:false; response_revision:24238; number_of_response:1; }","duration":"657.477543ms","start":"2026-05-25T01:31:33.646105Z","end":"2026-05-25T01:31:34.303582Z","steps":["trace[1484747807] 'process raft request' (duration: 657.221496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.3037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:33.646065Z","time spent":"657.572896ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:34.303741Z","caller":"traceutil/trace.go:171","msg":"trace[576957847] transaction","detail":"{read_only:false; response_revision:24239; number_of_response:1; }","duration":"657.602367ms","start":"2026-05-25T01:31:33.646127Z","end":"2026-05-25T01:31:34.30373Z","steps":["trace[576957847] 'process raft request' (duration: 657.398911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.303795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:33.646115Z","time spent":"657.649208ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:34.303853Z","caller":"traceutil/trace.go:171","msg":"trace[1782008768] transaction","detail":"{read_only:false; response_revision:24240; number_of_response:1; }","duration":"657.686289ms","start":"2026-05-25T01:31:33.646161Z","end":"2026-05-25T01:31:34.303847Z","steps":["trace[1782008768] 'process raft request' (duration: 657.535425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.303889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:33.646065Z","time spent":"657.806563ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:34.304044Z","caller":"traceutil/trace.go:171","msg":"trace[115815196] transaction","detail":"{read_only:false; response_revision:24241; number_of_response:1; }","duration":"657.835323ms","start":"2026-05-25T01:31:33.6462Z","end":"2026-05-25T01:31:34.304035Z","steps":["trace[115815196] 'process raft request' (duration: 657.623417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.30408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:33.646193Z","time spent":"657.869834ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:34.304068Z","caller":"traceutil/trace.go:171","msg":"trace[1426039101] linearizableReadLoop","detail":"{readStateIndex:25033; appliedIndex:25028; }","duration":"507.43489ms","start":"2026-05-25T01:31:33.796612Z","end":"2026-05-25T01:31:34.304047Z","steps":["trace[1426039101] 'read index received' (duration: 506.700619ms)","trace[1426039101] 'applied index is now lower than readState.Index' (duration: 733.431µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:34.304137Z","caller":"traceutil/trace.go:171","msg":"trace[704119821] transaction","detail":"{read_only:false; response_revision:24242; number_of_response:1; }","duration":"657.936166ms","start":"2026-05-25T01:31:33.646196Z","end":"2026-05-25T01:31:34.304132Z","steps":["trace[704119821] 'process raft request' (duration: 657.766751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.304166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:33.646188Z","time spent":"657.962676ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:34.304225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.66221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-25T01:31:34.304209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.583324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:34.304267Z","caller":"traceutil/trace.go:171","msg":"trace[303324394] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:24242; }","duration":"428.755362ms","start":"2026-05-25T01:31:33.875503Z","end":"2026-05-25T01:31:34.304258Z","steps":["trace[303324394] 'agreement among raft nodes before linearized reading' (duration: 428.65777ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:34.304299Z","caller":"traceutil/trace.go:171","msg":"trace[2082920480] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24242; }","duration":"507.714797ms","start":"2026-05-25T01:31:33.796565Z","end":"2026-05-25T01:31:34.30428Z","steps":["trace[2082920480] 'agreement among raft nodes before linearized reading' (duration: 507.582833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.304352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:33.796547Z","time spent":"507.79476ms","remote":"127.0.0.1:38138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-25T01:31:34.304306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:33.875483Z","time spent":"428.810143ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":16,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-25T01:31:34.304515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.234664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:34.304564Z","caller":"traceutil/trace.go:171","msg":"trace[970033880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24242; }","duration":"213.310316ms","start":"2026-05-25T01:31:34.091243Z","end":"2026-05-25T01:31:34.304553Z","steps":["trace[970033880] 'agreement among raft nodes before linearized reading' (duration: 213.245705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.812708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199889111304522,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-25T01:31:34.828382Z","caller":"traceutil/trace.go:171","msg":"trace[1547195696] transaction","detail":"{read_only:false; response_revision:24243; number_of_response:1; }","duration":"519.753579ms","start":"2026-05-25T01:31:34.308606Z","end":"2026-05-25T01:31:34.828359Z","steps":["trace[1547195696] 'process raft request' (duration: 519.608105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.828547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:34.30859Z","time spent":"519.866992ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:34.830505Z","caller":"traceutil/trace.go:171","msg":"trace[1273737189] transaction","detail":"{read_only:false; response_revision:24244; number_of_response:1; }","duration":"520.936312ms","start":"2026-05-25T01:31:34.309546Z","end":"2026-05-25T01:31:34.830482Z","steps":["trace[1273737189] 'process raft request' (duration: 520.790918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.830664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:34.309529Z","time spent":"521.042864ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:34.830688Z","caller":"traceutil/trace.go:171","msg":"trace[777029870] linearizableReadLoop","detail":"{readStateIndex:25036; appliedIndex:25034; }","duration":"518.279248ms","start":"2026-05-25T01:31:34.312392Z","end":"2026-05-25T01:31:34.830671Z","steps":["trace[777029870] 'read index received' (duration: 516.003355ms)","trace[777029870] 'applied index is now lower than readState.Index' (duration: 2.274943ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:34.83086Z","caller":"traceutil/trace.go:171","msg":"trace[1533741638] transaction","detail":"{read_only:false; response_revision:24245; number_of_response:1; }","duration":"519.026649ms","start":"2026-05-25T01:31:34.311823Z","end":"2026-05-25T01:31:34.830849Z","steps":["trace[1533741638] 'process raft request' (duration: 518.621038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.83093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:34.311802Z","time spent":"519.08948ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:34.831109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.105836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-05-25T01:31:34.831158Z","caller":"traceutil/trace.go:171","msg":"trace[1244288447] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:24245; }","duration":"158.184008ms","start":"2026-05-25T01:31:34.67296Z","end":"2026-05-25T01:31:34.831144Z","steps":["trace[1244288447] 'agreement among raft nodes before linearized reading' (duration: 158.048944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.831189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.790452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:34.831233Z","caller":"traceutil/trace.go:171","msg":"trace[1280131314] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24245; }","duration":"518.859684ms","start":"2026-05-25T01:31:34.312361Z","end":"2026-05-25T01:31:34.831221Z","steps":["trace[1280131314] 'agreement among raft nodes before linearized reading' (duration: 518.793272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:34.831268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:34.312348Z","time spent":"518.913515ms","remote":"127.0.0.1:35374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-05-25T01:31:40.984706Z","caller":"traceutil/trace.go:171","msg":"trace[334489239] linearizableReadLoop","detail":"{readStateIndex:25141; appliedIndex:25140; }","duration":"207.700812ms","start":"2026-05-25T01:31:40.776977Z","end":"2026-05-25T01:31:40.984678Z","steps":["trace[334489239] 'read index received' (duration: 207.405514ms)","trace[334489239] 'applied index is now lower than readState.Index' (duration: 294.188µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:40.984795Z","caller":"traceutil/trace.go:171","msg":"trace[120352972] transaction","detail":"{read_only:false; response_revision:24349; number_of_response:1; }","duration":"211.578709ms","start":"2026-05-25T01:31:40.773189Z","end":"2026-05-25T01:31:40.984768Z","steps":["trace[120352972] 'process raft request' (duration: 211.300731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:40.984909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.907028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-976e4263-5f4b-433c-9db6-ebe8099958ea\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-25T01:31:40.984979Z","caller":"traceutil/trace.go:171","msg":"trace[644510360] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-976e4263-5f4b-433c-9db6-ebe8099958ea; range_end:; response_count:1; response_revision:24349; }","duration":"208.026521ms","start":"2026-05-25T01:31:40.776935Z","end":"2026-05-25T01:31:40.984962Z","steps":["trace[644510360] 'agreement among raft nodes before linearized reading' (duration: 207.862196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:40.984996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.478491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b2aae42d545298\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-05-25T01:31:40.985025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.14133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-25T01:31:40.985028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.988996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-25T01:31:40.985053Z","caller":"traceutil/trace.go:171","msg":"trace[1429813112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24349; }","duration":"132.193932ms","start":"2026-05-25T01:31:40.852848Z","end":"2026-05-25T01:31:40.985042Z","steps":["trace[1429813112] 'agreement among raft nodes before linearized reading' (duration: 132.079099ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:40.985057Z","caller":"traceutil/trace.go:171","msg":"trace[13153025] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b2aae42d545298; range_end:; response_count:1; response_revision:24349; }","duration":"160.584424ms","start":"2026-05-25T01:31:40.824454Z","end":"2026-05-25T01:31:40.985039Z","steps":["trace[13153025] 'agreement among raft nodes before linearized reading' (duration: 160.371538ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:40.985087Z","caller":"traceutil/trace.go:171","msg":"trace[774201594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24349; }","duration":"128.093209ms","start":"2026-05-25T01:31:40.856979Z","end":"2026-05-25T01:31:40.985073Z","steps":["trace[774201594] 'agreement among raft nodes before linearized reading' (duration: 127.901184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:40.985078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.401263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-25T01:31:40.985138Z","caller":"traceutil/trace.go:171","msg":"trace[433948342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24349; }","duration":"126.465674ms","start":"2026-05-25T01:31:40.858654Z","end":"2026-05-25T01:31:40.98512Z","steps":["trace[433948342] 'agreement among raft nodes before linearized reading' (duration: 126.30133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:40.985273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.070924ms","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-25T01:31:40.98535Z","caller":"traceutil/trace.go:171","msg":"trace[278057401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24349; }","duration":"134.189927ms","start":"2026-05-25T01:31:40.851139Z","end":"2026-05-25T01:31:40.985329Z","steps":["trace[278057401] 'agreement among raft nodes before linearized reading' (duration: 133.991932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:41.169169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.568893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:31:41.169312Z","caller":"traceutil/trace.go:171","msg":"trace[1413071318] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"180.535993ms","start":"2026-05-25T01:31:40.988758Z","end":"2026-05-25T01:31:41.169294Z","steps":["trace[1413071318] 'process raft request' (duration: 76.737554ms)","trace[1413071318] 'compare' (duration: 103.342387ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:41.303455Z","caller":"traceutil/trace.go:171","msg":"trace[332327831] transaction","detail":"{read_only:false; response_revision:24351; number_of_response:1; }","duration":"314.535976ms","start":"2026-05-25T01:31:40.988893Z","end":"2026-05-25T01:31:41.303428Z","steps":["trace[332327831] 'process raft request' (duration: 314.184166ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:41.303526Z","caller":"traceutil/trace.go:171","msg":"trace[148604504] transaction","detail":"{read_only:false; response_revision:24352; number_of_response:1; }","duration":"314.433273ms","start":"2026-05-25T01:31:40.989058Z","end":"2026-05-25T01:31:41.303491Z","steps":["trace[148604504] 'process raft request' (duration: 314.274338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:41.303609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:40.988875Z","time spent":"314.649109ms","remote":"127.0.0.1:35262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:41.303732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:40.989041Z","time spent":"314.577136ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:41.304046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.253988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T01:31:41.304052Z","caller":"traceutil/trace.go:171","msg":"trace[1425005293] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"314.297499ms","start":"2026-05-25T01:31:40.989723Z","end":"2026-05-25T01:31:41.304021Z","steps":["trace[1425005293] 'process raft request' (duration: 314.072663ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:41.304121Z","caller":"traceutil/trace.go:171","msg":"trace[2117065860] linearizableReadLoop","detail":"{readStateIndex:25146; appliedIndex:25142; }","duration":"110.205676ms","start":"2026-05-25T01:31:41.193774Z","end":"2026-05-25T01:31:41.30398Z","steps":["trace[2117065860] 'read index received' (duration: 109.399054ms)","trace[2117065860] 'applied index is now lower than readState.Index' (duration: 805.432µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:41.304124Z","caller":"traceutil/trace.go:171","msg":"trace[1567524842] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24354; }","duration":"110.387022ms","start":"2026-05-25T01:31:41.193719Z","end":"2026-05-25T01:31:41.304106Z","steps":["trace[1567524842] 'agreement among raft nodes before linearized reading' (duration: 110.265938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:41.304206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:40.989714Z","time spent":"314.411242ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:41.304017Z","caller":"traceutil/trace.go:171","msg":"trace[211571790] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"314.411592ms","start":"2026-05-25T01:31:40.989567Z","end":"2026-05-25T01:31:41.303978Z","steps":["trace[211571790] 'process raft request' (duration: 313.839556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:41.304382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:40.989558Z","time spent":"314.743591ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:41.418999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.257208ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T01:31:41.419116Z","caller":"traceutil/trace.go:171","msg":"trace[520826997] linearizableReadLoop","detail":"{readStateIndex:25147; appliedIndex:25146; }","duration":"114.943427ms","start":"2026-05-25T01:31:41.304153Z","end":"2026-05-25T01:31:41.419097Z","steps":["trace[520826997] 'read index received' (duration: 12.485594ms)","trace[520826997] 'applied index is now lower than readState.Index' (duration: 102.455933ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:41.419257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.768089ms","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":"warn","ts":"2026-05-25T01:31:41.419329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.848333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-25T01:31:41.419331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.120514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-25T01:31:41.419386Z","caller":"traceutil/trace.go:171","msg":"trace[927732512] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24354; }","duration":"176.926844ms","start":"2026-05-25T01:31:41.242444Z","end":"2026-05-25T01:31:41.419371Z","steps":["trace[927732512] 'agreement among raft nodes before linearized reading' (duration: 176.76395ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:41.419406Z","caller":"traceutil/trace.go:171","msg":"trace[1568885503] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24354; }","duration":"167.220887ms","start":"2026-05-25T01:31:41.252163Z","end":"2026-05-25T01:31:41.419384Z","steps":["trace[1568885503] 'agreement among raft nodes before linearized reading' (duration: 166.997781ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:41.419336Z","caller":"traceutil/trace.go:171","msg":"trace[1065934906] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24354; }","duration":"160.909794ms","start":"2026-05-25T01:31:41.258407Z","end":"2026-05-25T01:31:41.419317Z","steps":["trace[1065934906] 'agreement among raft nodes before linearized reading' (duration: 160.741749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:41.419538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.583054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T01:31:41.419621Z","caller":"traceutil/trace.go:171","msg":"trace[781223879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24354; }","duration":"197.714327ms","start":"2026-05-25T01:31:41.221888Z","end":"2026-05-25T01:31:41.419602Z","steps":["trace[781223879] 'agreement among raft nodes before linearized reading' (duration: 197.482721ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:41.5522Z","caller":"traceutil/trace.go:171","msg":"trace[394243462] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"127.982686ms","start":"2026-05-25T01:31:41.424185Z","end":"2026-05-25T01:31:41.552168Z","steps":["trace[394243462] 'process raft request' (duration: 127.77021ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:41.581681Z","caller":"traceutil/trace.go:171","msg":"trace[262163994] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"157.019306ms","start":"2026-05-25T01:31:41.424633Z","end":"2026-05-25T01:31:41.581652Z","steps":["trace[262163994] 'process raft request' (duration: 156.846201ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:41.988273Z","caller":"traceutil/trace.go:171","msg":"trace[1335624482] linearizableReadLoop","detail":"{readStateIndex:25150; appliedIndex:25149; }","duration":"207.26097ms","start":"2026-05-25T01:31:41.780988Z","end":"2026-05-25T01:31:41.988249Z","steps":["trace[1335624482] 'read index received' (duration: 207.088375ms)","trace[1335624482] 'applied index is now lower than readState.Index' (duration: 171.725µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:41.988348Z","caller":"traceutil/trace.go:171","msg":"trace[557300104] transaction","detail":"{read_only:false; response_revision:24357; number_of_response:1; }","duration":"212.763481ms","start":"2026-05-25T01:31:41.775542Z","end":"2026-05-25T01:31:41.988305Z","steps":["trace[557300104] 'process raft request' (duration: 212.305839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:41.988569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.544077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-05-25T01:31:41.988675Z","caller":"traceutil/trace.go:171","msg":"trace[265450725] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24357; }","duration":"207.683942ms","start":"2026-05-25T01:31:41.780968Z","end":"2026-05-25T01:31:41.988652Z","steps":["trace[265450725] 'agreement among raft nodes before linearized reading' (duration: 207.375183ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:42.002891Z","caller":"traceutil/trace.go:171","msg":"trace[335484513] transaction","detail":"{read_only:false; response_revision:24358; number_of_response:1; }","duration":"195.657379ms","start":"2026-05-25T01:31:41.807202Z","end":"2026-05-25T01:31:42.00286Z","steps":["trace[335484513] 'process raft request' (duration: 194.520088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:42.003656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.743794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:42.003762Z","caller":"traceutil/trace.go:171","msg":"trace[693313799] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:24358; }","duration":"100.901558ms","start":"2026-05-25T01:31:41.902837Z","end":"2026-05-25T01:31:42.003738Z","steps":["trace[693313799] 'agreement among raft nodes before linearized reading' (duration: 100.750244ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:42.168256Z","caller":"traceutil/trace.go:171","msg":"trace[466909942] linearizableReadLoop","detail":"{readStateIndex:25152; appliedIndex:25151; }","duration":"157.457218ms","start":"2026-05-25T01:31:42.010771Z","end":"2026-05-25T01:31:42.168229Z","steps":["trace[466909942] 'read index received' (duration: 104.328815ms)","trace[466909942] 'applied index is now lower than readState.Index' (duration: 53.127043ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:42.168403Z","caller":"traceutil/trace.go:171","msg":"trace[1191896361] transaction","detail":"{read_only:false; response_revision:24359; number_of_response:1; }","duration":"162.678992ms","start":"2026-05-25T01:31:42.005709Z","end":"2026-05-25T01:31:42.168388Z","steps":["trace[1191896361] 'process raft request' (duration: 109.400644ms)","trace[1191896361] 'compare' (duration: 52.829615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:42.168682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.857346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-05-25T01:31:42.168755Z","caller":"traceutil/trace.go:171","msg":"trace[2068344397] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24359; }","duration":"154.954649ms","start":"2026-05-25T01:31:42.013784Z","end":"2026-05-25T01:31:42.168739Z","steps":["trace[2068344397] 'agreement among raft nodes before linearized reading' (duration: 154.690142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:42.16886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.107605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:42.168958Z","caller":"traceutil/trace.go:171","msg":"trace[991777575] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24359; }","duration":"158.2468ms","start":"2026-05-25T01:31:42.010689Z","end":"2026-05-25T01:31:42.168936Z","steps":["trace[991777575] 'agreement among raft nodes before linearized reading' (duration: 158.045494ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:47.011757Z","caller":"traceutil/trace.go:171","msg":"trace[582458677] transaction","detail":"{read_only:false; response_revision:24417; number_of_response:1; }","duration":"109.030133ms","start":"2026-05-25T01:31:46.902695Z","end":"2026-05-25T01:31:47.011725Z","steps":["trace[582458677] 'process raft request' (duration: 108.771636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:47.344204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.62712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:31:47.344355Z","caller":"traceutil/trace.go:171","msg":"trace[817288192] transaction","detail":"{read_only:false; response_revision:24419; number_of_response:1; }","duration":"327.059001ms","start":"2026-05-25T01:31:47.017282Z","end":"2026-05-25T01:31:47.344341Z","steps":["trace[817288192] 'process raft request' (duration: 172.232655ms)","trace[817288192] 'compare' (duration: 154.542558ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:47.344387Z","caller":"traceutil/trace.go:171","msg":"trace[333608150] transaction","detail":"{read_only:false; response_revision:24420; number_of_response:1; }","duration":"327.0142ms","start":"2026-05-25T01:31:47.01735Z","end":"2026-05-25T01:31:47.344364Z","steps":["trace[333608150] 'process raft request' (duration: 326.922897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:47.344425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:47.017273Z","time spent":"327.113172ms","remote":"127.0.0.1:35334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:47.3445Z","caller":"traceutil/trace.go:171","msg":"trace[519470821] linearizableReadLoop","detail":"{readStateIndex:25216; appliedIndex:25212; }","duration":"319.720539ms","start":"2026-05-25T01:31:47.024761Z","end":"2026-05-25T01:31:47.344482Z","steps":["trace[519470821] 'read index received' (duration: 26.350636ms)","trace[519470821] 'applied index is now lower than readState.Index' (duration: 293.368983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:47.344516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:47.017341Z","time spent":"327.128642ms","remote":"127.0.0.1:35334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:31:47.344632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.431288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:47.34467Z","caller":"traceutil/trace.go:171","msg":"trace[1677231388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24421; }","duration":"255.499009ms","start":"2026-05-25T01:31:47.089159Z","end":"2026-05-25T01:31:47.344658Z","steps":["trace[1677231388] 'agreement among raft nodes before linearized reading' (duration: 255.436447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:47.344653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.863153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:47.34466Z","caller":"traceutil/trace.go:171","msg":"trace[175113584] transaction","detail":"{read_only:false; response_revision:24421; number_of_response:1; }","duration":"326.844404ms","start":"2026-05-25T01:31:47.017764Z","end":"2026-05-25T01:31:47.344609Z","steps":["trace[175113584] 'process raft request' (duration: 326.540126ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:47.344736Z","caller":"traceutil/trace.go:171","msg":"trace[1647246099] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:24421; }","duration":"319.973285ms","start":"2026-05-25T01:31:47.024746Z","end":"2026-05-25T01:31:47.344719Z","steps":["trace[1647246099] 'agreement among raft nodes before linearized reading' (duration: 319.832871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:47.344746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.781845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-25T01:31:47.344784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:47.024739Z","time spent":"320.034897ms","remote":"127.0.0.1:35312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-25T01:31:47.344748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.575832ms","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-25T01:31:47.344816Z","caller":"traceutil/trace.go:171","msg":"trace[90262127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24421; }","duration":"150.892538ms","start":"2026-05-25T01:31:47.193907Z","end":"2026-05-25T01:31:47.344799Z","steps":["trace[90262127] 'agreement among raft nodes before linearized reading' (duration: 150.695312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:47.344836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:31:47.017757Z","time spent":"326.989699ms","remote":"127.0.0.1:35446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:31:47.344863Z","caller":"traceutil/trace.go:171","msg":"trace[1431510149] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24421; }","duration":"128.749647ms","start":"2026-05-25T01:31:47.216102Z","end":"2026-05-25T01:31:47.344852Z","steps":["trace[1431510149] 'agreement among raft nodes before linearized reading' (duration: 128.49689ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:47.486812Z","caller":"traceutil/trace.go:171","msg":"trace[554413408] transaction","detail":"{read_only:false; response_revision:24422; number_of_response:1; }","duration":"137.882179ms","start":"2026-05-25T01:31:47.348867Z","end":"2026-05-25T01:31:47.486749Z","steps":["trace[554413408] 'process raft request' (duration: 136.935562ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:47.486855Z","caller":"traceutil/trace.go:171","msg":"trace[368339792] transaction","detail":"{read_only:false; response_revision:24423; number_of_response:1; }","duration":"137.895379ms","start":"2026-05-25T01:31:47.348929Z","end":"2026-05-25T01:31:47.486825Z","steps":["trace[368339792] 'process raft request' (duration: 137.674552ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:47.486913Z","caller":"traceutil/trace.go:171","msg":"trace[1083736334] transaction","detail":"{read_only:false; response_revision:24424; number_of_response:1; }","duration":"137.730375ms","start":"2026-05-25T01:31:47.349145Z","end":"2026-05-25T01:31:47.486876Z","steps":["trace[1083736334] 'process raft request' (duration: 137.5645ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:47.487151Z","caller":"traceutil/trace.go:171","msg":"trace[1450711821] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"137.845337ms","start":"2026-05-25T01:31:47.349296Z","end":"2026-05-25T01:31:47.487141Z","steps":["trace[1450711821] 'process raft request' (duration: 137.451576ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:57.492453Z","caller":"traceutil/trace.go:171","msg":"trace[500424924] linearizableReadLoop","detail":"{readStateIndex:25299; appliedIndex:25298; }","duration":"194.56149ms","start":"2026-05-25T01:31:57.297865Z","end":"2026-05-25T01:31:57.492426Z","steps":["trace[500424924] 'read index received' (duration: 194.325314ms)","trace[500424924] 'applied index is now lower than readState.Index' (duration: 234.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:57.492544Z","caller":"traceutil/trace.go:171","msg":"trace[1400545461] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"196.456412ms","start":"2026-05-25T01:31:57.296063Z","end":"2026-05-25T01:31:57.492519Z","steps":["trace[1400545461] 'process raft request' (duration: 196.137123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:57.492627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.741385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:31:57.492673Z","caller":"traceutil/trace.go:171","msg":"trace[627902401] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24502; }","duration":"194.830018ms","start":"2026-05-25T01:31:57.297829Z","end":"2026-05-25T01:31:57.492659Z","steps":["trace[627902401] 'agreement among raft nodes before linearized reading' (duration: 194.736515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:57.493021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.071466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-05-25T01:31:57.493054Z","caller":"traceutil/trace.go:171","msg":"trace[1005064025] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24502; }","duration":"190.131608ms","start":"2026-05-25T01:31:57.302913Z","end":"2026-05-25T01:31:57.493045Z","steps":["trace[1005064025] 'agreement among raft nodes before linearized reading' (duration: 189.974364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:57.775802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.492992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:31:57.775909Z","caller":"traceutil/trace.go:171","msg":"trace[1950577081] linearizableReadLoop","detail":"{readStateIndex:25300; appliedIndex:25299; }","duration":"240.848985ms","start":"2026-05-25T01:31:57.535044Z","end":"2026-05-25T01:31:57.775893Z","steps":["trace[1950577081] 'read index received' (duration: 72.181708ms)","trace[1950577081] 'applied index is now lower than readState.Index' (duration: 168.666027ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:31:57.775981Z","caller":"traceutil/trace.go:171","msg":"trace[1209593740] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"276.738664ms","start":"2026-05-25T01:31:57.499204Z","end":"2026-05-25T01:31:57.775942Z","steps":["trace[1209593740] 'process raft request' (duration: 108.018046ms)","trace[1209593740] 'compare' (duration: 167.981188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:31:57.776098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.000279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-25T01:31:57.776085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.0229ms","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-25T01:31:57.776128Z","caller":"traceutil/trace.go:171","msg":"trace[475943988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24503; }","duration":"241.059191ms","start":"2026-05-25T01:31:57.535059Z","end":"2026-05-25T01:31:57.776118Z","steps":["trace[475943988] 'agreement among raft nodes before linearized reading' (duration: 240.929927ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:57.776153Z","caller":"traceutil/trace.go:171","msg":"trace[1068499990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24503; }","duration":"241.118302ms","start":"2026-05-25T01:31:57.535016Z","end":"2026-05-25T01:31:57.776134Z","steps":["trace[1068499990] 'agreement among raft nodes before linearized reading' (duration: 240.937287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:57.776314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.041944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-25T01:31:57.776355Z","caller":"traceutil/trace.go:171","msg":"trace[1885404448] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24503; }","duration":"121.116797ms","start":"2026-05-25T01:31:57.655226Z","end":"2026-05-25T01:31:57.776343Z","steps":["trace[1885404448] 'agreement among raft nodes before linearized reading' (duration: 121.026855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:57.776414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.579681ms","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-25T01:31:57.776454Z","caller":"traceutil/trace.go:171","msg":"trace[263680943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24503; }","duration":"149.621452ms","start":"2026-05-25T01:31:57.626824Z","end":"2026-05-25T01:31:57.776445Z","steps":["trace[263680943] 'agreement among raft nodes before linearized reading' (duration: 149.516309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:57.77655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.40045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-25T01:31:57.776598Z","caller":"traceutil/trace.go:171","msg":"trace[1344228891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24503; }","duration":"241.498483ms","start":"2026-05-25T01:31:57.535087Z","end":"2026-05-25T01:31:57.776586Z","steps":["trace[1344228891] 'agreement among raft nodes before linearized reading' (duration: 241.334078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:31:57.77672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.540195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-25T01:31:57.776752Z","caller":"traceutil/trace.go:171","msg":"trace[1499034002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24503; }","duration":"241.610376ms","start":"2026-05-25T01:31:57.535133Z","end":"2026-05-25T01:31:57.776743Z","steps":["trace[1499034002] 'agreement among raft nodes before linearized reading' (duration: 241.535234ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:57.95985Z","caller":"traceutil/trace.go:171","msg":"trace[400063560] transaction","detail":"{read_only:false; response_revision:24505; number_of_response:1; }","duration":"176.77895ms","start":"2026-05-25T01:31:57.783049Z","end":"2026-05-25T01:31:57.959828Z","steps":["trace[400063560] 'process raft request' (duration: 176.638257ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:57.96006Z","caller":"traceutil/trace.go:171","msg":"trace[1388669398] transaction","detail":"{read_only:false; response_revision:24506; number_of_response:1; }","duration":"176.974785ms","start":"2026-05-25T01:31:57.783068Z","end":"2026-05-25T01:31:57.960043Z","steps":["trace[1388669398] 'process raft request' (duration: 176.730599ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:57.960169Z","caller":"traceutil/trace.go:171","msg":"trace[1993135099] transaction","detail":"{read_only:false; response_revision:24508; number_of_response:1; }","duration":"176.491723ms","start":"2026-05-25T01:31:57.783665Z","end":"2026-05-25T01:31:57.960157Z","steps":["trace[1993135099] 'process raft request' (duration: 176.354269ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:57.960259Z","caller":"traceutil/trace.go:171","msg":"trace[1838770835] transaction","detail":"{read_only:false; response_revision:24507; number_of_response:1; }","duration":"176.806661ms","start":"2026-05-25T01:31:57.783444Z","end":"2026-05-25T01:31:57.96025Z","steps":["trace[1838770835] 'process raft request' (duration: 176.497073ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:31:57.960347Z","caller":"traceutil/trace.go:171","msg":"trace[478859555] transaction","detail":"{read_only:false; response_revision:24509; number_of_response:1; }","duration":"176.325377ms","start":"2026-05-25T01:31:57.784009Z","end":"2026-05-25T01:31:57.960335Z","steps":["trace[478859555] 'process raft request' (duration: 176.0603ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:07.148549Z","caller":"traceutil/trace.go:171","msg":"trace[1430328294] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"131.177292ms","start":"2026-05-25T01:32:07.017349Z","end":"2026-05-25T01:32:07.148526Z","steps":["trace[1430328294] 'process raft request' (duration: 130.998337ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:07.387908Z","caller":"traceutil/trace.go:171","msg":"trace[1974104885] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"231.059095ms","start":"2026-05-25T01:32:07.15681Z","end":"2026-05-25T01:32:07.38787Z","steps":["trace[1974104885] 'process raft request' (duration: 142.192517ms)","trace[1974104885] 'compare' (duration: 88.730025ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:07.560639Z","caller":"traceutil/trace.go:171","msg":"trace[279760867] linearizableReadLoop","detail":"{readStateIndex:25387; appliedIndex:25386; }","duration":"105.162688ms","start":"2026-05-25T01:32:07.455456Z","end":"2026-05-25T01:32:07.560619Z","steps":["trace[279760867] 'read index received' (duration: 48.668911ms)","trace[279760867] 'applied index is now lower than readState.Index' (duration: 56.492637ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:07.560749Z","caller":"traceutil/trace.go:171","msg":"trace[213566641] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"110.028341ms","start":"2026-05-25T01:32:07.450678Z","end":"2026-05-25T01:32:07.560706Z","steps":["trace[213566641] 'process raft request' (duration: 53.476093ms)","trace[213566641] 'compare' (duration: 56.306351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:32:07.560802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.331402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-05-25T01:32:07.560967Z","caller":"traceutil/trace.go:171","msg":"trace[484539959] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24587; }","duration":"105.550228ms","start":"2026-05-25T01:32:07.455405Z","end":"2026-05-25T01:32:07.560956Z","steps":["trace[484539959] 'agreement among raft nodes before linearized reading' (duration: 105.284031ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:08.176035Z","caller":"traceutil/trace.go:171","msg":"trace[489210181] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"168.338098ms","start":"2026-05-25T01:32:08.007669Z","end":"2026-05-25T01:32:08.176007Z","steps":["trace[489210181] 'process raft request' (duration: 79.247703ms)","trace[489210181] 'compare' (duration: 88.818817ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:08.176267Z","caller":"traceutil/trace.go:171","msg":"trace[1134290] linearizableReadLoop","detail":"{readStateIndex:25391; appliedIndex:25388; }","duration":"164.355008ms","start":"2026-05-25T01:32:08.011877Z","end":"2026-05-25T01:32:08.176232Z","steps":["trace[1134290] 'read index received' (duration: 75.062648ms)","trace[1134290] 'applied index is now lower than readState.Index' (duration: 89.29153ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:08.176376Z","caller":"traceutil/trace.go:171","msg":"trace[2054616768] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"166.548499ms","start":"2026-05-25T01:32:08.009814Z","end":"2026-05-25T01:32:08.176363Z","steps":["trace[2054616768] 'process raft request' (duration: 166.157798ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:08.176334Z","caller":"traceutil/trace.go:171","msg":"trace[2133318534] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"166.428305ms","start":"2026-05-25T01:32:08.009885Z","end":"2026-05-25T01:32:08.176313Z","steps":["trace[2133318534] 'process raft request' (duration: 166.289831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:08.176498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.178413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-25T01:32:08.176516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.615825ms","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-25T01:32:08.176545Z","caller":"traceutil/trace.go:171","msg":"trace[2072115649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24591; }","duration":"164.256425ms","start":"2026-05-25T01:32:08.012279Z","end":"2026-05-25T01:32:08.176535Z","steps":["trace[2072115649] 'agreement among raft nodes before linearized reading' (duration: 164.103501ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:08.176587Z","caller":"traceutil/trace.go:171","msg":"trace[1354982773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24591; }","duration":"164.715498ms","start":"2026-05-25T01:32:08.011854Z","end":"2026-05-25T01:32:08.176569Z","steps":["trace[1354982773] 'agreement among raft nodes before linearized reading' (duration: 164.513283ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:08.416863Z","caller":"traceutil/trace.go:171","msg":"trace[35594938] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"235.615201ms","start":"2026-05-25T01:32:08.181228Z","end":"2026-05-25T01:32:08.416843Z","steps":["trace[35594938] 'process raft request' (duration: 182.295272ms)","trace[35594938] 'compare' (duration: 53.028781ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:08.47034Z","caller":"traceutil/trace.go:171","msg":"trace[870487723] linearizableReadLoop","detail":"{readStateIndex:25395; appliedIndex:25392; }","duration":"182.995281ms","start":"2026-05-25T01:32:08.287328Z","end":"2026-05-25T01:32:08.470323Z","steps":["trace[870487723] 'read index received' (duration: 76.189869ms)","trace[870487723] 'applied index is now lower than readState.Index' (duration: 106.804122ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:08.470378Z","caller":"traceutil/trace.go:171","msg":"trace[769337005] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"245.328869ms","start":"2026-05-25T01:32:08.225019Z","end":"2026-05-25T01:32:08.470348Z","steps":["trace[769337005] 'process raft request' (duration: 245.173805ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:08.470405Z","caller":"traceutil/trace.go:171","msg":"trace[511415703] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"186.602122ms","start":"2026-05-25T01:32:08.283767Z","end":"2026-05-25T01:32:08.470369Z","steps":["trace[511415703] 'process raft request' (duration: 186.521979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:08.470474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.127355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:32:08.470557Z","caller":"traceutil/trace.go:171","msg":"trace[1274892245] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24595; }","duration":"183.244258ms","start":"2026-05-25T01:32:08.287297Z","end":"2026-05-25T01:32:08.470541Z","steps":["trace[1274892245] 'agreement among raft nodes before linearized reading' (duration: 183.091174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:08.47469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.376855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:32:08.474756Z","caller":"traceutil/trace.go:171","msg":"trace[791060624] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:24601; }","duration":"125.482218ms","start":"2026-05-25T01:32:08.349252Z","end":"2026-05-25T01:32:08.474734Z","steps":["trace[791060624] 'agreement among raft nodes before linearized reading' (duration: 125.388605ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:10.325947Z","caller":"traceutil/trace.go:171","msg":"trace[837241298] transaction","detail":"{read_only:false; response_revision:24604; number_of_response:1; }","duration":"141.903449ms","start":"2026-05-25T01:32:10.184022Z","end":"2026-05-25T01:32:10.325925Z","steps":["trace[837241298] 'process raft request' (duration: 141.757445ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:10.361645Z","caller":"traceutil/trace.go:171","msg":"trace[955464873] linearizableReadLoop","detail":"{readStateIndex:25406; appliedIndex:25403; }","duration":"133.801196ms","start":"2026-05-25T01:32:10.227822Z","end":"2026-05-25T01:32:10.361623Z","steps":["trace[955464873] 'read index received' (duration: 97.99869ms)","trace[955464873] 'applied index is now lower than readState.Index' (duration: 35.801676ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:10.361721Z","caller":"traceutil/trace.go:171","msg":"trace[884485646] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"177.48456ms","start":"2026-05-25T01:32:10.184213Z","end":"2026-05-25T01:32:10.361698Z","steps":["trace[884485646] 'process raft request' (duration: 176.164804ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:10.361749Z","caller":"traceutil/trace.go:171","msg":"trace[1679250052] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"176.685728ms","start":"2026-05-25T01:32:10.185047Z","end":"2026-05-25T01:32:10.361732Z","steps":["trace[1679250052] 'process raft request' (duration: 176.482282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:10.361854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.009063ms","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-25T01:32:10.361918Z","caller":"traceutil/trace.go:171","msg":"trace[112219052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24606; }","duration":"134.125676ms","start":"2026-05-25T01:32:10.227778Z","end":"2026-05-25T01:32:10.361903Z","steps":["trace[112219052] 'agreement among raft nodes before linearized reading' (duration: 133.9348ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:14.444558Z","caller":"traceutil/trace.go:171","msg":"trace[1775507793] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"103.719937ms","start":"2026-05-25T01:32:14.340795Z","end":"2026-05-25T01:32:14.444515Z","steps":["trace[1775507793] 'process raft request' (duration: 103.418629ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.078975Z","caller":"traceutil/trace.go:171","msg":"trace[1979422456] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"111.712828ms","start":"2026-05-25T01:32:25.967235Z","end":"2026-05-25T01:32:26.078948Z","steps":["trace[1979422456] 'process raft request' (duration: 111.485251ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.245316Z","caller":"traceutil/trace.go:171","msg":"trace[705855773] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"157.927771ms","start":"2026-05-25T01:32:26.087365Z","end":"2026-05-25T01:32:26.245292Z","steps":["trace[705855773] 'process raft request' (duration: 157.841859ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.2454Z","caller":"traceutil/trace.go:171","msg":"trace[787258011] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"160.186194ms","start":"2026-05-25T01:32:26.085187Z","end":"2026-05-25T01:32:26.245374Z","steps":["trace[787258011] 'process raft request' (duration: 127.474282ms)","trace[787258011] 'compare' (duration: 32.225958ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:26.245483Z","caller":"traceutil/trace.go:171","msg":"trace[194633415] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"159.268328ms","start":"2026-05-25T01:32:26.086199Z","end":"2026-05-25T01:32:26.245467Z","steps":["trace[194633415] 'process raft request' (duration: 158.913989ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.43787Z","caller":"traceutil/trace.go:171","msg":"trace[1706419513] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"185.625534ms","start":"2026-05-25T01:32:26.252216Z","end":"2026-05-25T01:32:26.437841Z","steps":["trace[1706419513] 'process raft request' (duration: 128.814169ms)","trace[1706419513] 'compare' (duration: 56.699402ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:32:26.696894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.898357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:32:26.697326Z","caller":"traceutil/trace.go:171","msg":"trace[169254039] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"152.610305ms","start":"2026-05-25T01:32:26.544702Z","end":"2026-05-25T01:32:26.697312Z","steps":["trace[169254039] 'process raft request' (duration: 152.561663ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.697328Z","caller":"traceutil/trace.go:171","msg":"trace[1070085308] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"161.674424ms","start":"2026-05-25T01:32:26.535616Z","end":"2026-05-25T01:32:26.69729Z","steps":["trace[1070085308] 'process raft request' (duration: 161.429217ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.697415Z","caller":"traceutil/trace.go:171","msg":"trace[1630290998] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"151.048371ms","start":"2026-05-25T01:32:26.546355Z","end":"2026-05-25T01:32:26.697404Z","steps":["trace[1630290998] 'process raft request' (duration: 150.931548ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.697413Z","caller":"traceutil/trace.go:171","msg":"trace[697711320] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"209.304996ms","start":"2026-05-25T01:32:26.487991Z","end":"2026-05-25T01:32:26.697296Z","steps":["trace[697711320] 'process raft request' (duration: 65.917316ms)","trace[697711320] 'compare' (duration: 142.360122ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:26.697559Z","caller":"traceutil/trace.go:171","msg":"trace[1074963438] linearizableReadLoop","detail":"{readStateIndex:25536; appliedIndex:25535; }","duration":"209.44408ms","start":"2026-05-25T01:32:26.488107Z","end":"2026-05-25T01:32:26.697551Z","steps":["trace[1074963438] 'read index received' (duration: 65.880335ms)","trace[1074963438] 'applied index is now lower than readState.Index' (duration: 143.563195ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:26.697565Z","caller":"traceutil/trace.go:171","msg":"trace[25206507] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"160.262666ms","start":"2026-05-25T01:32:26.537275Z","end":"2026-05-25T01:32:26.697537Z","steps":["trace[25206507] 'process raft request' (duration: 159.874355ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.697658Z","caller":"traceutil/trace.go:171","msg":"trace[1014522690] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"159.195386ms","start":"2026-05-25T01:32:26.538455Z","end":"2026-05-25T01:32:26.697651Z","steps":["trace[1014522690] 'process raft request' (duration: 158.724203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:26.697695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.748981ms","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-25T01:32:26.697715Z","caller":"traceutil/trace.go:171","msg":"trace[1662310430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24739; }","duration":"208.787392ms","start":"2026-05-25T01:32:26.488921Z","end":"2026-05-25T01:32:26.697708Z","steps":["trace[1662310430] 'agreement among raft nodes before linearized reading' (duration: 208.702399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:26.697742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.613685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-25T01:32:26.697871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.522977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-25T01:32:26.697907Z","caller":"traceutil/trace.go:171","msg":"trace[1700638961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24739; }","duration":"195.587899ms","start":"2026-05-25T01:32:26.502313Z","end":"2026-05-25T01:32:26.697901Z","steps":["trace[1700638961] 'agreement among raft nodes before linearized reading' (duration: 195.511007ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.697889Z","caller":"traceutil/trace.go:171","msg":"trace[311890506] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"153.290644ms","start":"2026-05-25T01:32:26.544571Z","end":"2026-05-25T01:32:26.697862Z","steps":["trace[311890506] 'process raft request' (duration: 152.665607ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.697897Z","caller":"traceutil/trace.go:171","msg":"trace[1399930237] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"157.426377ms","start":"2026-05-25T01:32:26.540444Z","end":"2026-05-25T01:32:26.697871Z","steps":["trace[1399930237] 'process raft request' (duration: 156.762269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:26.697812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.277912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-25T01:32:26.69817Z","caller":"traceutil/trace.go:171","msg":"trace[1894810139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24739; }","duration":"194.611202ms","start":"2026-05-25T01:32:26.503505Z","end":"2026-05-25T01:32:26.698116Z","steps":["trace[1894810139] 'agreement among raft nodes before linearized reading' (duration: 194.268202ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:26.69843Z","caller":"traceutil/trace.go:171","msg":"trace[1961029633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24739; }","duration":"209.79351ms","start":"2026-05-25T01:32:26.488054Z","end":"2026-05-25T01:32:26.697848Z","steps":["trace[1961029633] 'agreement among raft nodes before linearized reading' (duration: 209.541513ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:36.862467Z","caller":"traceutil/trace.go:171","msg":"trace[1846246977] transaction","detail":"{read_only:false; response_revision:24803; number_of_response:1; }","duration":"128.276004ms","start":"2026-05-25T01:32:36.734169Z","end":"2026-05-25T01:32:36.862445Z","steps":["trace[1846246977] 'process raft request' (duration: 128.12536ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.087672Z","caller":"traceutil/trace.go:171","msg":"trace[11512249] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"353.063967ms","start":"2026-05-25T01:32:36.734579Z","end":"2026-05-25T01:32:37.087643Z","steps":["trace[11512249] 'process raft request' (duration: 352.80697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.087848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.734556Z","time spent":"353.204221ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:32:37.088186Z","caller":"traceutil/trace.go:171","msg":"trace[1727173711] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"347.103933ms","start":"2026-05-25T01:32:36.741056Z","end":"2026-05-25T01:32:37.08816Z","steps":["trace[1727173711] 'process raft request' (duration: 346.527087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.088293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.741008Z","time spent":"347.234946ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:32:37.088408Z","caller":"traceutil/trace.go:171","msg":"trace[1914649020] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"343.140083ms","start":"2026-05-25T01:32:36.74525Z","end":"2026-05-25T01:32:37.08839Z","steps":["trace[1914649020] 'process raft request' (duration: 342.917187ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.088231Z","caller":"traceutil/trace.go:171","msg":"trace[1221914021] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"343.882813ms","start":"2026-05-25T01:32:36.744325Z","end":"2026-05-25T01:32:37.088207Z","steps":["trace[1221914021] 'process raft request' (duration: 343.74832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.088519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.745236Z","time spent":"343.232035ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:32:37.088586Z","caller":"traceutil/trace.go:171","msg":"trace[855742342] linearizableReadLoop","detail":"{readStateIndex:25615; appliedIndex:25608; }","duration":"337.737434ms","start":"2026-05-25T01:32:36.750831Z","end":"2026-05-25T01:32:37.088568Z","steps":["trace[855742342] 'read index received' (duration: 111.518592ms)","trace[855742342] 'applied index is now lower than readState.Index' (duration: 226.217752ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:37.088721Z","caller":"traceutil/trace.go:171","msg":"trace[628397649] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"341.728814ms","start":"2026-05-25T01:32:36.746975Z","end":"2026-05-25T01:32:37.088703Z","steps":["trace[628397649] 'process raft request' (duration: 341.499488ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.088697Z","caller":"traceutil/trace.go:171","msg":"trace[2099743163] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"342.806244ms","start":"2026-05-25T01:32:36.745854Z","end":"2026-05-25T01:32:37.08866Z","steps":["trace[2099743163] 'process raft request' (duration: 342.466254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.088763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.922659ms","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-25T01:32:37.088815Z","caller":"traceutil/trace.go:171","msg":"trace[1223492816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24809; }","duration":"337.999352ms","start":"2026-05-25T01:32:36.750802Z","end":"2026-05-25T01:32:37.088801Z","steps":["trace[1223492816] 'agreement among raft nodes before linearized reading' (duration: 337.817127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.088826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.74696Z","time spent":"341.799566ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:32:37.088865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.750791Z","time spent":"338.060923ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T01:32:37.088862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.74584Z","time spent":"342.935368ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:32:37.088978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.744313Z","time spent":"344.168932ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T01:32:37.274707Z","caller":"traceutil/trace.go:171","msg":"trace[544236413] linearizableReadLoop","detail":"{readStateIndex:25616; appliedIndex:25615; }","duration":"185.928143ms","start":"2026-05-25T01:32:37.088755Z","end":"2026-05-25T01:32:37.274683Z","steps":["trace[544236413] 'read index received' (duration: 185.512292ms)","trace[544236413] 'applied index is now lower than readState.Index' (duration: 414.881µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:32:37.274923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.603394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-25T01:32:37.27503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.830871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-25T01:32:37.275053Z","caller":"traceutil/trace.go:171","msg":"trace[1069966585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24810; }","duration":"450.766099ms","start":"2026-05-25T01:32:36.824272Z","end":"2026-05-25T01:32:37.275038Z","steps":["trace[1069966585] 'agreement among raft nodes before linearized reading' (duration: 450.45754ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.274921Z","caller":"traceutil/trace.go:171","msg":"trace[1015837431] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"309.133747ms","start":"2026-05-25T01:32:36.96576Z","end":"2026-05-25T01:32:37.274893Z","steps":["trace[1015837431] 'process raft request' (duration: 308.433668ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.275093Z","caller":"traceutil/trace.go:171","msg":"trace[1368337751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24810; }","duration":"454.936174ms","start":"2026-05-25T01:32:36.820139Z","end":"2026-05-25T01:32:37.275075Z","steps":["trace[1368337751] 'agreement among raft nodes before linearized reading' (duration: 454.727408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.275098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.175486ms","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-25T01:32:37.275113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.824261Z","time spent":"450.82729ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T01:32:37.275126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.82012Z","time spent":"455.000395ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-25T01:32:37.27514Z","caller":"traceutil/trace.go:171","msg":"trace[395756962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24810; }","duration":"461.274078ms","start":"2026-05-25T01:32:36.813856Z","end":"2026-05-25T01:32:37.27513Z","steps":["trace[395756962] 'agreement among raft nodes before linearized reading' (duration: 461.126404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.275166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.965731Z","time spent":"309.386334ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T01:32:37.27518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.813838Z","time spent":"461.33477ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T01:32:37.275327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.547756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-25T01:32:37.275451Z","caller":"traceutil/trace.go:171","msg":"trace[434659261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24810; }","duration":"453.681709ms","start":"2026-05-25T01:32:36.82176Z","end":"2026-05-25T01:32:37.275442Z","steps":["trace[434659261] 'agreement among raft nodes before linearized reading' (duration: 453.029291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.275482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T01:32:36.821754Z","time spent":"453.72002ms","remote":"127.0.0.1:35442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-25T01:32:37.327162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.853515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:32:37.327292Z","caller":"traceutil/trace.go:171","msg":"trace[546333349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24812; }","duration":"236.968578ms","start":"2026-05-25T01:32:37.090251Z","end":"2026-05-25T01:32:37.32722Z","steps":["trace[546333349] 'agreement among raft nodes before linearized reading' (duration: 236.832294ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.327773Z","caller":"traceutil/trace.go:171","msg":"trace[432313762] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"233.653097ms","start":"2026-05-25T01:32:37.094099Z","end":"2026-05-25T01:32:37.327752Z","steps":["trace[432313762] 'process raft request' (duration: 232.837334ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.328074Z","caller":"traceutil/trace.go:171","msg":"trace[206131417] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"201.698966ms","start":"2026-05-25T01:32:37.126352Z","end":"2026-05-25T01:32:37.328051Z","steps":["trace[206131417] 'process raft request' (duration: 200.687238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.328082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.046605ms","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-25T01:32:37.328146Z","caller":"traceutil/trace.go:171","msg":"trace[417913991] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24812; }","duration":"190.140868ms","start":"2026-05-25T01:32:37.137988Z","end":"2026-05-25T01:32:37.328128Z","steps":["trace[417913991] 'agreement among raft nodes before linearized reading' (duration: 190.020415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T01:32:37.548584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.386638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-25T01:32:37.548909Z","caller":"traceutil/trace.go:171","msg":"trace[1652113844] linearizableReadLoop","detail":"{readStateIndex:25622; appliedIndex:25618; }","duration":"219.900588ms","start":"2026-05-25T01:32:37.328987Z","end":"2026-05-25T01:32:37.548888Z","steps":["trace[1652113844] 'read index received' (duration: 63.068288ms)","trace[1652113844] 'applied index is now lower than readState.Index' (duration: 156.83119ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T01:32:37.549047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.049612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T01:32:37.549097Z","caller":"traceutil/trace.go:171","msg":"trace[382113379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24816; }","duration":"220.118255ms","start":"2026-05-25T01:32:37.328965Z","end":"2026-05-25T01:32:37.549083Z","steps":["trace[382113379] 'agreement among raft nodes before linearized reading' (duration: 219.999451ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.549343Z","caller":"traceutil/trace.go:171","msg":"trace[1947737509] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"269.620598ms","start":"2026-05-25T01:32:37.279698Z","end":"2026-05-25T01:32:37.549319Z","steps":["trace[1947737509] 'process raft request' (duration: 269.006831ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.549356Z","caller":"traceutil/trace.go:171","msg":"trace[1641537938] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"269.526535ms","start":"2026-05-25T01:32:37.2798Z","end":"2026-05-25T01:32:37.549326Z","steps":["trace[1641537938] 'process raft request' (duration: 268.97844ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.549392Z","caller":"traceutil/trace.go:171","msg":"trace[687901641] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"269.811613ms","start":"2026-05-25T01:32:37.279553Z","end":"2026-05-25T01:32:37.549364Z","steps":["trace[687901641] 'process raft request' (duration: 112.552051ms)","trace[687901641] 'compare' (duration: 156.254325ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:32:37.549346Z","caller":"traceutil/trace.go:171","msg":"trace[1651608678] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"268.384954ms","start":"2026-05-25T01:32:37.280935Z","end":"2026-05-25T01:32:37.54932Z","steps":["trace[1651608678] 'process raft request' (duration: 267.894421ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T01:32:37.764953Z","caller":"traceutil/trace.go:171","msg":"trace[1829845459] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"189.171912ms","start":"2026-05-25T01:32:37.575751Z","end":"2026-05-25T01:32:37.764923Z","steps":["trace[1829845459] 'process raft request' (duration: 128.592813ms)","trace[1829845459] 'compare' (duration: 60.441705ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T01:34:37.882081Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068c9.wal"} {"level":"info","ts":"2026-05-25T01:35:32.560607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23621} {"level":"info","ts":"2026-05-25T01:35:32.69686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23621,"took":"133.002913ms","hash":1191046867,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":37933056,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-25T01:35:32.69691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1191046867,"revision":23621,"compact-revision":20437} {"level":"info","ts":"2026-05-25T01:40:32.566427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26616} {"level":"info","ts":"2026-05-25T01:40:32.711195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26616,"took":"138.329921ms","hash":397269279,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":32407552,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T01:40:32.711261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":397269279,"revision":26616,"compact-revision":23621} {"level":"info","ts":"2026-05-25T01:40:43.010249Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1686e9b1ea717edf","local-member-applied-index":30004,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T01:40:43.014983Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-05-25T01:40:43.015108Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-05-25T01:45:32.573133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29004} {"level":"info","ts":"2026-05-25T01:45:32.705239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29004,"took":"126.866085ms","hash":3896306805,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":29970432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T01:45:32.705313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3896306805,"revision":29004,"compact-revision":26616} {"level":"info","ts":"2026-05-25T01:50:32.586359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31229} {"level":"info","ts":"2026-05-25T01:50:32.702223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31229,"took":"112.670864ms","hash":807936216,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":31944704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T01:50:32.702281Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":807936216,"revision":31229,"compact-revision":29004} {"level":"info","ts":"2026-05-25T01:55:32.593121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33557} {"level":"info","ts":"2026-05-25T01:55:32.758021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33557,"took":"161.232575ms","hash":2054994379,"current-db-size-bytes":60342272,"current-db-size":"60 MB","current-db-size-in-use-bytes":32583680,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-25T01:55:32.75809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2054994379,"revision":33557,"compact-revision":31229} {"level":"info","ts":"2026-05-25T02:00:32.600332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35798} {"level":"info","ts":"2026-05-25T02:00:32.746364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35798,"took":"138.776546ms","hash":1691778580,"current-db-size-bytes":61648896,"current-db-size":"62 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-25T02:00:32.746456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1691778580,"revision":35798,"compact-revision":33557} {"level":"info","ts":"2026-05-25T02:02:03.213561Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1686e9b1ea717edf","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T02:02:03.21851Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-05-25T02:02:03.218645Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005}